=== 2012-07-05 11:40:15,750 [sor7] INFO  IDACall - Performing 1 operation(s)
=== 2012-07-05 11:40:15,718 [sor3] INFO  SQLDataSource - [builtinApplication.Project_fetch] Performing fetch operation with
	criteria: {PROJECTMANAGERID:101}	values: {PROJECTMANAGERID:101}
=== 2012-07-05 11:40:15,781 [sor3] INFO  SQLDataSource - [builtinApplication.Project_fetch] derived query: SELECT $defaultSelectClause FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE $defaultWhereClause
=== 2012-07-05 11:40:15,750 [sor2] INFO  SQLDataSource - [builtinApplication.Issue_fetch] Performing fetch operation with
	criteria: {OWNERID:101}	values: {OWNERID:101}
=== 2012-07-05 11:40:15,781 [sor7] DEBUG AppBase - [builtinApplication.Task_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-07-05 11:40:15,812 [sor7] DEBUG AppBase - [builtinApplication.Task_fetch] No public zero-argument method named '_Task_fetch' found, performing generic datasource operation
=== 2012-07-05 11:40:15,812 [sor7] INFO  SQLDataSource - [builtinApplication.Task_fetch] Performing fetch operation with
	criteria: {OWNERID:101}	values: {OWNERID:101}
=== 2012-07-05 11:40:15,812 [sor7] INFO  SQLDataSource - [builtinApplication.Task_fetch] derived query: SELECT $defaultSelectClause FROM Project, Issue, Task LEFT JOIN TeamMember ON Task.OwnerID = TeamMember.TeamMemberID LEFT JOIN TeamMember IssueMember ON Issue.OwnerID = IssueMember.TeamMemberID WHERE Task.IssueID = Issue.IssueID AND Task.ProjectID = Project.ProjectID  AND ($defaultWhereClause)
=== 2012-07-05 11:40:15,812 [sor3] DEBUG SQLDataSource - [builtinApplication.Project_fetch] Executing row count query: SELECT COUNT(*) FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE $defaultWhereClause
=== 2012-07-05 11:40:15,843 [sor3] DEBUG SQLDataSource - [builtinApplication.Project_fetch] Eval'd row count query: SELECT COUNT(*) FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE (PROJECT.PROJECTMANAGERID='101')
=== 2012-07-05 11:40:15,843 [sor3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Project_fetch] DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\Planet Project/WEB-INF/db/hsqldb/PlanetProjectDB
=== 2012-07-05 11:40:15,843 [sor3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Project_fetch] Passing JDBC URL only to getConnection
=== 2012-07-05 11:40:15,843 [sor3] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Project_fetch] Returning pooled Connection
=== 2012-07-05 11:40:15,843 [sor3] DEBUG SQLTransaction - [builtinApplication.Project_fetch] Started new PlanetProjectDB transaction "11398356"
=== 2012-07-05 11:40:15,843 [sor3] INFO  SQLDriver - [builtinApplication.Project_fetch] Executing SQL query on 'PlanetProjectDB': SELECT COUNT(*) FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE (PROJECT.PROJECTMANAGERID='101')
=== 2012-07-05 11:40:15,843 [sor7] DEBUG SQLDataSource - [builtinApplication.Task_fetch] Executing row count query: SELECT COUNT(*) FROM Project, Issue, Task LEFT JOIN TeamMember ON Task.OwnerID = TeamMember.TeamMemberID LEFT JOIN TeamMember IssueMember ON Issue.OwnerID = IssueMember.TeamMemberID WHERE Task.IssueID = Issue.IssueID AND Task.ProjectID = Project.ProjectID  AND ($defaultWhereClause)
=== 2012-07-05 11:40:15,843 [sor7] DEBUG SQLDataSource - [builtinApplication.Task_fetch] Eval'd row count query: SELECT COUNT(*) FROM Project, Issue, Task LEFT JOIN TeamMember ON Task.OwnerID = TeamMember.TeamMemberID LEFT JOIN TeamMember IssueMember ON Issue.OwnerID = IssueMember.TeamMemberID WHERE Task.IssueID = Issue.IssueID AND Task.ProjectID = Project.ProjectID  AND ((Task.OWNERID='101'))
=== 2012-07-05 11:40:15,843 [sor7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Task_fetch] DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\Planet Project/WEB-INF/db/hsqldb/PlanetProjectDB
=== 2012-07-05 11:40:15,843 [sor7] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Task_fetch] Passing JDBC URL only to getConnection
=== 2012-07-05 11:40:15,812 [sor2] INFO  SQLDataSource - [builtinApplication.Issue_fetch] derived query: SELECT $defaultSelectClause FROM Project, IssueImpact, IssueLikelihood, IssueType, Issue LEFT JOIN Category ON Issue.CategoryID = Category.CategoryID LEFT JOIN TeamMember OwnerMember ON Issue.OwnerID = OwnerMember.TeamMemberID LEFT JOIN TeamMember OrigMember ON Issue.OwnerID = OrigMember.TeamMemberID  WHERE Issue.ProjectID = Project.ProjectID AND Issue.ImpactID = IssueImpact.ImpactID AND Issue.LikelihoodID = IssueLikelihood.LikelihoodID AND Issue.IssueTypeID = IssueType.IssueTypeID AND ($defaultWhereClause)
=== 2012-07-05 11:40:15,875 [sor2] DEBUG SQLDataSource - [builtinApplication.Issue_fetch] Executing row count query: SELECT COUNT(*) FROM Project, IssueImpact, IssueLikelihood, IssueType, Issue LEFT JOIN Category ON Issue.CategoryID = Category.CategoryID LEFT JOIN TeamMember OwnerMember ON Issue.OwnerID = OwnerMember.TeamMemberID LEFT JOIN TeamMember OrigMember ON Issue.OwnerID = OrigMember.TeamMemberID  WHERE Issue.ProjectID = Project.ProjectID AND Issue.ImpactID = IssueImpact.ImpactID AND Issue.LikelihoodID = IssueLikelihood.LikelihoodID AND Issue.IssueTypeID = IssueType.IssueTypeID AND ($defaultWhereClause)
=== 2012-07-05 11:40:15,875 [sor2] DEBUG SQLDataSource - [builtinApplication.Issue_fetch] Eval'd row count query: SELECT COUNT(*) FROM Project, IssueImpact, IssueLikelihood, IssueType, Issue LEFT JOIN Category ON Issue.CategoryID = Category.CategoryID LEFT JOIN TeamMember OwnerMember ON Issue.OwnerID = OwnerMember.TeamMemberID LEFT JOIN TeamMember OrigMember ON Issue.OwnerID = OrigMember.TeamMemberID  WHERE Issue.ProjectID = Project.ProjectID AND Issue.ImpactID = IssueImpact.ImpactID AND Issue.LikelihoodID = IssueLikelihood.LikelihoodID AND Issue.IssueTypeID = IssueType.IssueTypeID AND ((Issue.OWNERID='101'))
=== 2012-07-05 11:40:15,875 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Issue_fetch] DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\Planet Project/WEB-INF/db/hsqldb/PlanetProjectDB
=== 2012-07-05 11:40:15,875 [sor2] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Issue_fetch] Passing JDBC URL only to getConnection
=== 2012-07-05 11:40:15,984 [sor3] INFO  DSResponse - [builtinApplication.Project_fetch] DSResponse: List with 4 items
=== 2012-07-05 11:40:15,984 [sor3] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-07-05 11:40:16,000 [sor2] INFO  DSResponse - [builtinApplication.Issue_fetch] DSResponse: List with 7 items
=== 2012-07-05 11:40:15,984 [sor7] INFO  DSResponse - [builtinApplication.Task_fetch] DSResponse: List with 4 items
=== 2012-07-05 11:40:16,000 [sor3] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "11398356"
=== 2012-07-05 11:40:16,000 [sor7] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-07-05 11:40:16,000 [sor7] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "15191197"
=== 2012-07-05 11:40:16,000 [sor2] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-07-05 11:40:16,000 [sor2] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "31130923"
=== 2012-07-05 11:40:16,000 [sor3] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-07-05 11:40:16,000 [sor3] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "11398356"
=== 2012-07-05 11:40:16,000 [sor3] INFO  Compression - /isomorphic/IDACall: 2854 -> 835 bytes
=== 2012-07-05 11:40:16,015 [sor7] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-07-05 11:40:16,015 [sor7] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "15191197"
=== 2012-07-05 11:40:16,015 [sor7] INFO  Compression - /isomorphic/IDACall: 2003 -> 550 bytes
=== 2012-07-05 11:40:16,015 [sor2] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-07-05 11:40:16,046 [sor2] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "31130923"
=== 2012-07-05 11:40:16,078 [sor2] INFO  Compression - /isomorphic/IDACall: 4649 -> 1006 bytes
=== 2012-07-05 11:40:24,609 [sor8] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:24,609 [sor8] DEBUG XML - Parsed XML from (in memory stream): 0ms
=== 2012-07-05 11:40:24,859 [sor8] DEBUG RPCManager - Processing 1 requests.
=== 2012-07-05 11:40:25,046 [sor8] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"Project",
        operationType:"fetch",
        textMatchStyle:"startsWith"
    },
    startRow:0,
    endRow:75,
    componentId:"isc_PickListMenu_2",
    appID:"builtinApplication",
    operation:"Project_fetch",
    oldValues:{
    }
}
=== 2012-07-05 11:40:25,046 [sor8] INFO  IDACall - Performing 1 operation(s)
=== 2012-07-05 11:40:25,046 [sor8] DEBUG AppBase - [builtinApplication.Project_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-07-05 11:40:25,046 [sor8] DEBUG AppBase - [builtinApplication.Project_fetch] No public zero-argument method named '_Project_fetch' found, performing generic datasource operation
=== 2012-07-05 11:40:25,046 [sor8] INFO  SQLDataSource - [builtinApplication.Project_fetch] Performing fetch operation with
	criteria: {}	values: {}
=== 2012-07-05 11:40:25,078 [sor8] INFO  SQLWhereClause - [builtinApplication.Project_fetch] empty condition
=== 2012-07-05 11:40:25,078 [sor8] INFO  SQLDataSource - [builtinApplication.Project_fetch] derived query: SELECT $defaultSelectClause FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE $defaultWhereClause
=== 2012-07-05 11:40:25,078 [sor8] DEBUG SQLDataSource - [builtinApplication.Project_fetch] Executing row count query: SELECT COUNT(*) FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE $defaultWhereClause
=== 2012-07-05 11:40:25,078 [sor8] DEBUG SQLDataSource - [builtinApplication.Project_fetch] Eval'd row count query: SELECT COUNT(*) FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE ('1'='1')
=== 2012-07-05 11:40:25,078 [sor8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Project_fetch] DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\Planet Project/WEB-INF/db/hsqldb/PlanetProjectDB
=== 2012-07-05 11:40:25,078 [sor8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Project_fetch] Passing JDBC URL only to getConnection
=== 2012-07-05 11:40:25,187 [sor8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Project_fetch] Returning pooled Connection
=== 2012-07-05 11:40:25,187 [sor8] DEBUG SQLTransaction - [builtinApplication.Project_fetch] Started new PlanetProjectDB transaction "14875603"
=== 2012-07-05 11:40:25,187 [sor8] INFO  SQLDriver - [builtinApplication.Project_fetch] Executing SQL query on 'PlanetProjectDB': SELECT COUNT(*) FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE ('1'='1')
=== 2012-07-05 11:40:25,187 [sor8] DEBUG SQLDataSource - [builtinApplication.Project_fetch] Using SQL Limit query
=== 2012-07-05 11:40:25,203 [sor8] DEBUG SQLDataSource - [builtinApplication.Project_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT LIMIT 0 75  PROJECT.AGENDAPRINTOPTIONS, PROJECT.AGENDATEMPLATEID, PROJECT.COMMUNITYID, PROJECT.CREATED, PROJECT.DESC, PROJECT.INACTIVE, PROJECT.LASTISSUENUMBER, PROJECT.LASTTASKNUMBER, PROJECT.LOG, PROJECT.MINUTESPRINTOPTIONS, PROJECT.MINUTESTEMPLATEID, PROJECT.MODIFIED, PROJECT.OWNINGPROJECTID, OwningProject.PROJECTNUMBER AS OWNINGPROJECTNUMBER, PROJECT.PROJECTID, PROJECT.PROJECTMANAGERID, TeamMember.TEAMMEMBERNAME AS PROJECTMANAGERNAME, PROJECT.PROJECTNUMBER, PROJECT.PROJECTTITLE, PROJECT.REPORTPRINTOPTIONS, PROJECT.REPORTTEMPLATEID, PROJECT.TASKORDER FROM Project LEFT JOIN TeamMember ON Project.ProjectManagerID = TeamMember.TeamMemberID LEFT JOIN Project OwningProject ON Project.OwningProjectID = OwningProject.ProjectID WHERE ('1'='1')
=== 2012-07-05 11:40:25,203 [sor8] INFO  DSResponse - [builtinApplication.Project_fetch] DSResponse: List with 4 items
=== 2012-07-05 11:40:25,203 [sor8] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-07-05 11:40:25,203 [sor8] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "14875603"
=== 2012-07-05 11:40:25,203 [sor8] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-07-05 11:40:25,203 [sor8] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "14875603"
=== 2012-07-05 11:40:25,218 [sor8] INFO  Compression - /isomorphic/IDACall: 2854 -> 835 bytes
=== 2012-07-05 11:40:31,453 [sor4] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:31,453 [sor4] DEBUG XML - Parsed XML from (in memory stream): 0ms
=== 2012-07-05 11:40:31,625 [sor4] DEBUG RPCManager - Processing 1 requests.
=== 2012-07-05 11:40:31,968 [sor4] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\MeetingType.ds.xml: 93ms
=== 2012-07-05 11:40:32,203 [sor4] INFO  SQLDataSource - Deriving dataSource MeetingType from table: MeetingType
=== 2012-07-05 11:40:32,203 [sor4] DEBUG PoolableSQLConnectionFactory - DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\Planet Project/WEB-INF/db/hsqldb/PlanetProjectDB
=== 2012-07-05 11:40:32,234 [sor4] DEBUG PoolableSQLConnectionFactory - Passing JDBC URL only to getConnection
=== 2012-07-05 11:40:32,234 [sor4] DEBUG PoolableSQLConnectionFactory - Returning pooled Connection
=== 2012-07-05 11:40:32,234 [sor4] INFO  SQLDSGenerator - Fetching column metadata for table: MeetingType
=== 2012-07-05 11:40:32,234 [sor4] INFO  SQLDSGenerator - =============Using catalog: null
=== 2012-07-05 11:40:32,234 [sor4] INFO  SQLDSGenerator - Fetching column metadata for MeetingType complete
=== 2012-07-05 11:40:32,234 [sor4] INFO  SQLDSGenerator - Failed to get columns for MeetingType, trying MEETINGTYPE
=== 2012-07-05 11:40:32,265 [sor4] INFO  SQLDSGenerator - Fetching column metadata for MEETINGTYPE complete
=== 2012-07-05 11:40:32,265 [sor4] WARN  SQLDataSource - ds:isc.DataSource.create({
    schema:"PUBLIC",
    dbName:"PlanetProjectDB",
    ID:"MeetingType_inheritsFrom",
    dataSourceVersion:"1",
    serverType:"sql",
    generatedBy:"v8.2p_2012-06-08/EVAL Deployment 2012-06-08",
    fields:[
        {
            name:"MEETINGTYPEID",
            type:"integer"
        },
        {
            name:"PROJECTID",
            type:"integer"
        },
        {
            name:"COMMUNITYID",
            type:"integer"
        },
        {
            name:"MEETINGNAME",
            length:64,
            type:"text"
        },
        {
            name:"PURPOSE",
            length:255,
            type:"text"
        },
        {
            name:"FREQUENCY",
            length:255,
            type:"text"
        },
        {
            name:"LOCATION",
            length:255,
            type:"text"
        },
        {
            name:"TELECONFERENCEINFO",
            length:255,
            type:"text"
        },
        {
            name:"MEETINGDATE",
            type:"date"
        },
        {
            name:"MEETINGTIME",
            length:32,
            type:"text"
        },
        {
            name:"MEETINGLEADERID",
            type:"integer"
        },
        {
            name:"AGENDATEMPLATEID",
            type:"integer"
        },
        {
            name:"AGENDAPRINTOPTIONS",
            length:32767,
            type:"text"
        },
        {
            name:"MINUTESTEMPLATEID",
            type:"integer"
        },
        {
            name:"MINUTESPRINTOPTIONS",
            length:32767,
            type:"text"
        },
        {
            name:"LOG",
            length:32767,
            type:"text"
        },
        {
            name:"LASTMEETINGSTART",
            type:"datetime"
        },
        {
            name:"CREATED",
            type:"datetime"
        },
        {
            name:"MODIFIED",
            type:"datetime"
        }
    ]
})

=== 2012-07-05 11:40:32,265 [sor4] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"MeetingType",
        operationType:"fetch"
    },
    appID:"builtinApplication",
    operation:"MeetingType_fetch",
    oldValues:null
}
=== 2012-07-05 11:40:32,296 [sor4] INFO  IDACall - Performing 1 operation(s)
=== 2012-07-05 11:40:32,296 [sor4] DEBUG AppBase - [builtinApplication.MeetingType_fetch] No userTypes defined, allowing anyone access to all operations for this application
=== 2012-07-05 11:40:32,296 [sor4] DEBUG AppBase - [builtinApplication.MeetingType_fetch] No public zero-argument method named '_MeetingType_fetch' found, performing generic datasource operation
=== 2012-07-05 11:40:32,296 [sor4] INFO  SQLDataSource - [builtinApplication.MeetingType_fetch] Performing fetch operation with
	criteria: {}	values: {}
=== 2012-07-05 11:40:32,296 [sor4] INFO  SQLWhereClause - [builtinApplication.MeetingType_fetch] empty condition
=== 2012-07-05 11:40:32,296 [sor4] INFO  SQLDataSource - [builtinApplication.MeetingType_fetch] derived query: SELECT $defaultSelectClause FROM MeetingType LEFT JOIN TeamMember ON MeetingType.MeetingLeaderID = TeamMember.TeamMemberID LEFT JOIN AgendaTemplate ON MeetingType.AgendaTemplateID = AgendaTemplate.AgendaTemplateID LEFT JOIN MinutesTemplate ON MeetingType.MinutesTemplateID = MinutesTemplate.MinutesTemplateID  WHERE ($defaultWhereClause)
=== 2012-07-05 11:40:32,328 [sor4] INFO  SQLDataSource - [builtinApplication.MeetingType_fetch] Executing SQL query on 'PlanetProjectDB': SELECT MeetingType.AGENDAPRINTOPTIONS, MeetingType.AGENDATEMPLATEID, AgendaTemplate.AGENDATEMPLATENAME, MeetingType.COMMUNITYID, MeetingType.CREATED, MeetingType.FREQUENCY, MeetingType.LASTMEETINGSTART, MeetingType.LOCATION, MeetingType.LOG, MeetingType.MEETINGDATE, MeetingType.MEETINGLEADERID, TeamMember.TEAMMEMBERNAME AS MEETINGLEADERNAME, MeetingType.MEETINGNAME, MeetingType.MEETINGTIME, MeetingType.MEETINGTYPEID, MeetingType.MINUTESPRINTOPTIONS, MeetingType.MINUTESTEMPLATEID, MinutesTemplate.MINUTESTEMPLATENAME, MeetingType.MODIFIED, MeetingType.PROJECTID, MeetingType.PURPOSE, MeetingType.TELECONFERENCEINFO FROM MeetingType LEFT JOIN TeamMember ON MeetingType.MeetingLeaderID = TeamMember.TeamMemberID LEFT JOIN AgendaTemplate ON MeetingType.AgendaTemplateID = AgendaTemplate.AgendaTemplateID LEFT JOIN MinutesTemplate ON MeetingType.MinutesTemplateID = MinutesTemplate.MinutesTemplateID  WHERE (('1'='1'))
=== 2012-07-05 11:40:32,328 [sor4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.MeetingType_fetch] DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:hsqldb:file:D:\My Websites\Planet Project/WEB-INF/db/hsqldb/PlanetProjectDB
=== 2012-07-05 11:40:32,328 [sor4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.MeetingType_fetch] Passing JDBC URL only to getConnection
=== 2012-07-05 11:40:32,328 [sor4] DEBUG PoolableSQLConnectionFactory - [builtinApplication.MeetingType_fetch] Returning pooled Connection
=== 2012-07-05 11:40:32,328 [sor4] DEBUG SQLTransaction - [builtinApplication.MeetingType_fetch] Started new PlanetProjectDB transaction "26095612"
=== 2012-07-05 11:40:32,328 [sor4] INFO  SQLDriver - [builtinApplication.MeetingType_fetch] Executing SQL query on 'PlanetProjectDB': SELECT MeetingType.AGENDAPRINTOPTIONS, MeetingType.AGENDATEMPLATEID, AgendaTemplate.AGENDATEMPLATENAME, MeetingType.COMMUNITYID, MeetingType.CREATED, MeetingType.FREQUENCY, MeetingType.LASTMEETINGSTART, MeetingType.LOCATION, MeetingType.LOG, MeetingType.MEETINGDATE, MeetingType.MEETINGLEADERID, TeamMember.TEAMMEMBERNAME AS MEETINGLEADERNAME, MeetingType.MEETINGNAME, MeetingType.MEETINGTIME, MeetingType.MEETINGTYPEID, MeetingType.MINUTESPRINTOPTIONS, MeetingType.MINUTESTEMPLATEID, MinutesTemplate.MINUTESTEMPLATENAME, MeetingType.MODIFIED, MeetingType.PROJECTID, MeetingType.PURPOSE, MeetingType.TELECONFERENCEINFO FROM MeetingType LEFT JOIN TeamMember ON MeetingType.MeetingLeaderID = TeamMember.TeamMemberID LEFT JOIN AgendaTemplate ON MeetingType.AgendaTemplateID = AgendaTemplate.AgendaTemplateID LEFT JOIN MinutesTemplate ON MeetingType.MinutesTemplateID = MinutesTemplate.MinutesTemplateID  WHERE (('1'='1'))
=== 2012-07-05 11:40:32,359 [sor4] INFO  DSResponse - [builtinApplication.MeetingType_fetch] DSResponse: List with 1 items
=== 2012-07-05 11:40:32,359 [sor4] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
=== 2012-07-05 11:40:32,359 [sor4] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "26095612"
=== 2012-07-05 11:40:32,390 [sor4] DEBUG RPCManager - non-DMI response, dropExtraFields: false
=== 2012-07-05 11:40:32,390 [sor4] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "26095612"
=== 2012-07-05 11:40:32,421 [sor4] INFO  Compression - /isomorphic/IDACall: 3952 -> 845 bytes
=== 2012-07-05 11:40:53,125 [sor1] INFO  RequestContext - URL: '/isomorphic/skins/SilverWave/images/button/button_Focused_Over_start.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:53,281 [sor4] INFO  RequestContext - URL: '/isomorphic/skins/SilverWave/images/button/button_Focused_Down_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:53,281 [sor2] INFO  RequestContext - URL: '/isomorphic/skins/SilverWave/images/button/button_Focused_Over_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:53,296 [sor8] INFO  RequestContext - URL: '/isomorphic/skins/SilverWave/images/button/button_Focused_Down_stretch.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:53,296 [sor7] INFO  RequestContext - URL: '/isomorphic/skins/SilverWave/images/button/button_Focused_Down_start.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:53,343 [sor3] INFO  RequestContext - URL: '/isomorphic/skins/SilverWave/images/button/button_Focused_Over_stretch.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:54,015 [sor6] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:40:54,015 [sor6] DEBUG XML - Parsed XML from (in memory stream): 0ms
=== 2012-07-05 11:40:54,109 [sor1] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/SilverWave/images/button/button_Focused_Over_start.png
=== 2012-07-05 11:40:54,109 [sor8] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/SilverWave/images/button/button_Focused_Down_stretch.png
=== 2012-07-05 11:40:54,109 [sor7] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/SilverWave/images/button/button_Focused_Down_start.png
=== 2012-07-05 11:40:54,109 [sor4] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/SilverWave/images/button/button_Focused_Down_end.png
=== 2012-07-05 11:40:54,109 [sor2] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/SilverWave/images/button/button_Focused_Over_end.png
=== 2012-07-05 11:40:54,109 [sor3] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/SilverWave/images/button/button_Focused_Over_stretch.png
=== 2012-07-05 11:40:54,234 [sor6] DEBUG RPCManager - Processing 1 requests.
=== 2012-07-05 11:40:54,234 [sor6] DEBUG RPCManager - Request #1 (RPCRequest) data: {
    appID:"PlanetProject",
    className:"com.planetproject.server.reports.MeetingAgenda10",
    methodName:"main",
    arguments:[
    ],
    is_ISC_RPC_DMI:true
}
=== 2012-07-05 11:40:54,234 [sor6] INFO  IDACall - Performing 1 operation(s)
=== 2012-07-05 11:40:54,421 [sor6] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\app\PlanetProject.app.xml: 0ms
=== 2012-07-05 11:40:54,671 [sor6] DEBUG RPCDMI - appConfig: isc.Application.create({
    rpcBindings:[
        {
            ID:"MeetingAgenda01",
            className:"com.planetproject.server.reports.MeetingAgenda01",
            visibleMethods:[
                {
                    name:"main"
                }
            ]
        },
        {
            ID:"MeetingAgenda10",
            className:"com.planetproject.server.reports.MeetingAgenda10",
            visibleMethods:[
                {
                    name:"main"
                }
            ]
        },
        {
            ID:"MeetingAgenda11",
            className:"com.planetproject.server.reports.MeetingAgenda11",
            visibleMethods:[
                {
                    name:"main"
                }
            ]
        }
    ]
})

=== 2012-07-05 11:40:54,953 [sor6] ERROR IDACall - Top-level servlet error: 
=== 2012-07-05 11:40:54,953 [sor6] INFO  Compression - /isomorphic/IDACall: 3324 -> 915 bytes
=== 2012-07-05 11:41:07,953 [sor6] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Scrollbar/vscroll_Over_end.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:41:08,015 [sor6] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Scrollbar/vscroll_Over_end.png
=== 2012-07-05 11:41:08,125 [sor6] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Scrollbar/vscroll_Over_track.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:41:08,140 [sor6] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Scrollbar/vscroll_Over_track.png
=== 2012-07-05 11:41:08,359 [sor6] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Scrollbar/vscroll_Down_track.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:41:08,453 [sor6] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Scrollbar/vscroll_Down_track.png
=== 2012-07-05 11:48:17,203 [sor5] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_TR.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,234 [sor3] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_L.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,234 [sor2] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_R.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,312 [sor6] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_BL.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,312 [sor4] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_T.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,328 [sor7] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_TL.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,343 [sor7] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_TL.png
=== 2012-07-05 11:48:17,375 [sor3] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_L.png
=== 2012-07-05 11:48:17,375 [sor5] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_TR.png
=== 2012-07-05 11:48:17,375 [sor2] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_R.png
=== 2012-07-05 11:48:17,375 [sor6] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_BL.png
=== 2012-07-05 11:48:17,421 [sor2] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_B.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,437 [sor7] INFO  RequestContext - URL: '/isomorphic/system/development/ISC_ServerLogViewer.js', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,468 [sor3] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/Window/window_BR.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,468 [sor4] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_T.png
=== 2012-07-05 11:48:17,484 [sor2] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_B.png
=== 2012-07-05 11:48:17,515 [sor3] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/Window/window_BR.png
=== 2012-07-05 11:48:17,515 [sor7] INFO  Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/development/ISC_ServerLogViewer.js
=== 2012-07-05 11:48:17,531 [sor7] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/development/ISC_ServerLogViewer.js
=== 2012-07-05 11:48:17,656 [sor5] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,812 [sor6] INFO  RequestContext - URL: '/isomorphic/skins/Enterprise/images/pickers/comboBoxPicker.png', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:17,937 [sor5] INFO  Compression - /isomorphic/IDACall: 81 -> 86 bytes
=== 2012-07-05 11:48:18,156 [sor4] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:18,234 [sor6] INFO  Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/Enterprise/images/pickers/comboBoxPicker.png
=== 2012-07-05 11:48:18,359 [sor4] INFO  Compression - /isomorphic/IDACall: 96693 -> 8892 bytes
=== 2012-07-05 11:48:59,718 [sor2] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:48:59,953 [sor2] INFO  Compression - /isomorphic/IDACall: 81 -> 86 bytes
=== 2012-07-05 11:49:00,078 [sor3] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:49:00,250 [sor3] INFO  Compression - /isomorphic/IDACall: 97472 -> 8944 bytes
=== 2012-07-05 11:51:30,578 [sor8] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header
=== 2012-07-05 11:51:30,609 [sor8] INFO  Compression - /isomorphic/IDACall: 81 -> 86 bytes
=== 2012-07-05 11:51:30,796 [sor8] INFO  RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.18) Gecko/20110614 Firefox/3.6.18': Moz (Gecko) with Accept-Encoding header