Announcement

Collapse
No announcement yet.
X
  • Filter
  • Time
Clear All
new posts

    ArrayList cannot be cast to java.lang.String

    My application is getting much further under 9.1. It loads Issues and Projects and other records. However, it cannot load and display MyTasks. I get a java.lang.ClassCastException.

    This is the server log.
    === 2015-05-08 19:52:43,542 [sor7] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\Task.ds.xml: 0ms
    === 2015-05-08 19:52:43,542 [sor3] INFO SQLDriver - [builtinApplication.Organization_fetch] Executing SQL query on 'PlanetProjectDB': SELECT Organizat
    ion.OrganizationID, Organization.TimeZoneKey, Organization.LocaleKey, Organization.DateFormatKey, Organization.CommunityID, Organization.OrganizationNa
    me, Organization.Internal, Organization.ContactName, Organization.ContactEmail, Organization.ContactPhone, Organization.ContactAddress, Organization.De
    scription, Organization.Log, Organization.Created, Organization.Modified FROM PUBLIC.Organization WHERE ('1'='1')
    === 2015-05-08 19:52:43,573 [sor6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2015-05-08 19:52:43,573 [sor6] DEBUG DeclarativeSecurity - DataSource Community is not in the pre-checked list, processing...
    === 2015-05-08 19:52:43,557 [sor8] DEBUG SQLDataSource - [builtinApplication.Issue_fetch] Eval'd row count query: SELECT COUNT(*) FROM (Project, IssueI
    mpact, IssueLikelihood, IssueType, Issue) LEFT JOIN Category ON Issue.CategoryID = Category.CategoryID LEFT JOIN TeamMember OwnerMember ON Issue.OwnerI
    D = 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 AND Issue.OwnerID IS NOT NULL) AND NOT((Issue.IssueStatus LIKE BINARY 'Closed' AND Issue.IssueStatus IS NOT NULL))))
    === 2015-05-08 19:52:43,588 [sor8] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Issue_fetch] Executing pingTest 'select 1 from dual' on con
    nection 19984758
    === 2015-05-08 19:52:43,588 [sor6] DEBUG AppBase - [builtinApplication.Community_fetch] No userTypes defined, allowing anyone access to all operations
    for this application
    === 2015-05-08 19:52:43,604 [sor6] DEBUG AppBase - [builtinApplication.Community_fetch] No public zero-argument method named '_Community_fetch' found,
    performing generic datasource operation
    === 2015-05-08 19:52:43,588 [sor7] INFO SQLDataSource - Deriving dataSource Task from table: Task
    === 2015-05-08 19:52:43,604 [sor7] DEBUG PoolableSQLConnectionFactory - DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:mysql:/
    /localhost:3306/PUBLIC
    === 2015-05-08 19:52:43,604 [sor7] DEBUG PoolableSQLConnectionFactory - Passing credentials getConnection separately from JDBC URL
    === 2015-05-08 19:52:43,573 [sor3] INFO DSResponse - [builtinApplication.Organization_fetch] DSResponse: List with 5 items
    === 2015-05-08 19:52:43,604 [sor6] INFO SQLDataSource - [builtinApplication.Community_fetch] Performing fetch operation with
    criteria: {CommunityID:101} values: {CommunityID:101}
    === 2015-05-08 19:52:43,588 [sor8] DEBUG SQLConnectionManager - [builtinApplication.Issue_fetch] Borrowed connection '19984758'
    === 2015-05-08 19:52:43,635 [sor6] INFO SQLDataSource - [builtinApplication.Community_fetch] derived query: SELECT $defaultSelectClause FROM $defaultT
    ableClause WHERE $defaultWhereClause
    === 2015-05-08 19:52:43,635 [sor6] INFO SQLDataSource - [builtinApplication.Community_fetch] 156: Executing SQL query on 'PlanetProjectDB': SELECT Com
    munity.CommunityID, Community.AccountID, Community.CommunityName, Community.CommunityHashCode, Community.ContactName, Community.ContactEmail, Community
    .ContactPhone, Community.ContactAddress, Community.TimeZoneKey, Community.LocaleKey, Community.DateFormatKey, Community.IssueNearlyDueDays, Community.T
    askNearlyDueDays, Community.AutoAnnotateIssues, Community.AutoAnnotateRisks, Community.CloseIssuesInMeetings, Community.SendAlertsOnSave, Community.Age
    ndaServletName, Community.AgendaPrintOptions, Community.MinutesServletName, Community.MinutesPrintOptions, Community.ReportServletName, Community.Repor
    tPrintOptions, Community.Description, Community.Log, Community.Created, Community.Modified FROM PUBLIC.Community WHERE (Community.CommunityID=101)
    === 2015-05-08 19:52:43,620 [sor3] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2015-05-08 19:52:43,651 [sor3] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:43,635 [sor8] DEBUG SQLTransaction - [builtinApplication.Issue_fetch] Started new PlanetProjectDB transaction "19984758"
    === 2015-05-08 19:52:43,651 [sor8] DEBUG SQLDriver - [builtinApplication.Issue_fetch] About to execute SQL query in 'PlanetProjectDB' using connection
    '19984758'
    === 2015-05-08 19:52:43,651 [sor8] INFO SQLDriver - [builtinApplication.Issue_fetch] Executing SQL query on 'PlanetProjectDB': SELECT COUNT(*) FROM (P
    roject, IssueImpact, IssueLikelihood, IssueType, Issue) LEFT JOIN Category ON Issue.CategoryID = Category.CategoryID LEFT JOIN TeamMember OwnerMember O
    N 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 AND Issue.OwnerID IS NOT NULL) AND NOT((Issue.IssueStatus LIKE BINARY 'Closed' AND Issue.IssueStatus IS NOT NULL))))
    === 2015-05-08 19:52:43,651 [sor3] DEBUG RPCManager - non-DMI response, dropExtraFields: false
    === 2015-05-08 19:52:43,666 [sor3] DEBUG SQLTransaction - getConnection() found transactional connection for PlanetProjectDB with hashcode "25988786"
    === 2015-05-08 19:52:43,666 [sor3] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:43,666 [sor7] DEBUG PoolableSQLConnectionFactory - makeObject() created a pooled Connection '26798919'
    === 2015-05-08 19:52:43,666 [sor3] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "25988786"
    === 2015-05-08 19:52:43,682 [sor7] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 26798919
    === 2015-05-08 19:52:43,682 [sor8] DEBUG SQLDataSource - [builtinApplication.Issue_fetch] Using SQL Limit query
    === 2015-05-08 19:52:43,682 [sor3] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 25988786
    === 2015-05-08 19:52:43,682 [sor7] DEBUG SQLConnectionManager - Borrowed connection '26798919'
    === 2015-05-08 19:52:43,698 [sor6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.Community_fetch] Executing pingTest 'select 1 from dual' on
    connection 25988786
    === 2015-05-08 19:52:43,682 [sor8] DEBUG SQLDataSource - [builtinApplication.Issue_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT
    Issue.IssueID, Issue.ProjectID, Project.ProjectNumber, Project.ProjectTitle, Issue.CommunityID, Issue.IssueNumber, Issue.IssueTitle, Issue.CategoryID,
    Category.CategoryName, Issue.IssueTypeID, IssueType.IssueTypeName, Issue.ImpactID, IssueImpact.ImpactName, Issue.LikelihoodID, IssueLikelihood.Likelih
    oodTitle, Issue.OwnerID, OwnerMember.TeamMemberName AS OwnerName, Issue.OriginatorID, OrigMember.TeamMemberName AS OriginatorName, Issue.IssueStatus, I
    ssue.IssueDueDate, Issue.ClosedDate, Issue.OriginalDueDate, Issue.Resolution, Issue.Private, Issue.Description, Issue.LogEntry, Issue.Log, Issue.Create
    d, Issue.Modified, Issue.MeetingTopicID FROM (Project, IssueImpact, IssueLikelihood, IssueType, Issue) LEFT JOIN Category ON Issue.CategoryID = Categor
    y.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.LikelihoodI
    D AND Issue.IssueTypeID = IssueType.IssueTypeID AND (((Issue.OwnerID = 101 AND Issue.OwnerID IS NOT NULL) AND NOT((Issue.IssueStatus LIKE BINARY 'Close
    d' AND Issue.IssueStatus IS NOT NULL)))) ORDER BY IssueImpact.ImpactName DESC LIMIT 0, 75
    === 2015-05-08 19:52:43,713 [sor8] DEBUG SQLDataSource - [builtinApplication.Issue_fetch] SQL windowed select rows 0->75, result size 75. Query: SELECT
    Issue.IssueID, Issue.ProjectID, Project.ProjectNumber, Project.ProjectTitle, Issue.CommunityID, Issue.IssueNumber, Issue.IssueTitle, Issue.CategoryID,
    Category.CategoryName, Issue.IssueTypeID, IssueType.IssueTypeName, Issue.ImpactID, IssueImpact.ImpactName, Issue.LikelihoodID, IssueLikelihood.Likelih
    oodTitle, Issue.OwnerID, OwnerMember.TeamMemberName AS OwnerName, Issue.OriginatorID, OrigMember.TeamMemberName AS OriginatorName, Issue.IssueStatus, I
    ssue.IssueDueDate, Issue.ClosedDate, Issue.OriginalDueDate, Issue.Resolution, Issue.Private, Issue.Description, Issue.LogEntry, Issue.Log, Issue.Create
    d, Issue.Modified, Issue.MeetingTopicID FROM (Project, IssueImpact, IssueLikelihood, IssueType, Issue) LEFT JOIN Category ON Issue.CategoryID = Categor
    y.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.LikelihoodI
    D AND Issue.IssueTypeID = IssueType.IssueTypeID AND (((Issue.OwnerID = 101 AND Issue.OwnerID IS NOT NULL) AND NOT((Issue.IssueStatus LIKE BINARY 'Close
    d' AND Issue.IssueStatus IS NOT NULL)))) ORDER BY IssueImpact.ImpactName DESC LIMIT 0, 75
    === 2015-05-08 19:52:43,698 [sor6] DEBUG SQLConnectionManager - [builtinApplication.Community_fetch] Borrowed connection '25988786'
    === 2015-05-08 19:52:43,698 [sor7] INFO SQLDSGenerator - Fetching column metadata for table: Task
    === 2015-05-08 19:52:43,713 [sor7] INFO SQLDSGenerator - =============Using catalog: PUBLIC
    === 2015-05-08 19:52:43,713 [sor6] DEBUG SQLTransaction - [builtinApplication.Community_fetch] Started new PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:43,729 [sor8] INFO DSResponse - [builtinApplication.Issue_fetch] DSResponse: List with 3 items
    === 2015-05-08 19:52:43,729 [sor6] DEBUG SQLDriver - [builtinApplication.Community_fetch] About to execute SQL query in 'PlanetProjectDB' using connect
    ion '25988786'
    === 2015-05-08 19:52:43,744 [sor6] INFO SQLDriver - [builtinApplication.Community_fetch] Executing SQL query on 'PlanetProjectDB': SELECT Community.Co
    mmunityID, Community.AccountID, Community.CommunityName, Community.CommunityHashCode, Community.ContactName, Community.ContactEmail, Community.ContactP
    hone, Community.ContactAddress, Community.TimeZoneKey, Community.LocaleKey, Community.DateFormatKey, Community.IssueNearlyDueDays, Community.TaskNearly
    DueDays, Community.AutoAnnotateIssues, Community.AutoAnnotateRisks, Community.CloseIssuesInMeetings, Community.SendAlertsOnSave, Community.AgendaServle
    tName, Community.AgendaPrintOptions, Community.MinutesServletName, Community.MinutesPrintOptions, Community.ReportServletName, Community.ReportPrintOpt
    ions, Community.Description, Community.Log, Community.Created, Community.Modified FROM PUBLIC.Community WHERE (Community.CommunityID=101)
    === 2015-05-08 19:52:43,729 [sor8] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2015-05-08 19:52:43,744 [sor6] INFO DSResponse - [builtinApplication.Community_fetch] DSResponse: List with 1 items
    === 2015-05-08 19:52:43,760 [sor6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2015-05-08 19:52:43,744 [sor8] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "19984758"
    === 2015-05-08 19:52:43,760 [sor6] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:43,760 [sor8] DEBUG RPCManager - non-DMI response, dropExtraFields: false
    === 2015-05-08 19:52:43,776 [sor6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
    === 2015-05-08 19:52:43,776 [sor8] DEBUG SQLTransaction - getConnection() found transactional connection for PlanetProjectDB with hashcode "19984758"
    === 2015-05-08 19:52:43,776 [sor8] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "19984758"
    === 2015-05-08 19:52:43,776 [sor7] INFO SQLDSGenerator - Fetching column metadata for Task complete
    === 2015-05-08 19:52:43,776 [sor6] DEBUG SQLTransaction - getConnection() found transactional connection for PlanetProjectDB with hashcode "25988786"
    === 2015-05-08 19:52:43,791 [sor6] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:43,791 [sor8] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "19984758"
    === 2015-05-08 19:52:43,807 [sor6] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "25988786"
    === 2015-05-08 19:52:43,791 [sor7] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "26798919"
    === 2015-05-08 19:52:43,807 [sor8] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 19984758
    === 2015-05-08 19:52:43,822 [sor7] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 26798919
    === 2015-05-08 19:52:43,822 [sor6] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 25988786
    === 2015-05-08 19:52:43,822 [sor8] INFO Compression - /isomorphic/IDACall: 2329 -> 900 bytes
    === 2015-05-08 19:52:43,822 [sor7] ERROR IDACall - Top-level servlet error:
    java.lang.ClassCastException: java.util.ArrayList cannot be cast to java.lang.String
    at com.isomorphic.sql.SQLDataSource.buildSQLTable(SQLDataSource.java:339)
    at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:309)
    at com.isomorphic.datasource.DataSource.initialize(DataSource.java:528)
    at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:177)
    at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:513)
    at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
    at com.isomorphic.datasource.DataSource.forName(DataSource.java:272)
    at com.isomorphic.datasource.DataSource.forName(DataSource.java:264)
    at com.isomorphic.datasource.DataSource.forName(DataSource.java:253)
    at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:135)
    at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:151)
    at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:964)
    at com.isomorphic.pool.ISCKeyedObjectPool.borrowObject(ISCKeyedObjectPool.java:106)
    at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
    at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:135)
    at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
    at com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2207)
    at com.isomorphic.datasource.DSRequest.decodeUploadedStrings(DSRequest.java:789)
    at com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:676)
    at com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2402)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:328)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:308)
    at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:144)
    at com.isomorphic.servlet.IDACall._processRequest(IDACall.java:114)
    at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
    at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
    at com.isomorphic.js.JSSyntaxScannerFilter.doFilter(JSSyntaxScannerFilter.java:242)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
    at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)
    at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870)
    at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
    at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
    at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
    at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685)
    at java.lang.Thread.run(Thread.java:722)
    This is the developer connsole.
    15:52:24.398:INFO:Log:initialized
    15:52:24.801:DEBUG:Log:deferred code being placed on class: [Class AdvancedHiliteEditor]
    15:52:25.762:WARN:DynamicForm:MainForm:[Class LinkItem] form item defined with no 'name' property - Value will not be saved. To explicitly exclude a form item from the set of values to be saved, set 'shouldSaveValue' to false for this item.
    15:52:25.762:WARN:DynamicForm:MainForm:[Class LinkItem] form item defined with no 'name' property - Value will not be saved. To explicitly exclude a form item from the set of values to be saved, set 'shouldSaveValue' to false for this item.
    15:52:25.763:WARN:DynamicForm:MainForm:[Class LinkItem] form item defined with no 'name' property - Value will not be saved. To explicitly exclude a form item from the set of values to be saved, set 'shouldSaveValue' to false for this item.
    15:52:26.689:WARN:DynamicForm:ProjectStakeholderForm:unable to look up DataSource: Stakeholder, databinding will not be used
    15:52:27.080:INFO:Log:MyMeetingsReports****************************** MeetingWindow created.
    15:52:27.486:INFO:Log:isc.Page is loaded
    15:52:41.033:MUP1:INFO:Log:********************* LoginButton_click
    15:52:41.034:MUP1:INFO:Log:********************** Email address submitted: rick@menloparksoftware.com
    15:52:41.091:MUP1:INFO:Log:***END***END***END*** LoginButton_click
    15:52:41.343:XRP8:INFO:Log:*********login******** team member record fetched: {operationId: "custom",
    clientContext: Obj,
    internalClientContext: undef,
    context: Obj,
    transactionNum: 9,
    httpResponseCode: 200,
    httpResponseText: "//isc_RPCResponseStart-->[{affectedRows:..."[911],
    xmlHttpRequest: [object XMLHttpRequest],
    transport: "xmlHttpRequest",
    status: 0,
    clientOnly: undef,
    httpHeaders: Obj,
    isStructured: true,
    callbackArgs: null,
    results: Obj,
    affectedRows: 0,
    data: Array[1],
    endRow: 1,
    invalidateCache: false,
    isDSResponse: true,
    operationType: "fetch",
    queueStatus: 0,
    startRow: 0,
    totalRows: 1}
    15:52:41.344:XRP8:DEBUG:Log:************* team member data fetched: {Enabled: true,
    CommunityID: 101,
    NewPasswordRequired: true,
    OrganizationID: 102,
    SmsAlertOption: false,
    Log: "11/18/2014 10:39: Password updated by Ri..."[55],
    Created: Date(11/18/2014),
    TeamMemberEmail: "rick@menloparksoftware.com",
    Modified: Date(11/18/2014),
    Internal: true,
    AccountAdmin: true,
    TeamMemberSMS: "7346457170",
    EmailAlertOption: true,
    TimeZoneKey: "EST",
    TeamMemberID: 101,
    NDAstatus: "I AGREE",
    IssueOwnerAlerts: true,
    TaskOwnerAlerts: true,
    CommunityAdmin: true,
    OrganizationName: "IT Department",
    LocaleKey: "en_US",
    EmailConfirmed: false,
    NDArequired: false,
    DateFormatKey: "US",
    TeamMemberName: "Richard Bollinger",
    PasswordToken: "2F93CA7B8F3EF00FA4EDA4AB5D57DE01F1408460..."[96]}
    15:52:41.344:XRP8:INFO:Log:*******login********** logging in Team Member 101: Richard Bollinger
    15:52:41.344:XRP8:DEBUG:Log:************* PasswordToken.DMI.call arguments: graham, '2F93CA7B8F3EF00FA4EDA4AB5D57DE01F1408460583E8BF3A07A35C7BB268AC298C742655C0BD65E8F0E343997AE6ABD'
    15:52:41.776:XRP9:DEBUG:Log:************* data == 'VALID'
    15:52:41.776:XRP9:DEBUG:Log:************* timeZoneOffset: '-04:00'
    15:52:41.786:XRP9:DEBUG:Log:************* team member Community Admin is true
    15:52:41.941:XRP9:DEBUG:Log:************* team member Account Admin is true
    15:52:41.961:XRP9:DEBUG:Log:************* MyIssuesGrid.criteria: [object Object]
    15:52:41.972:XRP9:DEBUG:Log:************* MyTaskGrid.criteria: [object Object]
    15:52:43.435:XRP2:DEBUG:Log:************* team member project count is 18
    15:52:43.865:XRP6:INFO:Log:*******login********** community record fetched: {operationId: "custom",
    clientContext: Obj,
    internalClientContext: undef,
    context: Obj,
    transactionNum: 18,
    httpResponseCode: 200,
    httpResponseText: "//isc_RPCResponseStart-->[{affectedRows:..."[4320],
    xmlHttpRequest: [object XMLHttpRequest],
    transport: "xmlHttpRequest",
    status: 0,
    clientOnly: undef,
    httpHeaders: Obj,
    isStructured: true,
    callbackArgs: null,
    results: Obj,
    affectedRows: 0,
    data: Array[1],
    endRow: 1,
    invalidateCache: false,
    isDSResponse: true,
    operationType: "fetch",
    queueStatus: 0,
    startRow: 0,
    totalRows: 1}
    15:52:43.865:XRP6:INFO:Log:*******login********** community record fetched for Community 101: Menlo Park Associates LLC
    15:52:43.879:XRP6:DEBUG:Log:**************** Application.issueNearlyDueDate: Sun Jun 07 2015 00:00:00 GMT-0400 (Eastern Standard Time)
    15:52:43.879:XRP6:DEBUG:Log:**************** Application.taskNearlyDueDate: Sun Jun 07 2015 00:00:00 GMT-0400 (Eastern Standard Time)
    15:52:43.879:XRP6:DEBUG:Log:**************** Login.LoginButton.click: Application.currentCommunityID=101
    15:52:43.879:XRP6:INFO:Log:*********************2* Login.LoginButton.click: Application.currentCommunityID=101
    15:52:44.327:XRP6:WARN:LinkItem:Help:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:44.327:XRP6:WARN:LinkItem:BugReport:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:44.327:XRP6:WARN:LinkItem:Survey:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:44.333:XRP6:DEBUG:Log:*************** MainForm_tabSelected
    15:52:45.040:XRP8:WARN:ResultSet:isc_ResultSet_11 (dataSource Task, created by: MyTaskGrid):Bad data returned, ignoring: "isc.logWarn("java.lang.ClassCastException: java.util.ArrayList cannot be cast to java.lang.String\r\n\tat com.isomorphic.sql.SQLDataSource.buildSQLTable(SQLDataSource.java:339)\r\n\tat com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:309)\r\n\tat com.isomorphic.datasource.DataSource.initialize(DataSource.java:528)\r\n\tat com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:177)\r\n\tat com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:513)\r\n\tat com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)\r\n\tat com.isomorphic.datasource.DataSource.forName(DataSource.java:272)\r\n\tat com.isomorphic.datasource.DataSource.forName(DataSource.java:264)\r\n\tat com.isomorphic.datasource.DataSource.forName(DataSource.java:253)\r\n\tat com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:135)\r\n\tat com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:151)\r\n\tat org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:964)\r\n\tat com.isomorphic.pool.ISCKeyedObjectPool.borrowObject(ISCKeyedObjectPool.java:106)\r\n\tat com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)\r\n\tat com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:135)\r\n\tat com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)\r\n\tat com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2207)\r\n\tat com.isomorphic.datasource.DSRequest.decodeUploadedStrings(DSRequest.java:789)\r\n\tat com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:676)\r\n\tat com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2402)\r\n\tat com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:328)\r\n\tat com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:308)\r\n\tat com.isomorphic.servlet.IDACall.processRequest(IDACall.java:144)\r\n\tat com.isomorphic.servlet.IDACall._processRequest(IDACall.java:114)\r\n\tat com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)\r\n\tat javax.servlet.http.HttpServlet.service(HttpServlet.java:710)\r\n\tat com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)\r\n\tat javax.servlet.http.HttpServlet.service(HttpServlet.java:803)\r\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)\r\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)\r\n\tat com.isomorphic.js.JSSyntaxScannerFilter.doFilter(JSSyntaxScannerFilter.java:242)\r\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)\r\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)\r\n\tat com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260)\r\n\tat org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)\r\n\tat org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)\r\n\tat org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)\r\n\tat org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)\r\n\tat org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)\r\n\tat org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)\r\n\tat org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)\r\n\tat org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)\r\n\tat org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870)\r\n\tat org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)\r\n\tat org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)\r\n\tat org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)\r\n\tat org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685)\r\n\tat java.lang.Thread.run(Thread.java:722)\r\n");"
    15:52:45.073:TMR0:WARN:LinkItem:Help:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.076:TMR0:WARN:LinkItem:BugReport:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.078:TMR0:WARN:LinkItem:Survey:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.893:XRP9:INFO:Log:*******login********** account record fetched: {operationId: "custom",
    clientContext: Obj,
    internalClientContext: undef,
    context: Obj,
    transactionNum: 21,
    httpResponseCode: 200,
    httpResponseText: "//isc_RPCResponseStart-->[{affectedRows:..."[604],
    xmlHttpRequest: [object XMLHttpRequest],
    transport: "xmlHttpRequest",
    status: 0,
    clientOnly: undef,
    httpHeaders: Obj,
    isStructured: true,
    callbackArgs: null,
    results: Obj,
    affectedRows: 0,
    data: Array[1],
    endRow: 1,
    invalidateCache: false,
    isDSResponse: true,
    operationType: "fetch",
    queueStatus: 0,
    startRow: 0,
    totalRows: 1}
    15:52:45.893:XRP9:INFO:Log:*******login********** account record fetched for Account 1000000001: Menlo Park Enterprises, Inc.
    15:52:45.953:TMR2:WARN:LinkItem:Help:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.953:TMR2:WARN:LinkItem:BugReport:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.953:TMR2:WARN:LinkItem:Survey:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.957:TMR2:WARN:LinkItem:Help:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.958:TMR2:WARN:LinkItem:BugReport:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    15:52:45.959:TMR2:WARN:LinkItem:Survey:Attempting to apply event handlers to this item. Unable to get a pointer to this item's focus element
    I don't know if this is related. But, before this exception is another problem. Here is that part of the server log. Nothing of it shows on the developer console.
    === 2015-05-08 19:52:42,699 [sor6] DEBUG SQLConnectionManager - Borrowed connection '25988786'
    === 2015-05-08 19:52:42,715 [sor6] INFO SQLDSGenerator - Fetching column metadata for table: TimeZone
    === 2015-05-08 19:52:42,715 [sor6] INFO SQLDSGenerator - =============Using catalog: PUBLIC
    === 2015-05-08 19:52:42,715 [sor5] INFO DSResponse - [builtinApplication.Locale_fetch] DSResponse: List with 5 items
    === 2015-05-08 19:52:42,730 [sor5] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2015-05-08 19:52:42,730 [sor5] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "16075946"
    === 2015-05-08 19:52:42,746 [sor5] DEBUG RPCManager - non-DMI response, dropExtraFields: false
    === 2015-05-08 19:52:42,746 [sor5] DEBUG SQLTransaction - getConnection() found transactional connection for PlanetProjectDB with hashcode "16075946"
    === 2015-05-08 19:52:42,746 [sor5] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "16075946"
    === 2015-05-08 19:52:42,762 [sor5] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "16075946"
    === 2015-05-08 19:52:42,762 [sor5] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 16075946
    === 2015-05-08 19:52:42,762 [sor6] INFO SQLDSGenerator - Fetching column metadata for TimeZone complete
    === 2015-05-08 19:52:42,777 [sor6] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "25988786"
    === 2015-05-08 19:52:42,777 [sor6] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 25988786
    === 2015-05-08 19:52:42,777 [sor5] INFO Compression - /isomorphic/IDACall: 468 -> 294 bytes
    === 2015-05-08 19:52:42,777 [sor6] DEBUG RPCManager - Request #1 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
    dataSource:"TimeZone",
    repo:null,
    operationType:"fetch",
    textMatchStyle:"exact"
    },
    componentId:"(cacheAllData fetch)",
    appID:"builtinApplication",
    operation:"TimeZone_fetch",
    oldValues:null
    }
    === 2015-05-08 19:52:42,793 [sor6] INFO IDACall - Performing 1 operation(s)
    === 2015-05-08 19:52:42,808 [sor6] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
    === 2015-05-08 19:52:42,808 [sor6] DEBUG DeclarativeSecurity - DataSource TimeZone is not in the pre-checked list, processing...
    === 2015-05-08 19:52:42,808 [sor6] DEBUG AppBase - [builtinApplication.TimeZone_fetch] No userTypes defined, allowing anyone access to all operations f
    or this application
    === 2015-05-08 19:52:42,808 [sor6] DEBUG AppBase - [builtinApplication.TimeZone_fetch] No public zero-argument method named '_TimeZone_fetch' found, pe
    rforming generic datasource operation
    === 2015-05-08 19:52:42,808 [sor6] INFO SQLDataSource - [builtinApplication.TimeZone_fetch] Performing fetch operation with
    criteria: {} values: {}
    === 2015-05-08 19:52:42,808 [sor6] INFO SQLWhereClause - [builtinApplication.TimeZone_fetch] empty condition
    === 2015-05-08 19:52:42,824 [sor6] INFO SQLDataSource - [builtinApplication.TimeZone_fetch] derived query: SELECT $defaultSelectClause FROM $defaultTa
    bleClause WHERE $defaultWhereClause
    === 2015-05-08 19:52:42,793 [sor8] DEBUG RPCManager - Processing 1 requests.
    === 2015-05-08 19:52:42,824 [sor6] INFO SQLDataSource - [builtinApplication.TimeZone_fetch] 134: Executing SQL query on 'PlanetProjectDB': SELECT Time
    Zone.TimeZoneKey, TimeZone.TimeZoneTitle, TimeZone.RawOffset FROM PUBLIC.TimeZone WHERE ('1'='1')
    === 2015-05-08 19:52:42,808 [sor1] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\Project.ds.xml: 15ms
    === 2015-05-08 19:52:42,808 [sor5] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/2
    0100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
    === 2015-05-08 19:52:42,824 [sor6] DEBUG PoolableSQLConnectionFactory - [builtinApplication.TimeZone_fetch] Executing pingTest 'select 1 from dual' on
    connection 25988786
    === 2015-05-08 19:52:42,840 [sor5] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
    === 2015-05-08 19:52:42,840 [sor5] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firef
    ox/37.0
    === 2015-05-08 19:52:42,855 [sor5] DEBUG IDACall - Header Name:Value pair: accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
    === 2015-05-08 19:52:42,855 [sor5] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,de;q=0.5
    === 2015-05-08 19:52:42,840 [sor1] INFO SQLDataSource - Deriving dataSource Project from table: Project
    === 2015-05-08 19:52:42,840 [sor6] DEBUG SQLConnectionManager - [builtinApplication.TimeZone_fetch] Borrowed connection '25988786'
    === 2015-05-08 19:52:42,871 [sor1] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 16075946
    === 2015-05-08 19:52:42,855 [sor5] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip, deflate
    === 2015-05-08 19:52:42,871 [sor1] DEBUG SQLConnectionManager - Borrowed connection '16075946'
    === 2015-05-08 19:52:42,871 [sor6] DEBUG SQLTransaction - [builtinApplication.TimeZone_fetch] Started new PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:42,886 [sor6] DEBUG SQLDriver - [builtinApplication.TimeZone_fetch] About to execute SQL query in 'PlanetProjectDB' using connecti
    on '25988786'
    === 2015-05-08 19:52:42,886 [sor1] INFO SQLDSGenerator - Fetching column metadata for table: Project
    === 2015-05-08 19:52:42,871 [sor5] DEBUG IDACall - Header Name:Value pair: dnt:1
    === 2015-05-08 19:52:42,902 [sor1] INFO SQLDSGenerator - =============Using catalog: PUBLIC
    === 2015-05-08 19:52:42,886 [sor6] INFO SQLDriver - [builtinApplication.TimeZone_fetch] Executing SQL query on 'PlanetProjectDB': SELECT TimeZone.Time
    ZoneKey, TimeZone.TimeZoneTitle, TimeZone.RawOffset FROM PUBLIC.TimeZone WHERE ('1'='1')
    === 2015-05-08 19:52:42,902 [sor5] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
    === 2015-05-08 19:52:42,918 [sor5] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBuilder/workspace/PlanetProjectAlp
    haAppZ.jsp
    === 2015-05-08 19:52:42,918 [sor6] INFO DSResponse - [builtinApplication.TimeZone_fetch] DSResponse: List with 41 items
    === 2015-05-08 19:52:42,933 [sor6] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8
    === 2015-05-08 19:52:42,933 [sor6] DEBUG SQLTransaction - Committing PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:42,918 [sor5] DEBUG IDACall - Header Name:Value pair: content-length:888
    === 2015-05-08 19:52:42,933 [sor5] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A-9%2C%20%0D%20%20%20%20top%3A-9%2C%20%0
    D%20%20%20%20width%3A1525%2C%20%0D%20%20%20%20height%3A752%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A5%0D%20%20%20%20%
    7D%2C%20%0D%20%20%20%20defaultPriority%3A3%0D%7D; JSESSIONID=8DDC76B462DCA789409D94382749C301; isc_cState=ready
    === 2015-05-08 19:52:42,933 [sor6] DEBUG RPCManager - non-DMI response, dropExtraFields: false
    === 2015-05-08 19:52:42,964 [sor1] INFO SQLDSGenerator - Fetching column metadata for Project complete
    === 2015-05-08 19:52:42,964 [izer] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 25988786
    === 2015-05-08 19:52:42,933 [sor5] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
    === 2015-05-08 19:52:42,964 [sor5] DEBUG IDACall - Header Name:Value pair: pragma:no-cache
    === 2015-05-08 19:52:42,964 [sor5] DEBUG IDACall - Header Name:Value pair: cache-control:no-cache
    === 2015-05-08 19:52:42,964 [sor6] DEBUG SQLTransaction - getConnection() found transactional connection for PlanetProjectDB with hashcode "25988786"
    === 2015-05-08 19:52:42,964 [sor6] DEBUG SQLTransaction - Ending PlanetProjectDB transaction "25988786"
    === 2015-05-08 19:52:42,964 [sor5] DEBUG IDACall - session exists: 8DDC76B462DCA789409D94382749C301
    === 2015-05-08 19:52:42,964 [izer] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 16075946
    === 2015-05-08 19:52:42,980 [sor5] DEBUG IDACall - remote user: null
    === 2015-05-08 19:52:42,964 [sor1] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "16075946"
    === 2015-05-08 19:52:42,996 [sor5] DEBUG XML - Parsed XML from (in memory stream): 16ms
    === 2015-05-08 19:52:42,964 [sor6] DEBUG SQLConnectionManager - Connection 25988786 was already closed when we came to free it
    === 2015-05-08 19:52:42,996 [sor1] ERROR SQLConnectionManager - Error attempting to commit and close a connection
    java.sql.SQLException: Already closed.
    at org.apache.commons.dbcp.PoolableConnection.close(PoolableConnection.java:84)
    at com.isomorphic.sql.SQLConnectionManager.free(SQLConnectionManager.java:302)
    at com.isomorphic.sql.SQLDSGenerator.generate(SQLDSGenerator.java:211)
    at com.isomorphic.sql.SQLDataSource.getConfigFromTable(SQLDataSource.java:3851)
    at com.isomorphic.sql.SQLDataSource.fromTable(SQLDataSource.java:3800)
    at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:250)
    at com.isomorphic.datasource.DataSource.initialize(DataSource.java:528)
    at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:177)
    at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:513)
    at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
    at com.isomorphic.datasource.DataSource.forName(DataSource.java:272)
    at com.isomorphic.datasource.DataSource.forName(DataSource.java:264)
    at com.isomorphic.datasource.DataSource.forName(DataSource.java:253)
    at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:135)
    at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:151)
    at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:964)
    at com.isomorphic.pool.ISCKeyedObjectPool.borrowObject(ISCKeyedObjectPool.java:106)
    at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
    at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:135)
    at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
    at com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2207)
    at com.isomorphic.datasource.DSRequest.decodeUploadedStrings(DSRequest.java:789)
    at com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:676)
    at com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2402)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:328)
    at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:308)
    at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:144)
    at com.isomorphic.servlet.IDACall._processRequest(IDACall.java:114)
    at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
    at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
    at com.isomorphic.js.JSSyntaxScannerFilter.doFilter(JSSyntaxScannerFilter.java:242)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
    at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)
    at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870)
    at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
    at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
    at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
    at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685)
    at java.lang.Thread.run(Thread.java:722)
    Here is the code.
    Code:
    MyTaskGrid.sortField="IssueImpact";
    MyTaskGrid.sortDirection="descending";
    MyTaskGrid.fetchData(  { _constructor: "AdvancedCriteria",
    	operator:"and", criteria: [
    		{ fieldName:"OwnerID", operator:"equals", value:teamMemberRecord.TeamMemberID },
    		{ operator:"not", criteria: [ { fieldName:"TaskStatus", operator:"equals", value:"Completed"} ] }
    		] } , null, { showPrompt: true, prompt: "Logging in..." } );
    Log.logDebug("************* MyTaskGrid.criteria: " + MyTaskGrid.getCriteria());
    The screen result is a blank tab except for the words "Loading your tasks...".

    I did not see this specific problem in the forums.

    Rick

    P.S. I am running SmartClient_v91p_2015-05-06_Evaluation on Windows 7 Premium 64 bit using Mozilla Firefox 37.0.2 with Firebug.

    #2
    Problem still in v9.1 release 2015-05-09

    Testing reveals that SmartClient_v91p_2015-05-09_Evaluation version still has the casting error in buildSQLTable for the Task.ds.xml, below.

    Code:
    <DataSource ID="Task" dataFormat="iscServer" serverType="sql" dataSourceVersion="1" dbName="PlanetProjectDB" schema="PUBLIC" autoDeriveSchema="true">
        <fields>
            <field name="TaskNumber" type="integer" required="false" title="Task#"/>
            <field name="TaskTitle" type="text" required="true" length="64" title="Task Title"/>
            <field name="TaskDueDate" type="date" title="Due Date" detail="true"/>
            <field name="OwnerID" type="integer" title="Owner" detail="true" foreignKey="TeamMember.TeamMemberID"/>
            <field name="TeamMemberName" type="text">
                <tableName>TeamMember</tableName>
            </field>
            <field name="TaskStatus" type="text" required="true" length="16" title="Status">
                <valueMap>
                        <value>Open</value>
                        <value>Rejected</value>
                        <value>Accepted</value>
                        <value>Started</value>
                        <value>Waiting</value>
                        <value>Completed</value>
                </valueMap>
            </field>
            <field name="CompletedDate" type="date" title="Completed Date" detail="true"/>
            <field name="TaskID" type="sequence" detail="true" primaryKey="true"/>
            <field name="ProjectID" type="integer" required="true" detail="true" foreignKey="Project.ProjectID"/>
            <field name="ProjectNumber" type="text" title="Project#" detail="true">
                <tableName>Project</tableName>
            </field>
            <field name="ProjectTitle" type="text" title="Project Title" detail="true">
                <tableName>Project</tableName>
            </field>
            <field name="CommunityID" type="integer" required="true" hidden="true" foreignKey="Community.CommunityID"/>
            <field name="IssueID" type="integer" required="true" title="Issue#" detail="true" foreignKey="Issue.IssueID"/>
            <field name="IssueTitle" type="text" title="Issue Title" detail="true">
                <tableName>Issue</tableName>
            </field>
            <field name="Private" type="boolean" required="false" title="Private" canEdit="false" sqlStorageStrategy="integer">
    		    <tableName>Issue</tableName>
            </field>
            <field name="IssueNumber" type="text" title="Issue#" detail="true">
                <tableName>Issue</tableName>
            </field>
            <field name="IssueOwner" type="text" title="Issue Owner" detail="true">
                <nativeName>TeamMemberName</nativeName>
                <tableName>IssueMember</tableName>
            </field>
            <field name="IssueStatus" type="text" title="Issue Status" detail="true">
                <tableName>Issue</tableName>
            </field>
            <field name="IssueImpact" type="text" title="Issue Impact" detail="false">
                <nativeName>ImpactName</nativeName>
                <tableName>IssueImpact</tableName>
            </field>
            <field name="OriginalDueDate" type="date" title="Original Due" detail="true"/>
            <field name="StartDate" type="date" title="Start Date" detail="true"/>
            <field name="Description" type="text" length="2147483647" title="Description" detail="true"/>
            <field name="LogEntry" type="text" length="1024" title="Log" detail="true" canEdit="false"/>
            <field name="Log" type="text" length="2147483647" title="Log" detail="true"/>
            <field name="Created" type="creatorTimestamp" title="Created"/>
            <field name="Modified" type="modifierTimestamp" title="Modified" detail="true"/>
        </fields>
        <operationBindings>
            <OperationBinding>
                <operationType>fetch</operationType>
                <tableClause>(Project, Issue, Task, IssueImpact) LEFT JOIN TeamMember ON Task.OwnerID = TeamMember.TeamMemberID LEFT JOIN TeamMember IssueMember ON IssueMember.TeamMemberID = Issue.OwnerID</tableClause>
                <whereClause>IssueImpact.ImpactID = Issue.ImpactID AND Task.IssueID = Issue.IssueID AND Task.ProjectID = Project.ProjectID  AND ($defaultWhereClause)</whereClause>
            </OperationBinding>
        </operationBindings>
        <title>Task</title>
        <titleField>TaskTitle</titleField>
        <pluralTitle>Tasks</pluralTitle>
        <generatedBy>SC_SNAPSHOT-2012-01-09_v8.2p/Pro Deployment 2012-01-09</generatedBy>
    </DataSource>
    Curious.

    Rick

    Comment


      #3
      Problem not part of autoDeriveSchema

      I noticed in the server logs that the Task schema was being derived from the table. A check of the Task.ds.xml showed that, indeed, autoDeriveSchema was being set to true. I set it to false and tested again. The casting exception still occurs.

      Another oddity is that the failing Task criteria have the identical structure as the Issue one just before.
      Code:
      MyIssuesGrid.sortField="Impact";
      MyIssuesGrid.sortDirection="descending";
      MyIssuesGrid.fetchData( { _constructor: "AdvancedCriteria",
      	operator:"and", criteria: [
      		{ fieldName:"OwnerID", operator:"equals", value:teamMemberRecord.TeamMemberID },
      		{ operator:"not", criteria: [ { fieldName:"IssueStatus", operator:"equals", value:"Closed"} ] }
      		] } , null, { showPrompt: true, prompt: "Logging in..." } );
      m4_ifelse(m4_eval(debugPriority>=5),1,Log.logDebug("************* MyIssuesGrid.criteria: " + MyIssuesGrid.getCriteria());)
      
      MyTaskGrid.sortField="IssueImpact";
      MyTaskGrid.sortDirection="descending";
      MyTaskGrid.fetchData(  { _constructor: "AdvancedCriteria",
      	operator:"and", criteria: [
      		{ fieldName:"OwnerID", operator:"equals", value:teamMemberRecord.TeamMemberID },
      		{ operator:"not", criteria: [ { fieldName:"TaskStatus", operator:"equals", value:"Completed"} ] }
      		] } , null, { showPrompt: true, prompt: "Logging in..." } );
      m4_ifelse(m4_eval(debugPriority>=5),1,Log.logDebug("************* MyTaskGrid.criteria: " + this.echoAll(MyTaskGrid.getCriteria()));)
      Still curious are the pair of exceptions earlier in the logs that don't appear connected. But, you never know.
      Using user-defined JAVA_HOME: "C:\Program Files (x86)\Java\jdk1.7.0"

      Active code page: 65001
      Setting Tomcat <Connector> property (port 8080): maxThreads=1000
      Setting Tomcat <Connector> property (port 8080): maxHeaderCount=200
      Setting Tomcat <Connector> property (port 8080): maxKeepAliveRequests=1000
      Setting Tomcat <Connector> property (port 8080): maxHttpHeaderSize=65536
      === 2015-05-10 13:11:59,995 [main] INFO Embedded - Starting tomcat server
      === 2015-05-10 13:12:00,090 [main] INFO StandardEngine - Starting Servlet Engine: Apache Tomcat/5.5.23
      === 2015-05-10 13:12:00,141 [main] INFO StandardHost - XML validation disabled
      ISC: Configuring log4j from: file:/D:/My%20Websites/Planet%20Project/WEB-INF/classes/log4j.isc.config.xml
      log4j:WARN No such property [encoding] in com.isomorphic.log.RevolvingMemoryAppender.
      === 2015-05-10 13:12:00,660 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework - Initializing
      === 2015-05-10 13:12:00,670 [main] INFO ConfigLoader - Attempting to load framework.properties from CLASSPATH
      === 2015-05-10 13:12:00,762 [main] INFO ConfigLoader - Successfully loaded framework.properties from CLASSPATH at location: jar:file:/D:/My%20Websites
      /Planet%20Project/WEB-INF/lib/isomorphic_core_rpc.jar!/framework.properties
      === 2015-05-10 13:12:00,762 [main] INFO ConfigLoader - Attempting to load project.properties from CLASSPATH
      === 2015-05-10 13:12:00,763 [main] INFO ConfigLoader - Unable to locate project.properties in CLASSPATH
      === 2015-05-10 13:12:00,767 [main] INFO ConfigLoader - Successfully loaded isc_interfaces.properties from CLASSPATH at location: jar:file:/D:/My%20Web
      sites/Planet%20Project/WEB-INF/lib/isomorphic_core_rpc.jar!/isc_interfaces.properties
      === 2015-05-10 13:12:00,767 [main] INFO ConfigLoader - Attempting to load server.properties from CLASSPATH
      === 2015-05-10 13:12:00,771 [main] INFO ConfigLoader - Successfully loaded server.properties from CLASSPATH at location: file:/D:/My%20Websites/Planet
      %20Project/WEB-INF/classes/server.properties
      === 2015-05-10 13:12:00,784 [main] INFO Logger - Logging system started.
      === 2015-05-10 13:12:00,786 [main] INFO ISCInit - Auto-detected webRoot - using: D:\My Websites\Planet Project
      === 2015-05-10 13:12:00,788 [main] INFO ISCInit - Isomorphic SmartClient/SmartGWT Framework (v9.1p_2015-05-09/EVAL Deployment 2015-05-09) - Initializa
      tion Complete
      === 2015-05-10 13:12:00,859 [main] INFO JSSyntaxScannerFilter - Couldn't load list of FileAssembler URLs - if the filter intercepts those URLs, they w
      ill be scanned.
      === 2015-05-10 13:12:00,911 [main] INFO AuthenticationFilter - AuthExampleAuthenticator - URI Cache enabled - max size: 10000 entries
      === 2015-05-10 13:12:00,951 [main] INFO [/] - Initializing Spring root WebApplicationContext
      === 2015-05-10 13:12:06,125 [main] INFO TilesPlugin - Tiles definition factory loaded for module ''.
      === 2015-05-10 13:12:06,158 [main] INFO ValidatorPlugIn - Loading validation rules file from '/WEB-INF/validator-rules.xml'
      === 2015-05-10 13:12:06,159 [main] INFO ValidatorPlugIn - Loading validation rules file from '/WEB-INF/validation.xml'
      === 2015-05-10 13:12:06,291 [main] INFO PreCache - Isomorphic PreCache servlet loading
      === 2015-05-10 13:12:06,344 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\builtinTypes.xml: 5ms
      === 2015-05-10 13:12:06,366 [main] INFO PoolManager - SmartClient pooling started for 'simpleTypes' objects
      === 2015-05-10 13:12:06,538 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\DataSource.ds.xml: 3ms
      === 2015-05-10 13:12:06,580 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\DataSourceField.ds.xml: 3ms
      === 2015-05-10 13:12:06,612 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\Validator.ds.xml: 1ms
      === 2015-05-10 13:12:06,627 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\ValueMap.ds.xml: 1ms
      === 2015-05-10 13:12:06,632 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\InstantDataApp.ds.xml: 2ms
      === 2015-05-10 13:12:06,688 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\Application.ds.xml: 1ms
      === 2015-05-10 13:12:06,696 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\IDAPage.ds.xml: 2ms
      === 2015-05-10 13:12:06,706 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\IDAUserType.ds.xml: 4ms
      === 2015-05-10 13:12:06,720 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\IDAOperation.ds.xml: 1ms
      === 2015-05-10 13:12:06,741 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\Canvas.ds.xml: 5ms
      === 2015-05-10 13:12:06,799 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\MethodDeclaration.ds.xml: 1ms
      === 2015-05-10 13:12:06,806 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\Img.ds.xml: 1ms
      === 2015-05-10 13:12:06,815 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\StatefulCanvas.ds.xml: 1ms
      === 2015-05-10 13:12:06,852 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\Button.ds.xml: 1ms
      === 2015-05-10 13:12:06,860 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\ListViewer.ds.xml: 1ms
      === 2015-05-10 13:12:06,868 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\ListGrid.ds.xml: 3ms
      === 2015-05-10 13:12:06,909 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\DynamicForm.ds.xml: 1ms
      === 2015-05-10 13:12:06,933 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\FormItem.ds.xml: 2ms
      === 2015-05-10 13:12:06,960 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\DetailViewer.ds.xml: 1ms
      === 2015-05-10 13:12:06,972 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\DetailViewerField.ds.xml: 1ms
      === 2015-05-10 13:12:06,980 [main] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\isomorphicXML.ds.xml: 1ms
      === 2015-05-10 13:12:06,984 [main] INFO PreCache - Isomorphic PreCache complete (693ms)
      === 2015-05-10 13:12:07,058 [main] INFO Http11BaseProtocol - Initializing Coyote HTTP/1.1 on http-8080
      === 2015-05-10 13:12:07,076 [main] INFO Http11BaseProtocol - Starting Coyote HTTP/1.1 on http-8080
      === 2015-05-10 13:12:16,900 [sor4] INFO RequestContext - URL: '/tools/visualBuilder/workspace/PlanetProjectAlphaAppZ.jsp', User-Agent: 'Mozilla/5.0 (W
      indows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,119 [sor4] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/PlanetProjectAlphaAppZ.jsp: scanning <script> tags in h
      tml output
      === 2015-05-10 13:12:18,173 [sor4] INFO Compression - /tools/visualBuilder/workspace/PlanetProjectAlphaAppZ.jsp: 303160 -> 42415 bytes
      === 2015-05-10 13:12:18,375 [sor4] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Core.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW6
      4; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,381 [sor4] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Cor
      e.js
      === 2015-05-10 13:12:18,384 [sor4] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Core.js
      === 2015-05-10 13:12:18,390 [sor3] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Foundation.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1
      ; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,393 [sor1] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Grids.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW
      64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,407 [sor1] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Gri
      ds.js
      === 2015-05-10 13:12:18,407 [sor3] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Fou
      ndation.js
      === 2015-05-10 13:12:18,411 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Foundation.js
      === 2015-05-10 13:12:18,407 [sor2] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Containers.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1
      ; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,412 [sor8] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Forms.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW
      64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,408 [sor1] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Grids.js
      === 2015-05-10 13:12:18,428 [sor8] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_For
      ms.js
      === 2015-05-10 13:12:18,440 [sor8] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Forms.js
      === 2015-05-10 13:12:18,426 [sor2] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Con
      tainers.js
      === 2015-05-10 13:12:18,457 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Containers.js
      === 2015-05-10 13:12:18,442 [sor7] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_DataBinding.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.
      1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,460 [sor4] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Drawing.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; W
      OW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,474 [sor4] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Dra
      wing.js
      === 2015-05-10 13:12:18,507 [sor4] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Drawing.js
      === 2015-05-10 13:12:18,514 [sor7] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Dat
      aBinding.js
      === 2015-05-10 13:12:18,519 [sor7] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_DataBinding.js
      === 2015-05-10 13:12:18,551 [sor3] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_DocViewer.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1;
      WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,561 [sor1] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_VisualBuilder.js', User-Agent: 'Mozilla/5.0 (Windows NT
      6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,521 [sor8] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_Analytics.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1;
      WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,570 [sor1] INFO Download - File D:/My Websites/Planet Project/isomorphic/system/modules/ISC_VisualBuilder.js not found, sendin
      g 404
      === 2015-05-10 13:12:18,563 [sor3] INFO Download - File D:/My Websites/Planet Project/isomorphic/system/modules/ISC_DocViewer.js not found, sending 40
      4
      === 2015-05-10 13:12:18,581 [sor8] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Ana
      lytics.js
      === 2015-05-10 13:12:18,601 [sor1] INFO RequestContext - URL: '/tools/visualBuilder/workspace/MyCommunityZ.jsp', User-Agent: 'Mozilla/5.0 (Windows NT
      6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,624 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/load_skin.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW
      64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,624 [sor7] INFO RequestContext - URL: '/tools/visualBuilder/workspace/CommunityZ.jsp', User-Agent: 'Mozilla/5.0 (Windows NT 6.
      1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,623 [sor8] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/system/modules/ISC_Analytics.js
      === 2015-05-10 13:12:18,664 [sor3] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/load_sk
      in.js
      === 2015-05-10 13:12:18,714 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/load_skin.js
      === 2015-05-10 13:12:18,650 [sor2] INFO RequestContext - URL: '/tools/visualBuilder/workspace/MyAccountZ.jsp', User-Agent: 'Mozilla/5.0 (Windows NT 6.
      1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,743 [sor3] INFO RequestContext - URL: '/tools/visualBuilder/workspace/MyMeetingsReportsZ.jsp', User-Agent: 'Mozilla/5.0 (Windo
      ws NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,709 [sor4] INFO RequestContext - URL: '/tools/visualBuilder/workspace/MyProjectsZ.jsp', User-Agent: 'Mozilla/5.0 (Windows NT 6
      .1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:18,928 [sor1] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/MyCommunityZ.jsp: scanning <script> tags in html output

      === 2015-05-10 13:12:18,942 [sor1] INFO Compression - /tools/visualBuilder/workspace/MyCommunityZ.jsp: 75843 -> 8087 bytes
      === 2015-05-10 13:12:18,930 [sor7] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/CommunityZ.jsp: scanning <script> tags in html output
      === 2015-05-10 13:12:19,020 [sor7] INFO Compression - /tools/visualBuilder/workspace/CommunityZ.jsp: 64678 -> 5778 bytes
      === 2015-05-10 13:12:18,952 [sor2] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/MyAccountZ.jsp: scanning <script> tags in html output
      === 2015-05-10 13:12:19,030 [sor2] INFO Compression - /tools/visualBuilder/workspace/MyAccountZ.jsp: 14481 -> 2639 bytes
      === 2015-05-10 13:12:19,089 [sor4] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/MyProjectsZ.jsp: scanning <script> tags in html output
      === 2015-05-10 13:12:19,263 [sor4] INFO Compression - /tools/visualBuilder/workspace/MyProjectsZ.jsp: 113430 -> 14084 bytes
      === 2015-05-10 13:12:19,350 [sor3] INFO JSSyntaxScannerFilter - /tools/visualBuilder/workspace/MyMeetingsReportsZ.jsp: scanning <script> tags in html
      output
      === 2015-05-10 13:12:19,369 [sor3] INFO Compression - /tools/visualBuilder/workspace/MyMeetingsReportsZ.jsp: 216472 -> 33245 bytes
      === 2015-05-10 13:12:19,839 [sor1] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_DocViewer.js', User-Agent: 'Mozilla/5.0 (Windows NT 6.1;
      WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:19,844 [sor1] INFO Download - File D:/My Websites/Planet Project/isomorphic/system/modules/ISC_DocViewer.js not found, sending 40
      4
      === 2015-05-10 13:12:19,934 [sor1] INFO RequestContext - URL: '/isomorphic/system/modules/ISC_VisualBuilder.js', User-Agent: 'Mozilla/5.0 (Windows NT
      6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:19,936 [sor1] INFO Download - File D:/My Websites/Planet Project/isomorphic/system/modules/ISC_VisualBuilder.js not found, sendin
      g 404
      === 2015-05-10 13:12:19,958 [sor1] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/skin_styles.css', User-Agent: 'Mozilla/5.0 (Windows NT 6.1;
      WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:19,962 [sor1] INFO Download - Using gzipped form of request file: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/skin_st
      yles.css
      === 2015-05-10 13:12:19,964 [sor1] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/skin_styles.css
      === 2015-05-10 13:12:20,842 [sor1] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/button/button_start.png', User-Agent: 'Mozilla/5.0 (W
      indows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:20,856 [sor2] INFO RequestContext - URL: '/tools/visualBuilder/workspace/images/LoginDiagram2.jpg', User-Agent: 'Mozilla/5.0 (Win
      dows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:20,860 [sor1] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/button/button_start.
      png
      === 2015-05-10 13:12:20,853 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/blank.gif', User-Agent: 'Mozilla/5.0 (Windows NT 6.1;
      WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:20,848 [sor7] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadow.gif', User-Agent: 'Mozilla/5.0 (Windows
      NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:20,848 [sor8] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/button/button_end.png', User-Agent: 'Mozilla/5.0 (Win
      dows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:20,881 [sor7] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadow.gif
      === 2015-05-10 13:12:20,846 [sor4] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/button/button_stretch.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:20,881 [sor8] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/button/button_end.pn
      g
      === 2015-05-10 13:12:20,875 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/blank.gif
      === 2015-05-10 13:12:20,890 [sor4] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/button/button_stretc
      h.png
      === 2015-05-10 13:12:22,219 [sor1] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/button/button_Disabled_start.png', User-Agent: 'Mozil
      la/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,219 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/button/button_Disabled_end.png', User-Agent: 'Mozilla
      /5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,229 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/button/button_Disabl
      ed_end.png
      === 2015-05-10 13:12:22,219 [sor4] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/button/button_Disabled_stretch.png', User-Agent: 'Moz
      illa/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,234 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/controls/date_control.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,244 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/controls/date_contro
      l.png
      === 2015-05-10 13:12:22,223 [sor1] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/button/button_Disabl
      ed_start.png
      === 2015-05-10 13:12:22,237 [sor4] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/button/button_Disabl
      ed_stretch.png
      === 2015-05-10 13:12:22,247 [sor1] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/controls/selectPicker.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,256 [sor4] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/DynamicForm/unchecked.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,260 [sor1] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/controls/selectPicke
      r.png
      === 2015-05-10 13:12:22,260 [sor4] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/DynamicForm/unchecke
      d.png
      === 2015-05-10 13:12:22,519 [sor4] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/2
      0100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,519 [sor1] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/2
      0100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,519 [sor8] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/2
      0100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,524 [sor1] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
      === 2015-05-10 13:12:22,523 [sor4] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
      === 2015-05-10 13:12:22,521 [sor2] INFO RequestContext - URL: '/isomorphic/DataSourceLoader', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0
      ) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,534 [sor4] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firef
      ox/37.0
      === 2015-05-10 13:12:22,541 [sor4] DEBUG IDACall - Header Name:Value pair: accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
      === 2015-05-10 13:12:22,531 [sor1] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firef
      ox/37.0
      === 2015-05-10 13:12:22,529 [sor8] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
      === 2015-05-10 13:12:22,549 [sor1] DEBUG IDACall - Header Name:Value pair: accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
      === 2015-05-10 13:12:22,546 [sor4] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,de;q=0.5
      === 2015-05-10 13:12:22,543 [sor2] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\Stakeholder.ds.xml: 2ms
      === 2015-05-10 13:12:22,560 [sor4] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip, deflate
      === 2015-05-10 13:12:22,570 [sor4] DEBUG IDACall - Header Name:Value pair: dnt:1
      === 2015-05-10 13:12:22,571 [sor4] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
      === 2015-05-10 13:12:22,556 [sor1] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,de;q=0.5
      === 2015-05-10 13:12:22,585 [sor1] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip, deflate
      === 2015-05-10 13:12:22,588 [sor1] DEBUG IDACall - Header Name:Value pair: dnt:1
      === 2015-05-10 13:12:22,599 [sor1] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
      === 2015-05-10 13:12:22,601 [sor1] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBu...anetProjectAlp
      haAppZ.jsp
      === 2015-05-10 13:12:22,605 [sor1] DEBUG IDACall - Header Name:Value pair: content-length:888
      === 2015-05-10 13:12:22,609 [sor1] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A0%2C%20%0D%20%20%20%20top%3A0%2C%20%0D%
      20%20%20%20width%3A1505%2C%20%0D%20%20%20%20height%3A733%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A5%0D%20%20%20%20%7D
      %2C%20%0D%20%20%20%20defaultPriority%3A3%0D%7D; isc_cState=ready; JSESSIONID=A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,617 [sor1] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
      === 2015-05-10 13:12:22,618 [sor1] DEBUG IDACall - Header Name:Value pair: pragma:no-cache
      === 2015-05-10 13:12:22,635 [sor1] DEBUG IDACall - Header Name:Value pair: cache-control:no-cache
      === 2015-05-10 13:12:22,636 [sor1] DEBUG IDACall - session exists: A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,640 [sor1] DEBUG IDACall - remote user: null
      === 2015-05-10 13:12:22,552 [sor8] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firef
      ox/37.0
      === 2015-05-10 13:12:22,654 [sor8] DEBUG IDACall - Header Name:Value pair: accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
      === 2015-05-10 13:12:22,656 [sor8] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,de;q=0.5
      === 2015-05-10 13:12:22,660 [sor8] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip, deflate
      === 2015-05-10 13:12:22,664 [sor8] DEBUG IDACall - Header Name:Value pair: dnt:1
      === 2015-05-10 13:12:22,668 [sor8] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
      === 2015-05-10 13:12:22,670 [sor8] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBu...anetProjectAlp
      haAppZ.jsp
      === 2015-05-10 13:12:22,673 [sor8] DEBUG IDACall - Header Name:Value pair: content-length:888
      === 2015-05-10 13:12:22,679 [sor8] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A0%2C%20%0D%20%20%20%20top%3A0%2C%20%0D%
      20%20%20%20width%3A1505%2C%20%0D%20%20%20%20height%3A733%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A5%0D%20%20%20%20%7D
      %2C%20%0D%20%20%20%20defaultPriority%3A3%0D%7D; isc_cState=ready; JSESSIONID=A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,681 [sor8] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
      === 2015-05-10 13:12:22,683 [sor8] DEBUG IDACall - Header Name:Value pair: pragma:no-cache
      === 2015-05-10 13:12:22,687 [sor8] DEBUG IDACall - Header Name:Value pair: cache-control:no-cache
      === 2015-05-10 13:12:22,690 [sor8] DEBUG IDACall - session exists: A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,694 [sor8] DEBUG IDACall - remote user: null
      === 2015-05-10 13:12:22,583 [sor3] INFO RequestContext - URL: '/favicon.ico', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101
      Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,582 [sor7] INFO RequestContext - URL: '/isomorphic/IDACall', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/2
      0100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:22,708 [sor7] DEBUG IDACall - Header Name:Value pair: host:localhost:8080
      === 2015-05-10 13:12:22,709 [sor7] DEBUG IDACall - Header Name:Value pair: user-agent:Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firef
      ox/37.0
      === 2015-05-10 13:12:22,717 [sor7] DEBUG IDACall - Header Name:Value pair: accept:text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
      === 2015-05-10 13:12:22,718 [sor7] DEBUG IDACall - Header Name:Value pair: accept-language:en-US,de;q=0.5
      === 2015-05-10 13:12:22,723 [sor7] DEBUG IDACall - Header Name:Value pair: accept-encoding:gzip, deflate
      === 2015-05-10 13:12:22,727 [sor7] DEBUG IDACall - Header Name:Value pair: dnt:1
      === 2015-05-10 13:12:22,730 [sor7] DEBUG IDACall - Header Name:Value pair: content-type:application/x-www-form-urlencoded; charset=UTF-8
      === 2015-05-10 13:12:22,733 [sor7] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBu...anetProjectAlp
      haAppZ.jsp
      === 2015-05-10 13:12:22,736 [sor7] DEBUG IDACall - Header Name:Value pair: content-length:2348
      === 2015-05-10 13:12:22,740 [sor7] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A0%2C%20%0D%20%20%20%20top%3A0%2C%20%0D%
      20%20%20%20width%3A1505%2C%20%0D%20%20%20%20height%3A733%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A5%0D%20%20%20%20%7D
      %2C%20%0D%20%20%20%20defaultPriority%3A3%0D%7D; isc_cState=ready; JSESSIONID=A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,745 [sor7] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
      === 2015-05-10 13:12:22,747 [sor7] DEBUG IDACall - Header Name:Value pair: pragma:no-cache
      === 2015-05-10 13:12:22,750 [sor7] DEBUG IDACall - Header Name:Value pair: cache-control:no-cache
      === 2015-05-10 13:12:22,754 [sor7] DEBUG IDACall - session exists: A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,757 [sor7] DEBUG IDACall - remote user: null
      === 2015-05-10 13:12:22,575 [sor4] DEBUG IDACall - Header Name:Value pair: referer:http://localhost:8080/tools/visualBu...anetProjectAlp
      haAppZ.jsp
      === 2015-05-10 13:12:22,574 [sor2] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\OperationBinding.ds.xml: 2ms
      === 2015-05-10 13:12:22,768 [sor4] DEBUG IDACall - Header Name:Value pair: content-length:896
      === 2015-05-10 13:12:22,782 [sor4] DEBUG IDACall - Header Name:Value pair: cookie:GLog=%7B%0D%20%20%20%20left%3A0%2C%20%0D%20%20%20%20top%3A0%2C%20%0D%
      20%20%20%20width%3A1505%2C%20%0D%20%20%20%20height%3A733%2C%20%0D%20%20%20%20priorityDefaults%3A%7B%0D%20%20%20%20%20%20%20%20Log%3A5%0D%20%20%20%20%7D
      %2C%20%0D%20%20%20%20defaultPriority%3A3%0D%7D; isc_cState=ready; JSESSIONID=A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,783 [sor4] DEBUG IDACall - Header Name:Value pair: connection:keep-alive
      === 2015-05-10 13:12:22,789 [sor4] DEBUG IDACall - Header Name:Value pair: pragma:no-cache
      === 2015-05-10 13:12:22,790 [sor4] DEBUG IDACall - Header Name:Value pair: cache-control:no-cache
      === 2015-05-10 13:12:22,795 [sor4] DEBUG IDACall - session exists: A6E0765528F6AD9C6DCF5DD3384F67C7
      === 2015-05-10 13:12:22,799 [sor4] DEBUG IDACall - remote user: null
      === 2015-05-10 13:12:22,767 [sor7] DEBUG XML - Parsed XML from (in memory stream): 2ms
      === 2015-05-10 13:12:22,702 [sor8] DEBUG XML - Parsed XML from (in memory stream): 2ms
      === 2015-05-10 13:12:22,681 [sor1] DEBUG XML - Parsed XML from (in memory stream): 4ms
      === 2015-05-10 13:12:22,807 [sor4] DEBUG XML - Parsed XML from (in memory stream): 3ms
      === 2015-05-10 13:12:22,861 [sor2] DEBUG DataSourceLoader - For DataSource 'Stakeholder', got instanceId 74
      === 2015-05-10 13:12:22,868 [sor8] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\isomorphic\system\schema\List.ds.xml: 0ms
      === 2015-05-10 13:12:22,877 [sor8] DEBUG RPCManager - Processing 1 requests.
      === 2015-05-10 13:12:22,877 [sor1] DEBUG RPCManager - Processing 1 requests.
      === 2015-05-10 13:12:22,886 [sor4] DEBUG RPCManager - Processing 1 requests.
      === 2015-05-10 13:12:22,881 [sor7] DEBUG RPCManager - Processing 3 requests.
      === 2015-05-10 13:12:22,907 [sor2] INFO Compression - /isomorphic/DataSourceLoader: 3696 -> 1041 bytes
      === 2015-05-10 13:12:22,913 [sor7] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\TeamMember.ds.xml: 1ms
      === 2015-05-10 13:12:22,933 [sor7] DEBUG RPCManager - Request #1 (DSRequest) payload: {
      criteria:{
      },
      operationConfig:{
      dataSource:"TeamMember",
      repo:null,
      operationType:"fetch",
      textMatchStyle:"startsWith"
      },
      startRow:0,
      endRow:75,
      componentId:"isc_PickListMenu_2",
      appID:"builtinApplication",
      operation:"TeamMember_fetch",
      oldValues:{
      }
      }
      === 2015-05-10 13:12:22,934 [sor1] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\IssueImpact.ds.xml: 1ms
      === 2015-05-10 13:12:22,949 [sor1] INFO SQLDataSource - Deriving dataSource IssueImpact from table: IssueImpact
      === 2015-05-10 13:12:23,035 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_TL.png', User-Agent: 'Mozilla/5.0 (Win
      dows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,036 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_T.png', User-Agent: 'Mozilla/5.0 (Wind
      ows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,040 [sor1] INFO PoolManager - SmartClient pooling started for 'PlanetProjectDB' objects
      === 2015-05-10 13:12:23,042 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_TL.pn
      g
      === 2015-05-10 13:12:23,044 [sor1] DEBUG PoolableSQLConnectionFactory - Initializing SQL config for 'PlanetProjectDB' from system config - using Driver
      Manager: com.mysql.jdbc.Driver
      === 2015-05-10 13:12:23,043 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_T.png

      === 2015-05-10 13:12:23,052 [sor1] DEBUG PoolableSQLConnectionFactory - com.mysql.jdbc.Driver lookup successful
      === 2015-05-10 13:12:23,057 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_TR.png', User-Agent: 'Mozilla/5.0 (Win
      dows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,064 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_L.png', User-Agent: 'Mozilla/5.0 (Wind
      ows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,059 [sor1] DEBUG PoolableSQLConnectionFactory - DriverManager fetching connection for PlanetProjectDB via jdbc url jdbc:mysql:/
      /localhost:3306/PUBLIC
      === 2015-05-10 13:12:23,067 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_TR.pn
      g
      === 2015-05-10 13:12:23,077 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_L.png

      === 2015-05-10 13:12:23,072 [sor1] DEBUG PoolableSQLConnectionFactory - Passing credentials getConnection separately from JDBC URL
      === 2015-05-10 13:12:23,088 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_BL.png', User-Agent: 'Mozilla/5.0 (Win
      dows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,078 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_R.png', User-Agent: 'Mozilla/5.0 (Wind
      ows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,097 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_BL.pn
      g
      === 2015-05-10 13:12:23,099 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_R.png

      === 2015-05-10 13:12:23,107 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_BR.png', User-Agent: 'Mozilla/5.0 (Win
      dows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,108 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/edges/Window/w_B.png', User-Agent: 'Mozilla/5.0 (Wind
      ows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,112 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_BR.pn
      g
      === 2015-05-10 13:12:23,115 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/edges/Window/w_B.png

      === 2015-05-10 13:12:23,122 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_TL.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,126 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_T.png', User-Agent: 'Mozilla/5.0 (
      Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,132 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_T
      L.png
      === 2015-05-10 13:12:23,134 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_T
      .png
      === 2015-05-10 13:12:23,143 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_TR.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,146 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_L.png', User-Agent: 'Mozilla/5.0 (
      Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,154 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_T
      R.png
      === 2015-05-10 13:12:23,160 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_L
      .png
      === 2015-05-10 13:12:23,164 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds_center.png', User-Agent: 'Mozilla/5
      .0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,169 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_R.png', User-Agent: 'Mozilla/5.0 (
      Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,186 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds_ce
      nter.png
      === 2015-05-10 13:12:23,195 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_R
      .png
      === 2015-05-10 13:12:23,198 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_BL.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,202 [sor2] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_B.png', User-Agent: 'Mozilla/5.0 (
      Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,206 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_B
      L.png
      === 2015-05-10 13:12:23,216 [sor3] INFO RequestContext - URL: '/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_BR.png', User-Agent: 'Mozilla/5.0
      (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101 Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,223 [sor3] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_B
      R.png
      === 2015-05-10 13:12:23,214 [sor2] INFO Download - done streaming: D:/My Websites/Planet Project/isomorphic/skins/TreeFrog/images/shared/shadows/ds2_B
      .png
      === 2015-05-10 13:12:23,238 [sor2] INFO RequestContext - URL: '/favicon.ico', User-Agent: 'Mozilla/5.0 (Windows NT 6.1; WOW64; rv:37.0) Gecko/20100101
      Firefox/37.0': Moz (Gecko) with Accept-Encoding header
      === 2015-05-10 13:12:23,532 [sor1] DEBUG PoolableSQLConnectionFactory - makeObject() created a pooled Connection '23932647'
      === 2015-05-10 13:12:23,542 [sor1] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,547 [sor1] DEBUG SQLConnectionManager - Borrowed connection '23932647'
      === 2015-05-10 13:12:23,550 [sor1] INFO SQLDSGenerator - Fetching column metadata for table: IssueImpact
      === 2015-05-10 13:12:23,551 [sor1] INFO SQLDSGenerator - =============Using catalog: PUBLIC
      === 2015-05-10 13:12:23,600 [sor1] INFO SQLDSGenerator - Fetching column metadata for IssueImpact complete
      === 2015-05-10 13:12:23,612 [sor1] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "23932647"
      === 2015-05-10 13:12:23,613 [sor1] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,617 [sor1] DEBUG RPCManager - Request #1 (DSRequest) payload: {
      criteria:{
      },
      operationConfig:{
      dataSource:"IssueImpact",
      repo:null,
      operationType:"fetch",
      textMatchStyle:"exact"
      },
      componentId:"(cacheAllData fetch)",
      appID:"builtinApplication",
      operation:"IssueImpact_fetch",
      oldValues:null
      }
      === 2015-05-10 13:12:23,621 [sor1] INFO IDACall - Performing 1 operation(s)
      === 2015-05-10 13:12:23,620 [sor8] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\MeetingType.ds.xml: 2ms
      === 2015-05-10 13:12:23,623 [sor1] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
      === 2015-05-10 13:12:23,632 [sor1] DEBUG DeclarativeSecurity - DataSource IssueImpact is not in the pre-checked list, processing...
      === 2015-05-10 13:12:23,647 [sor8] INFO SQLDataSource - Deriving dataSource MeetingType from table: MeetingType
      === 2015-05-10 13:12:23,648 [sor8] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,652 [sor8] DEBUG SQLConnectionManager - Borrowed connection '23932647'
      === 2015-05-10 13:12:23,655 [sor8] INFO SQLDSGenerator - Fetching column metadata for table: MeetingType
      === 2015-05-10 13:12:23,656 [sor8] INFO SQLDSGenerator - =============Using catalog: PUBLIC
      === 2015-05-10 13:12:23,661 [sor1] DEBUG AppBase - [builtinApplication.IssueImpact_fetch] No userTypes defined, allowing anyone access to all operation
      s for this application
      === 2015-05-10 13:12:23,665 [sor1] DEBUG AppBase - [builtinApplication.IssueImpact_fetch] No public zero-argument method named '_IssueImpact_fetch' fou
      nd, performing generic datasource operation
      === 2015-05-10 13:12:23,671 [sor1] INFO SQLDataSource - [builtinApplication.IssueImpact_fetch] Performing fetch operation with
      criteria: {} values: {}
      === 2015-05-10 13:12:23,686 [sor8] INFO SQLDSGenerator - Fetching column metadata for MeetingType complete
      === 2015-05-10 13:12:23,696 [sor8] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "23932647"
      === 2015-05-10 13:12:23,696 [sor8] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,704 [sor8] DEBUG RPCManager - Request #1 (DSRequest) payload: {
      criteria:{
      },
      operationConfig:{
      dataSource:"MeetingType",
      repo:null,
      operationType:"fetch",
      textMatchStyle:"exact"
      },
      componentId:"(cacheAllData fetch)",
      appID:"builtinApplication",
      operation:"MeetingType_fetch",
      oldValues:null
      }
      === 2015-05-10 13:12:23,705 [sor1] INFO SQLWhereClause - [builtinApplication.IssueImpact_fetch] empty condition
      === 2015-05-10 13:12:23,706 [sor4] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\IssueLikelihood.ds.xml: 2ms
      === 2015-05-10 13:12:23,705 [sor8] INFO IDACall - Performing 1 operation(s)
      === 2015-05-10 13:12:23,710 [sor1] INFO SQLDataSource - [builtinApplication.IssueImpact_fetch] derived query: SELECT $defaultSelectClause FROM $defaul
      tTableClause WHERE $defaultWhereClause
      === 2015-05-10 13:12:23,718 [sor8] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
      === 2015-05-10 13:12:23,725 [sor8] DEBUG DeclarativeSecurity - DataSource MeetingType is not in the pre-checked list, processing...
      === 2015-05-10 13:12:23,727 [sor4] INFO SQLDataSource - Deriving dataSource IssueLikelihood from table: IssueLikelihood
      === 2015-05-10 13:12:23,729 [sor8] DEBUG AppBase - [builtinApplication.MeetingType_fetch] No userTypes defined, allowing anyone access to all operation
      s for this application
      === 2015-05-10 13:12:23,736 [sor8] DEBUG AppBase - [builtinApplication.MeetingType_fetch] No public zero-argument method named '_MeetingType_fetch' fou
      nd, performing generic datasource operation
      === 2015-05-10 13:12:23,740 [sor8] INFO SQLDataSource - [builtinApplication.MeetingType_fetch] Performing fetch operation with
      criteria: {} values: {}
      === 2015-05-10 13:12:23,733 [sor4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,744 [sor8] INFO SQLWhereClause - [builtinApplication.MeetingType_fetch] empty condition
      === 2015-05-10 13:12:23,751 [sor8] INFO SQLDataSource - [builtinApplication.MeetingType_fetch] derived query: SELECT $defaultSelectClause FROM Meeting
      Type LEFT JOIN TeamMember ON MeetingType.MeetingLeaderID = TeamMember.TeamMemberID LEFT JOIN AgendaTemplate ON MeetingType.AgendaServletName = AgendaTe
      mplate.AgendaServletName LEFT JOIN MinutesTemplate ON MeetingType.MinutesServletName = MinutesTemplate.MinutesServletName WHERE ($defaultWhereClause)
      === 2015-05-10 13:12:23,749 [sor4] DEBUG SQLConnectionManager - Borrowed connection '23932647'
      === 2015-05-10 13:12:23,759 [sor4] INFO SQLDSGenerator - Fetching column metadata for table: IssueLikelihood
      === 2015-05-10 13:12:23,760 [sor4] INFO SQLDSGenerator - =============Using catalog: PUBLIC
      === 2015-05-10 13:12:23,781 [sor4] INFO SQLDSGenerator - Fetching column metadata for IssueLikelihood complete
      === 2015-05-10 13:12:23,787 [sor4] DEBUG SQLConnectionManager - About to close ISCPoolableConnection with hashcode "23932647"
      === 2015-05-10 13:12:23,787 [sor4] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,797 [sor4] DEBUG RPCManager - Request #1 (DSRequest) payload: {
      criteria:{
      },
      operationConfig:{
      dataSource:"IssueLikelihood",
      repo:null,
      operationType:"fetch",
      textMatchStyle:"exact"
      },
      componentId:"(cacheAllData fetch)",
      appID:"builtinApplication",
      operation:"IssueLikelihood_fetch",
      oldValues:null
      }
      === 2015-05-10 13:12:23,798 [sor4] INFO IDACall - Performing 1 operation(s)
      === 2015-05-10 13:12:23,799 [sor4] DEBUG DeclarativeSecurity - Processing security checks for DataSource null, field null
      === 2015-05-10 13:12:23,800 [sor4] DEBUG DeclarativeSecurity - DataSource IssueLikelihood is not in the pre-checked list, processing...
      === 2015-05-10 13:12:23,807 [sor4] DEBUG AppBase - [builtinApplication.IssueLikelihood_fetch] No userTypes defined, allowing anyone access to all opera
      tions for this application
      === 2015-05-10 13:12:23,799 [sor7] DEBUG XML - Parsed XML from D:\My Websites\Planet Project\shared\ds\AgendaTemplate.ds.xml: 2ms
      === 2015-05-10 13:12:23,809 [sor4] DEBUG AppBase - [builtinApplication.IssueLikelihood_fetch] No public zero-argument method named '_IssueLikelihood_fe
      tch' found, performing generic datasource operation
      === 2015-05-10 13:12:23,818 [sor4] INFO SQLDataSource - [builtinApplication.IssueLikelihood_fetch] Performing fetch operation with
      criteria: {} values: {}
      === 2015-05-10 13:12:23,821 [sor4] INFO SQLWhereClause - [builtinApplication.IssueLikelihood_fetch] empty condition
      === 2015-05-10 13:12:23,825 [sor4] INFO SQLDataSource - [builtinApplication.IssueLikelihood_fetch] derived query: SELECT $defaultSelectClause FROM $de
      faultTableClause WHERE $defaultWhereClause
      === 2015-05-10 13:12:23,824 [sor7] INFO SQLDataSource - Deriving dataSource AgendaTemplate from table: AgendaTemplate
      === 2015-05-10 13:12:23,831 [sor7] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,837 [sor7] DEBUG SQLConnectionManager - Borrowed connection '23932647'
      === 2015-05-10 13:12:23,851 [sor7] INFO SQLDSGenerator - Fetching column metadata for table: AgendaTemplate
      === 2015-05-10 13:12:23,851 [sor7] INFO SQLDSGenerator - =============Using catalog: PUBLIC
      === 2015-05-10 13:12:23,851 [izer] DEBUG PoolableSQLConnectionFactory - Executing pingTest 'select 1 from dual' on connection 23932647
      === 2015-05-10 13:12:23,865 [sor1] DEBUG Velocity - SimpleLog4JLogSystem using category 'org.apache.Velocity'
      === 2015-05-10 13:12:23,865 [sor1] DEBUG Velocity - SimpleLog4JLogSystem using category 'org.apache.Velocity'
      === 2015-05-10 13:12:23,866 [sor1] DEBUG Velocity - *******************************************************************
      === 2015-05-10 13:12:23,869 [sor7] INFO SQLDSGenerator - Fetching column metadata for AgendaTemplate complete
      === 2015-05-10 13:12:23,866 [sor1] DEBUG Velocity - *******************************************************************
      === 2015-05-10 13:12:23,874 [sor7] INFO PoolableSQLConnectionFactory - No explicit configuration for db: derivedFromConnection in server.properties -
      will look for a Connection object at derivedFromConnection or java:comp/env/derivedFromConnection.
      === 2015-05-10 13:12:23,876 [sor1] DEBUG Velocity - Starting Apache Velocity v1.6.1 (compiled: 2008-12-09 10:57:23)
      === 2015-05-10 13:12:23,881 [sor7] INFO PoolManager - SmartClient pooling disabled for 'derivedFromConnection' objects
      === 2015-05-10 13:12:23,876 [sor1] DEBUG Velocity - Starting Apache Velocity v1.6.1 (compiled: 2008-12-09 10:57:23)
      === 2015-05-10 13:12:23,887 [sor7] DEBUG PoolableSQLConnectionFactory - Initializing SQL config for 'derivedFromConnection' via JNDI
      === 2015-05-10 13:12:23,890 [sor1] DEBUG Velocity - RuntimeInstance initializing.
      === 2015-05-10 13:12:23,890 [sor1] DEBUG Velocity - RuntimeInstance initializing.
      === 2015-05-10 13:12:23,900 [sor1] DEBUG Velocity - Default Properties File: org\apache\velocity\runtime\defaults\velocity.properties
      === 2015-05-10 13:12:23,900 [sor1] DEBUG Velocity - Default Properties File: org\apache\velocity\runtime\defaults\velocity.properties
      === 2015-05-10 13:12:23,907 [sor1] DEBUG Velocity - Trying to use logger class org.apache.velocity.runtime.log.SimpleLog4JLogSystem
      === 2015-05-10 13:12:23,907 [sor1] DEBUG Velocity - Trying to use logger class org.apache.velocity.runtime.log.SimpleLog4JLogSystem
      === 2015-05-10 13:12:23,909 [sor7] ERROR SQLDSGenerator - Unable to load foreign key information for autoDeriveFK logic.
      java.sql.SQLException: java.sql.SQLException: javax.naming.NameNotFoundException: Name derivedFromConnection is not bound in this Context
      at com.isomorphic.sql.SQLMetaData.conn(SQLMetaData.java:113)
      at com.isomorphic.sql.SQLMetaData.getMetaData(SQLMetaData.java:132)
      at com.isomorphic.sql.SQLMetaData.getForeignKeys(SQLMetaData.java:334)
      at com.isomorphic.sql.SQLDSGenerator.getFieldsFromTable(SQLDSGenerator.java:363)
      at com.isomorphic.sql.SQLDSGenerator.generate(SQLDSGenerator.java:176)
      at com.isomorphic.sql.SQLDataSource.getConfigFromTable(SQLDataSource.java:3851)
      at com.isomorphic.sql.SQLDataSource.fromTable(SQLDataSource.java:3800)
      at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:250)
      at com.isomorphic.datasource.DataSource.initialize(DataSource.java:528)
      at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:177)
      at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:513)
      at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
      at com.isomorphic.datasource.DataSource.forName(DataSource.java:272)
      at com.isomorphic.datasource.DataSource.forName(DataSource.java:264)
      at com.isomorphic.datasource.DataSource.forName(DataSource.java:253)
      at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:135)
      at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:151)
      at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:964)
      at com.isomorphic.pool.ISCKeyedObjectPool.borrowObject(ISCKeyedObjectPool.java:106)
      at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
      at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:135)
      at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
      at com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2207)
      at com.isomorphic.datasource.DSRequest.decodeUploadedStrings(DSRequest.java:789)
      at com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:676)
      at com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2402)
      at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:328)
      at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:308)
      at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:144)
      at com.isomorphic.servlet.IDACall._processRequest(IDACall.java:114)
      at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
      at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
      at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
      at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
      at com.isomorphic.js.JSSyntaxScannerFilter.doFilter(JSSyntaxScannerFilter.java:242)
      at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
      at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
      at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260)
      at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
      at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
      at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)
      at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
      at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
      at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
      at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
      at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)
      at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870)
      at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
      at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
      at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
      at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685)
      at java.lang.Thread.run(Thread.java:722)
      === 2015-05-10 13:12:23,929 [sor1] DEBUG Velocity - LogSystem has been deprecated. Please use a LogChute implementation.
      === 2015-05-10 13:12:23,931 [sor7] DEBUG PoolableSQLConnectionFactory - Initializing SQL config for 'derivedFromConnection' via JNDI
      === 2015-05-10 13:12:23,929 [sor1] DEBUG Velocity - LogSystem has been deprecated. Please use a LogChute implementation.
      === 2015-05-10 13:12:23,953 [sor7] DEBUG SQLConnectionManager - Connection 23932647 was already closed when we came to free it
      === 2015-05-10 13:12:23,955 [sor7] ERROR IDACall - Top-level servlet error:
      java.sql.SQLException: java.sql.SQLException: javax.naming.NameNotFoundException: Name derivedFromConnection is not bound in this Context
      at com.isomorphic.sql.SQLMetaData.conn(SQLMetaData.java:113)
      at com.isomorphic.sql.SQLMetaData.getMetaData(SQLMetaData.java:132)
      at com.isomorphic.sql.SQLMetaData.getPrimaryKeys(SQLMetaData.java:326)
      at com.isomorphic.sql.SQLDSGenerator.getFieldsFromTable(SQLDSGenerator.java:389)
      at com.isomorphic.sql.SQLDSGenerator.generate(SQLDSGenerator.java:176)
      at com.isomorphic.sql.SQLDataSource.getConfigFromTable(SQLDataSource.java:3851)
      at com.isomorphic.sql.SQLDataSource.fromTable(SQLDataSource.java:3800)
      at com.isomorphic.sql.SQLDataSource.init(SQLDataSource.java:250)
      at com.isomorphic.datasource.DataSource.initialize(DataSource.java:528)
      at com.isomorphic.datasource.BasicDataSource.fromConfig(BasicDataSource.java:177)
      at com.isomorphic.datasource.DataSource.fromConfig(DataSource.java:513)
      at com.isomorphic.datasource.FileSystemDSRepo.loadDS(FileSystemDSRepo.java:110)
      at com.isomorphic.datasource.DataSource.forName(DataSource.java:272)
      at com.isomorphic.datasource.DataSource.forName(DataSource.java:264)
      at com.isomorphic.datasource.DataSource.forName(DataSource.java:253)
      at com.isomorphic.datasource.PoolableDataSourceFactory.makeUnpooledObject(PoolableDataSourceFactory.java:135)
      at com.isomorphic.datasource.PoolableDataSourceFactory.makeObject(PoolableDataSourceFactory.java:151)
      at org.apache.commons.pool.impl.GenericKeyedObjectPool.borrowObject(GenericKeyedObjectPool.java:964)
      at com.isomorphic.pool.ISCKeyedObjectPool.borrowObject(ISCKeyedObjectPool.java:106)
      at com.isomorphic.pool.PoolManager.borrowObject(PoolManager.java:84)
      at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:135)
      at com.isomorphic.datasource.DataSourceManager.getDataSource(DataSourceManager.java:86)
      at com.isomorphic.datasource.DSRequest.getDataSource(DSRequest.java:2207)
      at com.isomorphic.datasource.DSRequest.decodeUploadedStrings(DSRequest.java:789)
      at com.isomorphic.datasource.DSRequest.<init>(DSRequest.java:676)
      at com.isomorphic.rpc.RPCManager.parseRequest(RPCManager.java:2402)
      at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:328)
      at com.isomorphic.rpc.RPCManager.<init>(RPCManager.java:308)
      at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:144)
      at com.isomorphic.servlet.IDACall._processRequest(IDACall.java:114)
      at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
      at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
      at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
      at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
      at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
      at com.isomorphic.js.JSSyntaxScannerFilter.doFilter(JSSyntaxScannerFilter.java:242)
      at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
      at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
      at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:260)
      at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
      at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
      at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210)
      at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
      at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
      at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
      at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
      at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151)
      at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870)
      at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
      at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
      at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
      at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685)
      at java.lang.Thread.run(Thread.java:722)
      === 2015-05-10 13:12:23,959 [sor1] DEBUG Velocity - Default ResourceManager initializing. (class org.apache.velocity.runtime.resource.ResourceManagerIm
      pl)
      === 2015-05-10 13:12:23,970 [sor7] INFO Compression - /isomorphic/IDACall: 4706 -> 1065 bytes
      === 2015-05-10 13:12:23,959 [sor1] DEBUG Velocity - Default ResourceManager initializing. (class org.apache.velocity.runtime.resource.ResourceManagerIm
      pl)
      === 2015-05-10 13:12:24,001 [sor1] DEBUG Velocity - ResourceLoader instantiated: org.apache.velocity.runtime.resource.loader.FileResourceLoader
      === 2015-05-10 13:12:24,001 [sor1] DEBUG Velocity - ResourceLoader instantiated: org.apache.velocity.runtime.resource.loader.FileResourceLoader
      === 2015-05-10 13:12:24,008 [sor1] DEBUG Velocity - FileResourceLoader : initialization starting.
      === 2015-05-10 13:12:24,008 [sor1] DEBUG Velocity - FileResourceLoader : initialization starting.
      === 2015-05-10 13:12:24,011 [sor1] DEBUG Velocity - Do unicode file recognition: false
      === 2015-05-10 13:12:24,011 [sor1] DEBUG Velocity - Do unicode file recognition: false
      === 2015-05-10 13:12:24,017 [sor1] DEBUG Velocity - FileResourceLoader : adding path ''
      === 2015-05-10 13:12:24,017 [sor1] DEBUG Velocity - FileResourceLoader : adding path ''
      === 2015-05-10 13:12:24,024 [sor1] DEBUG Velocity - FileResourceLoader : initialization complete.
      === 2015-05-10 13:12:24,024 [sor1] DEBUG Velocity - FileResourceLoader : initialization complete.
      === 2015-05-10 13:12:24,040 [sor1] DEBUG Velocity - ResourceCache: initialized (class org.apache.velocity.runtime.resource.ResourceCacheImpl) with clas
      s java.util.Collections$SynchronizedMap cache map.
      === 2015-05-10 13:12:24,040 [sor1] DEBUG Velocity - ResourceCache: initialized (class org.apache.velocity.runtime.resource.ResourceCacheImpl) with clas
      s java.util.Collections$SynchronizedMap cache map.
      === 2015-05-10 13:12:24,042 [sor1] DEBUG Velocity - Default ResourceManager initialization complete.
      === 2015-05-10 13:12:24,042 [sor1] DEBUG Velocity - Default ResourceManager initialization complete.
      I restarted MySQL56 without fixing the problem. Also, I note that the PlanetProjectDB is the default database and tests out OK in the Administration Console. That, plus the fact that the Issue, Project and other tables are working fine make it a puzzle why the Task table is messed up. Moreso when the Task table worked fine in other versions.

      Rick
      Last edited by RickBollinger; 10 May 2015, 05:51. Reason: still digging

      Comment


        #4
        Problem fixed.

        It pays to keep looking and trying things. I looked at changes. I looked at differences. The points of control were the fetch call for MyTaskGrid, and the Task.ds.xml. Both had worked earlier. The MyTaskGrid fetch call looked almost identical to the MyIssueGrid call that worked above it. The Task.ds.xml file looked similar to the Issue.ds.xml file.

        But, it wasn't. I couldn't do a diff. But, I could visually inspect. Just turn on the wraparound feature to show all the columns. There it was. Issue.ds.xml had a tableName parameter. Task.ds.xml did not.

        My code had been working by accident. Something had defaulted before. Add it explicitly and the code worked again.

        Lots of fixes to do. At least I can test them now.

        Rick

        Comment

        Working...
        X