=== 2012-08-13 21:14:35,657 [l0-4] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        user_name:"esmith"
    },
    operationConfig:{
        dataSource:"queryDashboardMySQL",
        operationType:"fetch",
        textMatchStyle:"exact"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_ListGrid_1",
    appID:"builtinApplication",
    operation:"queryDashboard",
    oldValues:{
        user_name:"esmith"
    }
}
=== 2012-08-13 21:14:35,658 [l0-6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        operator:"equals",
        fieldName:"user_name",
        value:"esmith",
        _constructor:"AdvancedCriteria"
    },
    operationConfig:{
        dataSource:"user_info",
        operationType:"fetch"
    },
    appID:"builtinApplication",
    operation:"user_info_fetch",
    oldValues:{
        operator:"equals",
        fieldName:"user_name",
        value:"esmith",
        _constructor:"AdvancedCriteria"
    }
}
=== 2012-08-13 21:14:35,659 [l0-0] DEBUG RPCManager - Processing 1 requests.
=== 2012-08-13 21:14:35,660 [l0-0] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
        operator:"equals",
        fieldName:"user_name",
        value:"esmith",
        _constructor:"AdvancedCriteria"
    },
    operationConfig:{
        dataSource:"user_roles",
        operationType:"fetch",
        textMatchStyle:"substring"
    },
    appID:"builtinApplication",
    operation:"user_roles_fetch",
    oldValues:{
        operator:"equals",
        fieldName:"user_name",
        value:"esmith",
        _constructor:"AdvancedCriteria"
    }
}
=== 2012-08-13 21:14:35,661 [l0-5] INFO  IDACall - Performing 1 operation(s)
=== 2012-08-13 21:14:35,661 [l0-2] INFO  IDACall - Performing 1 operation(s)
=== 2012-08-13 21:14:35,661 [l0-0] INFO  IDACall - Performing 1 operation(s)
=== 2012-08-13 21:14:35,661 [l0-4] INFO  IDACall - Performing 1 operation(s)
=== 2012-08-13 21:14:35,661 [l0-6] INFO  IDACall - Performing 1 operation(s)
=== 2012-08-13 21:14:35,663 [l0-4] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2012-08-13 21:14:35,663 [l0-0] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2012-08-13 21:14:35,663 [l0-4] DEBUG DeclarativeSecurity - DataSource queryDashboardMySQL is not in the pre-checked list, processing...
=== 2012-08-13 21:14:35,663 [l0-6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2012-08-13 21:14:35,663 [l0-5] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2012-08-13 21:14:35,663 [l0-2] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
=== 2012-08-13 21:14:35,663 [l0-5] DEBUG DeclarativeSecurity - DataSource user_info is not in the pre-checked list, processing...
=== 2012-08-13 21:14:35,663 [l0-6] DEBUG DeclarativeSecurity - DataSource user_info is not in the pre-checked list, processing...
=== 2012-08-13 21:14:35,663 [l0-0] DEBUG DeclarativeSecurity - DataSource user_roles is not in the pre-checked list, processing...
=== 2012-08-13 21:14:35,663 [l0-2] DEBUG DeclarativeSecurity - DataSource users is not in the pre-checked list, processing...
=== 2012-08-13 21:14:35,670 [l0-4] WARN  DataSourceDMI - DataSource queryDashboardMySQL: received a request to execute an operation of type 'fetch' named 'queryDashboard', but this operation is not defined to the dataSource.  Falling back to default behavior for operationType 'fetch'
=== 2012-08-13 21:14:35,672 [l0-4] DEBUG AppBase - [builtinApplication.queryDashboard] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-08-13 21:14:35,672 [l0-0] DEBUG AppBase - [builtinApplication.user_roles_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-08-13 21:14:35,672 [l0-6] DEBUG AppBase - [builtinApplication.user_info_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-08-13 21:14:35,672 [l0-5] DEBUG AppBase - [builtinApplication.user_info_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-08-13 21:14:35,672 [l0-2] DEBUG AppBase - [builtinApplication.users_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-08-13 21:14:35,672 [l0-0] DEBUG AppBase - [builtinApplication.user_roles_fetch] No public zero-argument method named '_user_roles_fetch' found, performing generic datasource operation
=== 2012-08-13 21:14:35,672 [l0-4] DEBUG AppBase - [builtinApplication.queryDashboard] No public zero-argument method named '_queryDashboard' found, performing generic datasource operation
=== 2012-08-13 21:14:35,672 [l0-5] DEBUG AppBase - [builtinApplication.user_info_fetch] No public zero-argument method named '_user_info_fetch' found, performing generic datasource operation
=== 2012-08-13 21:14:35,672 [l0-2] DEBUG AppBase - [builtinApplication.users_fetch] No public zero-argument method named '_users_fetch' found, performing generic datasource operation
=== 2012-08-13 21:14:35,672 [l0-6] DEBUG AppBase - [builtinApplication.user_info_fetch] No public zero-argument method named '_user_info_fetch' found, performing generic datasource operation
=== 2012-08-13 21:14:35,673 [l0-4] INFO  SQLDataSource - [builtinApplication.queryDashboard] Performing fetch operation with
	criteria: {user_name:"esmith"}	values: {user_name:"esmith"}
=== 2012-08-13 21:14:35,673 [l0-6] INFO  SQLDataSource - [builtinApplication.user_info_fetch] Performing fetch operation with
	criteria: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}	values: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}
=== 2012-08-13 21:14:35,673 [l0-5] INFO  SQLDataSource - [builtinApplication.user_info_fetch] Performing fetch operation with
	criteria: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}	values: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}
=== 2012-08-13 21:14:35,673 [l0-0] INFO  SQLDataSource - [builtinApplication.user_roles_fetch] Performing fetch operation with
	criteria: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}	values: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}
=== 2012-08-13 21:14:35,673 [l0-2] INFO  SQLDataSource - [builtinApplication.users_fetch] Performing fetch operation with
	criteria: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}	values: {operator:"equals",fieldName:"user_name",value:"esmith",_constructor:"AdvancedCriteria"}
=== 2012-08-13 21:14:35,675 [l0-4] WARN  SQLSelectClause - [builtinApplication.queryDashboard] SQLSelectClause is null or zero size, remap and conversions won't work!
=== 2012-08-13 21:14:35,680 [l0-4] INFO  SQLDataSource - [builtinApplication.queryDashboard] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2012-08-13 21:14:35,680 [l0-0] INFO  SQLDataSource - [builtinApplication.user_roles_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2012-08-13 21:14:35,680 [l0-6] INFO  SQLDataSource - [builtinApplication.user_info_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2012-08-13 21:14:35,680 [l0-5] INFO  SQLDataSource - [builtinApplication.user_info_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2012-08-13 21:14:35,680 [l0-2] INFO  SQLDataSource - [builtinApplication.users_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTableClause WHERE $defaultWhereClause
=== 2012-08-13 21:14:35,759 [l0-2] INFO  SQLDataSource - [builtinApplication.users_fetch] Executing SQL query on 'Mysql': SELECT users.user_name, users.user_pass FROM relayBDLive.users WHERE (users.user_name = 'esmith' AND users.user_name IS NOT NULL)
=== 2012-08-13 21:14:35,759 [l0-4] DEBUG SQLDataSource - [builtinApplication.queryDashboard] Executing row count query: SELECT COUNT(*) FROM $defaultTableClause WHERE $defaultWhereClause
=== 2012-08-13 21:14:35,759 [l0-5] INFO  SQLDataSource - [builtinApplication.user_info_fetch] Executing SQL query on 'Mysql': SELECT user_info.company_name, user_info.company_website, user_info.department, user_info.email_home, user_info.email_work, user_info.name_first, user_info.name_last, user_info.name_nickname, user_info.phone_mobile, user_info.phone_work, user_info.user_name FROM relayBDLive.user_info WHERE (user_info.user_name = 'esmith' AND user_info.user_name IS NOT NULL)
=== 2012-08-13 21:14:35,759 [l0-6] INFO  SQLDataSource - [builtinApplication.user_info_fetch] Executing SQL query on 'Mysql': SELECT user_info.company_name, user_info.company_website, user_info.department, user_info.email_home, user_info.email_work, user_info.name_first, user_info.name_last, user_info.name_nickname, user_info.phone_mobile, user_info.phone_work, user_info.user_name FROM relayBDLive.user_info WHERE (user_info.user_name = 'esmith' AND user_info.user_name IS NOT NULL)
=== 2012-08-13 21:14:35,760 [l0-0] INFO  SQLDataSource - [builtinApplication.user_roles_fetch] Executing SQL query on 'Mysql': SELECT user_roles.role_name, user_roles.user_name FROM relayBDLive.user_roles WHERE (user_roles.user_name = 'esmith' AND user_roles.user_name IS NOT NULL)
=== 2012-08-13 21:14:35,760 [l0-4] DEBUG SQLDataSource - [builtinApplication.queryDashboard] Eval'd row count query: SELECT COUNT(*) FROM relayBDLive.dashboard_info WHERE ('1'='1')
=== 2012-08-13 21:14:35,835 [l0-2] INFO  PoolManager - [builtinApplication.users_fetch] SmartClient pooling started for 'Mysql' objects
=== 2012-08-13 21:14:35,835 [l0-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.users_fetch] Initializing SQL config for 'Mysql' from system config - using DataSource:  com.mysql.jdbc.jdbc2.optional.MysqlDataSource
=== 2012-08-13 21:14:35,867 [l0-2] INFO  DataTools - [builtinApplication.users_fetch] setProperties: couldn't set:
{
    driverType:"No such property",
    context:"No such property",
    networkProtocol:"No such property"
}
=== 2012-08-13 21:14:35,988 [l0-2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.users_fetch] Returning pooled Connection '731054942'
=== 2012-08-13 21:14:35,989 [l0-5] DEBUG PoolableSQLConnectionFactory - [builtinApplication.user_info_fetch] Returning pooled Connection '1816456773'
=== 2012-08-13 21:14:35,993 [l0-0] DEBUG PoolableSQLConnectionFactory - [builtinApplication.user_roles_fetch] Returning pooled Connection '1810469378'
=== 2012-08-13 21:14:35,994 [l0-2] DEBUG SQLConnectionManager - [builtinApplication.users_fetch] Returning borrowed connection '731054942'
=== 2012-08-13 21:14:35,994 [l0-2] DEBUG SQLDriver - [builtinApplication.users_fetch] About to execute SQL query in 'Mysql' using connection '731054942'
=== 2012-08-13 21:14:35,994 [l0-2] INFO  SQLDriver - [builtinApplication.users_fetch] Executing SQL query on 'Mysql': SELECT users.user_name, users.user_pass FROM relayBDLive.users WHERE (users.user_name = 'esmith' AND users.user_name IS NOT NULL)
=== 2012-08-13 21:14:35,996 [l0-0] DEBUG SQLConnectionManager - [builtinApplication.user_roles_fetch] Returning borrowed connection '1810469378'
=== 2012-08-13 21:14:35,997 [l0-0] DEBUG SQLDriver - [builtinApplication.user_roles_fetch] About to execute SQL query in 'Mysql' using connection '1810469378'
=== 2012-08-13 21:14:35,997 [l0-5] DEBUG SQLConnectionManager - [builtinApplication.user_info_fetch] Returning borrowed connection '1816456773'
=== 2012-08-13 21:14:35,997 [l0-0] INFO  SQLDriver - [builtinApplication.user_roles_fetch] Executing SQL query on 'Mysql': SELECT user_roles.role_name, user_roles.user_name FROM relayBDLive.user_roles WHERE (user_roles.user_name = 'esmith' AND user_roles.user_name IS NOT NULL)
=== 2012-08-13 21:14:36,001 [l0-2] INFO  DSResponse - [builtinApplication.users_fetch] DSResponse: List with 1 items
=== 2012-08-13 21:14:36,001 [l0-2] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "731054942"
=== 2012-08-13 21:14:35,997 [l0-5] DEBUG SQLDriver - [builtinApplication.user_info_fetch] About to execute SQL query in 'Mysql' using connection '1816456773'
=== 2012-08-13 21:14:36,001 [l0-5] INFO  SQLDriver - [builtinApplication.user_info_fetch] Executing SQL query on 'Mysql': SELECT user_info.company_name, user_info.company_website, user_info.department, user_info.email_home, user_info.email_work, user_info.name_first, user_info.name_last, user_info.name_nickname, user_info.phone_mobile, user_info.phone_work, user_info.user_name FROM relayBDLive.user_info WHERE (user_info.user_name = 'esmith' AND user_info.user_name IS NOT NULL)
=== 2012-08-13 21:14:36,002 [l0-2] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-08-13 21:14:36,004 [l0-5] INFO  DSResponse - [builtinApplication.user_info_fetch] DSResponse: List with 1 items
=== 2012-08-13 21:14:36,004 [l0-2] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-08-13 21:14:36,004 [l0-5] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1816456773"
=== 2012-08-13 21:14:36,005 [l0-5] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-08-13 21:14:36,006 [l0-5] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-08-13 21:14:36,007 [l0-2] INFO  Compression - /winter/sc/IDACall: 210 -> 172 bytes
=== 2012-08-13 21:14:36,002 [l0-0] INFO  DSResponse - [builtinApplication.user_roles_fetch] DSResponse: List with 2 items
=== 2012-08-13 21:14:36,007 [l0-0] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1810469378"
=== 2012-08-13 21:14:36,007 [l0-5] INFO  Compression - /winter/sc/IDACall: 229 -> 181 bytes
=== 2012-08-13 21:14:36,008 [l0-0] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-08-13 21:14:36,008 [l0-0] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-08-13 21:14:36,010 [l0-0] INFO  Compression - /winter/sc/IDACall: 250 -> 184 bytes
=== 2012-08-13 21:14:36,010 [l0-6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.user_info_fetch] Returning pooled Connection '438234379'
=== 2012-08-13 21:14:36,011 [l0-6] DEBUG SQLConnectionManager - [builtinApplication.user_info_fetch] Returning borrowed connection '438234379'
=== 2012-08-13 21:14:36,011 [l0-6] DEBUG SQLDriver - [builtinApplication.user_info_fetch] About to execute SQL query in 'Mysql' using connection '438234379'
=== 2012-08-13 21:14:36,011 [l0-6] INFO  SQLDriver - [builtinApplication.user_info_fetch] Executing SQL query on 'Mysql': SELECT user_info.company_name, user_info.company_website, user_info.department, user_info.email_home, user_info.email_work, user_info.name_first, user_info.name_last, user_info.name_nickname, user_info.phone_mobile, user_info.phone_work, user_info.user_name FROM relayBDLive.user_info WHERE (user_info.user_name = 'esmith' AND user_info.user_name IS NOT NULL)
=== 2012-08-13 21:14:36,012 [l0-6] INFO  DSResponse - [builtinApplication.user_info_fetch] DSResponse: List with 1 items
=== 2012-08-13 21:14:36,012 [l0-6] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "438234379"
=== 2012-08-13 21:14:36,013 [l0-6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-08-13 21:14:36,014 [l0-6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-08-13 21:14:36,015 [l0-6] INFO  Compression - /winter/sc/IDACall: 229 -> 181 bytes
=== 2012-08-13 21:14:36,015 [l0-4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.queryDashboard] Returning pooled Connection '1175366682'
=== 2012-08-13 21:14:36,016 [l0-4] DEBUG SQLConnectionManager - [builtinApplication.queryDashboard] Returning borrowed connection '1175366682'
=== 2012-08-13 21:14:36,016 [l0-4] DEBUG SQLDriver - [builtinApplication.queryDashboard] About to execute SQL query in 'Mysql' using connection '1175366682'
=== 2012-08-13 21:14:36,016 [l0-4] INFO  SQLDriver - [builtinApplication.queryDashboard] Executing SQL query on 'Mysql': SELECT COUNT(*) FROM relayBDLive.dashboard_info WHERE ('1'='1')
=== 2012-08-13 21:14:36,017 [l0-4] DEBUG SQLDataSource - [builtinApplication.queryDashboard] Using SQL Limit query
=== 2012-08-13 21:14:36,017 [l0-4] DEBUG SQLDataSource - [builtinApplication.queryDashboard] SQL windowed select rows 0->75, result size 75. Query: SELECT * FROM relayBDLive.dashboard_info WHERE ('1'='1') LIMIT 0, 75
=== 2012-08-13 21:14:36,018 [l0-4] DEBUG SQLConnectionManager - [builtinApplication.queryDashboard] Returning borrowed connection '438234379'
=== 2012-08-13 21:14:36,020 [l0-4] INFO  DSResponse - [builtinApplication.queryDashboard] DSResponse: List with 10 items
=== 2012-08-13 21:14:36,020 [l0-4] DEBUG SQLConnectionManager - [builtinApplication.queryDashboard] About to close PoolableConnection with hashcode "438234379"
=== 2012-08-13 21:14:36,021 [l0-4] DEBUG SQLConnectionManager - About to close PoolableConnection with hashcode "1175366682"
=== 2012-08-13 21:14:36,022 [l0-4] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-08-13 21:14:36,023 [l0-4] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-08-13 21:14:36,024 [l0-4] INFO  Compression - /winter/sc/IDACall: 2095 -> 644 bytes
Update tabs from DS response