Uploaded image for project: 'Teiid'
  1. Teiid
  2. TEIID-6067

failed joining mechanics in salesforce

    XMLWordPrintable

Details

    • Bug
    • Status: Closed (View Workflow)
    • Major
    • Resolution: Cannot Reproduce
    • None
    • None
    • None
    • None
    • Hide

      1. Simple salesforce teiid spring boot project

      Show
      1. Simple salesforce teiid spring boot project

    Description

      Hi! I had hard month with multiple releases but now I started tests with new multijoining mechanics in sf. So, dependency

      <dependency>
      			<groupId>org.teiid</groupId>
      			<artifactId>spring-data-salesforce</artifactId>
      			<version>1.6.0</version>
      		</dependency>
      		<dependency>
      			<groupId>org.teiid</groupId>
      			<artifactId>teiid-spring-boot-starter</artifactId>
      			<version>1.6.0</version>
      		</dependency>
      		<!-- https://mvnrepository.com/artifact/org.teiid.connectors/translator-salesforce -->
      		<dependency>
      			<groupId>org.teiid.connectors</groupId>
      			<artifactId>translator-salesforce</artifactId>
      			<version>16.0.0</version>
      		</dependency>
      

      In service DDL

      CREATE DATABASE db;
      USE DATABASE db;
      CREATE FOREIGN DATA WRAPPER salesforce;
      CREATE FOREIGN DATA WRAPPER mysalesforce type salesforce OPTIONS (supportsOrderBy true, maxFromGroups 10);
      CREATE SERVER salesforce FOREIGN DATA WRAPPER mysalesforce OPTIONS ("resource-name" 'salesforce');
      CREATE SCHEMA salesforce SERVER salesforce;
      IMPORT FROM SERVER salesforce INTO salesforce OPTIONS("importer.useFullSchemaName" 'false', "importer.NormalizeNames" 'false', "importer.modelAuditFields" 'true', "importer.importStatstics" 'true');
      

      tested SQL request

      SELECT 
          Contact.Id,
          Account.name,
          User.Name,
          Profile.Name
      FROM Contact
          LEFT OUTER JOIN
          /*+ MAKEDEP */
          Account ON Account.Id = Contact.AccountId
          LEFT OUTER JOIN
          /*+ MAKEDEP */
          \"User\" ON \"User\".id = Contact.CreatedById
          LEFT OUTER JOIN
          /*+ MAKEDEP */
          Profile ON Profile.id = User.ProfileId
      limit 1
      

      First I got wrong execution plan with multiple requests to sf

      [2021-01-20 13:35:19,485 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG [Central-Executor-3]: 	START USER COMMAND:	startTime=2021-01-20 13:35:19.484	requestID=KIiWZobV/BsT.0	txID=null	sessionID=KIiWZobV/BsT	applicationName=JDBC	principal=anonymous	vdbName=db	vdbVersion=1	sql=SELECT Contact.Id, Account.name, User.Name, Profile.Name FROM Contact LEFT OUTER JOIN /*+ MAKEDEP */ Account ON Account.Id = Contact.AccountId LEFT OUTER JOIN /*+ MAKEDEP */ "User" ON "User".id = Contact.CreatedById LEFT OUTER JOIN /*+ MAKEDEP */ Profile ON Profile.id = User.ProfileId limit 1
      [2021-01-20 13:35:19,485 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: Request Thread KIiWZobV/BsT.0 with state NEW
      [2021-01-20 13:35:19,485 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: KIiWZobV/BsT.0 Command has no cache hint and result set cache mode is not on.
      [2021-01-20 13:35:19,485 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: KIiWZobV/BsT.0 executing prepared SELECT Contact.Id, Account.name, User.Name, Profile.Name FROM Contact LEFT OUTER JOIN /*+ MAKEDEP */ Account ON Account.Id = Contact.AccountId LEFT OUTER JOIN /*+ MAKEDEP */ "User" ON "User".id = Contact.CreatedById LEFT OUTER JOIN /*+ MAKEDEP */ Profile ON Profile.id = User.ProfileId limit 1
      [2021-01-20 13:35:19,521 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PLANNER [Central-Executor-3]: [LOW [Relational Planner] MAKE_DEP hint detected - marking as dependent side of join Access(groups=[salesforce."User", salesforce.Profile], props={MAKE_DEP=, SOURCE_HINT=null, MODEL_ID=Schema name=salesforce, nameInSource=null, uuid=tid:6b7918e394e2-66cf095f-00000000, OUTPUT_COLS=[salesforce."User".Id, salesforce."User".Name, salesforce.Profile.Name], APPROXIMATE_OUTPUT_COLUMNS=3, CONFORMED_SOURCES=null}, LOW [Relational Planner] MAKE_DEP hint detected - marking as dependent side of join Access(groups=[salesforce."User", salesforce.Profile], props={MAKE_DEP=, SOURCE_HINT=null, MODEL_ID=Schema name=salesforce, nameInSource=null, uuid=tid:6b7918e394e2-66cf095f-00000000, OUTPUT_COLS=[salesforce."User".Id, salesforce."User".Name, salesforce.Profile.Name], APPROXIMATE_OUTPUT_COLUMNS=3, CONFORMED_SOURCES=null}]
      [2021-01-20 13:35:19,521 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: ProcessTree for KIiWZobV/BsT.0 ProjectNode(0) output=[salesforce.Contact.Id, salesforce.Account.Name, salesforce."User".Name, salesforce.Profile.Name] [salesforce.Contact.Id, salesforce.Account.Name, salesforce."User".Name, salesforce.Profile.Name]
        LimitNode(1) output=[salesforce.Contact.Id, salesforce.Account.Name, salesforce."User".Name, salesforce.Profile.Name] limit 1
          JoinNode(2) [Dependent] [ENHANCED SORT JOIN (SORT/SORT)] [LEFT OUTER JOIN] criteria=[salesforce.Contact.CreatedById=salesforce."User".Id] output=[salesforce.Contact.Id, salesforce.Account.Name, salesforce."User".Name, salesforce.Profile.Name]
            AccessNode(3) output=[salesforce.Contact.CreatedById, salesforce.Contact.Id, salesforce.Account.Name] SELECT g_0.CreatedById, g_0.Id, g_1.Name FROM salesforce.Contact AS g_0 LEFT OUTER JOIN salesforce.Account AS g_1 ON g_1.Id = g_0.AccountId LIMIT 1
            DependentAccessNode(4) output=[salesforce."User".Id, salesforce."User".Name, salesforce.Profile.Name] SELECT g_0.Id, g_0.Name, g_1.Name FROM salesforce."User" AS g_0 LEFT OUTER JOIN salesforce.Profile AS g_1 ON g_1.Id = g_0.ProfileId WHERE g_0.Id IN (<dependent values>)
      

      And when this plan started to execution I got exception

      [2021-01-20 13:35:19,522 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.TXN_LOG [Central-Executor-3]: before getOrCreateTransactionContext:org.teiid.dqp.internal.process.TransactionServerImpl@7c47d039(KIiWZobV/BsT)
      [2021-01-20 13:35:19,522 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.TXN_LOG [Central-Executor-3]: after getOrCreateTransactionContext : KIiWZobV/BsT NONE ID:NONE
      [2021-01-20 13:35:19,523 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: Creating TupleBuffer: 2 [salesforce.Contact.Id, salesforce.Account.Name, salesforce."User".Name, salesforce.Profile.Name] [class java.lang.String, class java.lang.String, class java.lang.String, class java.lang.String] batch size 256 of type PROCESSOR
      [2021-01-20 13:35:19,526 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: KIiWZobV/BsT.0.3.0 Create State
      [2021-01-20 13:35:19,551 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: Creating TupleBuffer: 3 [salesforce.Contact.CreatedById, salesforce.Contact.Id, salesforce.Account.Name] [class java.lang.String, class java.lang.String, class java.lang.String] batch size 512 of type PROCESSOR
      [2021-01-20 13:35:19,552 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Worker0_QueryProcessorQueue0]: Running task for parent thread Central-Executor-3
      [2021-01-20 13:35:19,552 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: KIiWZobV/BsT.0.3.0 Blocking on source query KIiWZobV/BsT.0.3.0
      [2021-01-20 13:35:19,552 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: KIiWZobV/BsT.0 Blocking on source request(s).
      [2021-01-20 13:35:19,552 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: Request Thread KIiWZobV/BsT.0 - processor blocked
      [2021-01-20 13:35:19,552 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: KIiWZobV/BsT.0.3.0 Processing NEW request: SELECT g_0.CreatedById, g_0.Id, g_1.Name FROM salesforce.Contact AS g_0 LEFT OUTER JOIN salesforce.Account AS g_1 ON g_1.Id = g_0.AccountId LIMIT 1
      [2021-01-20 13:35:19,558 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: KIiWZobV/BsT.0.3.0 Obtained execution
      [2021-01-20 13:35:19,559 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG.SOURCE [Worker0_QueryProcessorQueue0]: 	START DATA SRC COMMAND:	startTime=2021-01-20 13:35:19.559	requestID=KIiWZobV/BsT.0	sourceCommandID=3	executionID=0	txID=null	modelName=salesforce	translatorName=mysalesforce	sessionID=KIiWZobV/BsT	principal=anonymous	sql=SELECT g_0.CreatedById, g_0.Id, g_1.Name FROM salesforce.Contact AS g_0 LEFT OUTER JOIN salesforce.Account AS g_1 ON g_1.Id = g_0.AccountId LIMIT 1
      [2021-01-20 13:35:19,562 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: salesforce.KIiWZobV/BsT.KIiWZobV/BsT.0.3:  Incoming Query: SELECT g_0.CreatedById, g_0.Id, g_1.Name FROM Contact AS g_0 LEFT OUTER JOIN Account AS g_1 ON g_1.Id = g_0.AccountId LIMIT 1
      [2021-01-20 13:35:19,564 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: salesforce.KIiWZobV/BsT.KIiWZobV/BsT.0.3:  Executing Query: SELECT CreatedById, Id, Name FROM Contact LIMIT 1
      [2021-01-20 13:35:19,564 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG.SOURCE [Worker0_QueryProcessorQueue0]: 	SOURCE SRC COMMAND:	endTime=2021-01-20 13:35:19.564	requestID=KIiWZobV/BsT.0	sourceCommandID=3	executionID=0	txID=null	modelName=salesforce	translatorName=mysalesforce	sessionID=KIiWZobV/BsT	principal=anonymous	sourceCommand=[SELECT CreatedById, Id, Name FROM Contact LIMIT 1]
      [2021-01-20 13:35:27,718 CET] INFO  [salesforce-failover-facade,c898e417b88133e6,3d17662950a250fb,false] [i3draven-W250ENQ-W270ENQ] com.test.dao.HostNameResolver [Central-Executor-5]: TIMER: DNS lookup time 8262ms test.salesforce.com
      [2021-01-20 13:35:28,299 CET] INFO  [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] com.test.components.sf.ReusableSalesforceConnection [Worker0_QueryProcessorQueue0]: TIMER: pure sf request time 8734ms
      [2021-01-20 13:35:28,299 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: KIiWZobV/BsT.0.3.0 Executed command
      [2021-01-20 13:35:28,299 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: KIiWZobV/BsT.0.3.0 Processing MORE request
      [2021-01-20 13:35:28,299 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: KIiWZobV/BsT.0.3.0 Getting results from connector
      [2021-01-20 13:35:28,300 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: SalesForce Object Name = Contact
      [2021-01-20 13:35:28,300 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: FieldCount = 5
      [2021-01-20 13:35:28,300 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: Field # 0 is type
      [2021-01-20 13:35:28,300 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: Field # 1 is Id
      [2021-01-20 13:35:28,300 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: Field # 2 is CreatedById
      [2021-01-20 13:35:28,300 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: Field # 3 is Id
      [2021-01-20 13:35:28,301 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: Field # 4 is Name
      [2021-01-20 13:35:28,301 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue0]: KIiWZobV/BsT.0.3.0 Obtained last batch, total row count: 1
      [2021-01-20 13:35:28,302 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: Request Thread KIiWZobV/BsT.0 with state PROCESSING
      [2021-01-20 13:35:28,302 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: KIiWZobV/BsT.0.3.0 Remove State
      [2021-01-20 13:35:28,303 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: KIiWZobV/BsT.0.3.0 Processing Close : SELECT g_0.CreatedById, g_0.Id, g_1.Name FROM salesforce.Contact AS g_0 LEFT OUTER JOIN salesforce.Account AS g_1 ON g_1.Id = g_0.AccountId LIMIT 1
      [2021-01-20 13:35:28,303 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: SynchQueryExecution.close() called
      [2021-01-20 13:35:28,303 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: KIiWZobV/BsT.0.3.0 Closed execution
      [2021-01-20 13:35:28,304 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: KIiWZobV/BsT.0.3.0 Closed connection
      [2021-01-20 13:35:28,304 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG.SOURCE [Central-Executor-3]: 	END SRC COMMAND:	endTime=2021-01-20 13:35:28.304	requestID=KIiWZobV/BsT.0	sourceCommandID=3	executionID=0	txID=null	modelName=salesforce	translatorName=mysalesforce	sessionID=KIiWZobV/BsT	principal=anonymous	finalRowCount=1	cpuTime(ns)=61286814
      [2021-01-20 13:35:28,311 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: Creating TupleBuffer: 4 [salesforce.Contact.CreatedById, salesforce.Contact.Id, salesforce.Account.Name] [class java.lang.String, class java.lang.String, class java.lang.String] batch size 512 of type PROCESSOR
      [2021-01-20 13:35:28,311 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: Created intermediate sort buffer 4
      [2021-01-20 13:35:28,311 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Central-Executor-3]: KIiWZobV/BsT.0.4.1 Create State
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Worker0_QueryProcessorQueue1]: Running task for parent thread Central-Executor-3
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue1]: KIiWZobV/BsT.0.4.1 Processing NEW request: SELECT g_0.Id, g_0.Name, g_1.Name FROM salesforce."User" AS g_0 LEFT OUTER JOIN salesforce.Profile AS g_1 ON g_1.Id = g_0.ProfileId WHERE g_0.Id = '00532000005NOH7AAO'
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: Creating TupleBuffer: 5 [salesforce."User".Id, salesforce."User".Name, salesforce.Profile.Name] [class java.lang.String, class java.lang.String, class java.lang.String] batch size 512 of type PROCESSOR
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue1]: KIiWZobV/BsT.0.4.1 Obtained execution
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: KIiWZobV/BsT.0.4.1 Blocking on source query KIiWZobV/BsT.0.4.1
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: KIiWZobV/BsT.0 Blocking on source request(s).
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG.SOURCE [Worker0_QueryProcessorQueue1]: 	START DATA SRC COMMAND:	startTime=2021-01-20 13:35:28.312	requestID=KIiWZobV/BsT.0	sourceCommandID=4	executionID=1	txID=null	modelName=salesforce	translatorName=mysalesforce	sessionID=KIiWZobV/BsT	principal=anonymous	sql=SELECT g_0.Id, g_0.Name, g_1.Name FROM salesforce."User" AS g_0 LEFT OUTER JOIN salesforce.Profile AS g_1 ON g_1.Id = g_0.ProfileId WHERE g_0.Id = '00532000005NOH7AAO'
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: KIiWZobV/BsT.0.4.1 Blocking on source query KIiWZobV/BsT.0.4.1
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.BUFFER_MGR [Central-Executor-3]: KIiWZobV/BsT.0 Blocking on source request(s).
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,c898e417b88133e6,25a136bb95a6dce1,false] [i3draven-W250ENQ-W270ENQ] org.teiid.PROCESSOR [Central-Executor-3]: Request Thread KIiWZobV/BsT.0 - processor blocked
      [2021-01-20 13:35:28,312 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue1]: salesforce.KIiWZobV/BsT.KIiWZobV/BsT.0.4:  Incoming Query: SELECT g_0.Id, g_0.Name, g_1.Name FROM User AS g_0 LEFT OUTER JOIN Profile AS g_1 ON g_1.Id = g_0.ProfileId WHERE g_0.Id = '00532000005NOH7AAO'
      [2021-01-20 13:35:28,313 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue1]: salesforce.KIiWZobV/BsT.KIiWZobV/BsT.0.4:  Executing Query: SELECT Id, Name, Name FROM User WHERE Id = '00532000005NOH7AAO'
      [2021-01-20 13:35:28,314 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG.SOURCE [Worker0_QueryProcessorQueue1]: 	SOURCE SRC COMMAND:	endTime=2021-01-20 13:35:28.314	requestID=KIiWZobV/BsT.0	sourceCommandID=4	executionID=1	txID=null	modelName=salesforce	translatorName=mysalesforce	sessionID=KIiWZobV/BsT	principal=anonymous	sourceCommand=[SELECT Id, Name, Name FROM User WHERE Id = '00532000005NOH7AAO']
      [2021-01-20 13:35:28,478 CET] INFO  [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] com.test.components.sf.ReusableSalesforceConnection [Worker0_QueryProcessorQueue1]: TIMER: pure sf request time 164ms
      [2021-01-20 13:35:28,478 CET] DEBUG [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.COMMAND_LOG.SOURCE [Worker0_QueryProcessorQueue1]: 	ERROR SRC COMMAND:	endTime=2021-01-20 13:35:28.478	requestID=KIiWZobV/BsT.0	sourceCommandID=4	executionID=1	txID=null	modelName=salesforce	translatorName=mysalesforce	sessionID=KIiWZobV/BsT	principal=anonymous
      [2021-01-20 13:35:28,479 CET] WARN  [salesforce-failover-facade,,,] [i3draven-W250ENQ-W270ENQ] org.teiid.CONNECTOR [Worker0_QueryProcessorQueue1]: Connector worker process failed for atomic-request=KIiWZobV/BsT.0.4.1
      org.teiid.translator.TranslatorException: class com.sforce.soap.partner.fault.InvalidFieldFault
      	at org.teiid.salesforce.BaseSalesforceConnection.query(BaseSalesforceConnection.java:223)
      	at com.test.components.sf.ReusableSalesforceConnection.query(ReusableSalesforceConnection.java:119)
      	at org.teiid.translator.salesforce.execution.QueryExecutionImpl.execute(QueryExecutionImpl.java:273)
      	at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:405)
      	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
      	at org.teiid.dqp.internal.datamgr.ConnectorManager$1.invoke(ConnectorManager.java:228)
      	at com.sun.proxy.$Proxy228.execute(Unknown Source)
      	at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:302)
      	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:108)
      	at org.teiid.dqp.internal.process.DataTierTupleSource$1.call(DataTierTupleSource.java:104)
      	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
      	at org.teiid.dqp.internal.process.FutureWork.run(FutureWork.java:59)
      	at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:283)
      	at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:124)
      	at org.teiid.dqp.internal.process.ThreadReuseExecutor$2.run(ThreadReuseExecutor.java:212)
      	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
      	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
      	at java.base/java.lang.Thread.run(Thread.java:834)
      Caused by: com.sforce.soap.partner.fault.InvalidFieldFault: null
      	at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
      	at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
      	at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
      	at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:490)
      	at java.base/java.lang.Class.newInstance(Class.java:584)
      	at com.sforce.ws.bind.TypeMapper.readSingle(TypeMapper.java:673)
      	at com.sforce.ws.bind.TypeMapper.readObject(TypeMapper.java:556)
      	at com.sforce.ws.transport.SoapConnection.parseDetail(SoapConnection.java:236)
      	at com.sforce.ws.transport.SoapConnection.createException(SoapConnection.java:210)
      	at com.sforce.ws.transport.SoapConnection.receive(SoapConnection.java:156)
      	at com.sforce.ws.transport.SoapConnection.send(SoapConnection.java:99)
      	at com.sforce.soap.partner.PartnerConnection.query(PartnerConnection.java:1197)
      	at org.teiid.salesforce.BaseSalesforceConnection.query(BaseSalesforceConnection.java:220)
      	... 20 common frames omitted
      

      Attachments

        Issue Links

          Activity

            People

              rhn-engineering-shawkins Steven Hawkins
              3draven Renat Eskenin (Inactive)
              Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved: