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

failed joining mechanics in salesforce

    XMLWordPrintable

Details

    • Bug
    • Resolution: Cannot Reproduce
    • Major
    • None
    • None
    • None
    • None
    • Hide

      1. Simple salesforce teiid spring boot project

      Show
      1. Simple salesforce teiid spring boot project
    • Undefined

    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

          is related to

          Enhancement - An enhancement to or refactoring of existing functionality that is not configurable by an end user (typically a change made by an internal team that affects users) TEIID-5861 Salesforce translator should support nested joins

          • Major - To be worked after higher priority work items are addressed. When the severity is high and the effort to change it is low to moderate, this term is chosen. Issues in this category likely have an existing workaround but implementation or execution may be non-trivial.
          • Resolved

          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: