db-derby-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Tony Brusseau (JIRA)" <j...@apache.org>
Subject [jira] [Comment Edited] (DERBY-6045) in list multi-probe by primary key not chosen on tables with >256 rows
Date Tue, 21 May 2013 16:39:16 GMT

    [ https://issues.apache.org/jira/browse/DERBY-6045?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13663095#comment-13663095 ] 

Tony Brusseau edited comment on DERBY-6045 at 5/21/13 4:38 PM:
---------------------------------------------------------------

Here is the query plan for the disjunctive delete, notice the full table scan on 29K rows even though term, pred and mt all have individual indexes (which I've confirmed):

Tue May 21 11:28:34 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88632), (SESSIONID = 1), DELETE FROM kb.mt_gaf_top_level_term_counts WHERE (term = 1407374883554049) OR (pred = 1407374883554049) OR (mt = 1407374883554049) ******* Delete Cascade ResultSet using row locking:
deferred: false
Rows deleted = 418
Indexes updated = 9
Execute Time = 0
	Project-Restrict ResultSet (1):
	Number of opens = 1
	Rows seen = 418
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 14751.50
		optimizer estimated cost: 12365.71
	Source result set:
		Table Scan ResultSet for MT_GAF_TOP_LEVEL_TERM_COUNTS at read committed isolation level using exclusive row locking chosen by the optimizer
		Number of opens = 1
		Rows seen = 418
		Rows filtered = 0
		Fetch Size = 1
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			next time in milliseconds/row = 0

		scan information:
			Bit set of columns fetched={0, 1, 2, 3, 4}
			Number of columns fetched=5
			Number of pages visited=67
			Number of rows qualified=418
			Number of rows visited=29498
			Scan type=heap
			start position:
				null
			stop position:
				null
			qualifiers:
				Column[1][0] Id: 3
				Operator: =
				Ordered nulls: false
				Unknown return value: false
				Negate comparison result: false
				Column[1][1] Id: 2
				Operator: =
				Ordered nulls: false
				Unknown return value: false
				Negate comparison result: false
				Column[1][2] Id: 1
				Operator: =
				Ordered nulls: false
				Unknown return value: false
				Negate comparison result: false
			optimizer estimated row count: 14751.50
			optimizer estimated cost: 12365.71
Referential Actions On Dependent Tables:
	Delete Cascade ResultSet using row locking:
	deferred: false
	Rows deleted = 860
	Indexes updated = 3
	Execute Time = 0
	Project-Restrict ResultSet (3):
	Number of opens = 1
	Rows seen = 860
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 129341.00
		optimizer estimated cost: 38529.20
	Source result set:
		Index Scan ResultSet for GAF_ARG using index On Foreign Key at serializable isolation level using exclusive table locking chosen by the optimizer
		Number of opens = 1
		Rows seen = 860
		Rows filtered = 0
		Fetch Size = 1
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			next time in milliseconds/row = 0

		scan information:
			Bit set of columns fetched=All
			Number of columns fetched=2
			Number of deleted rows visited=0
			Number of pages visited=837
			Number of rows qualified=860
			Number of rows visited=1278
			Scan type=btree
			Tree height=2
			start position:
				>= on first 1 column(s).
				Ordered null semantics on the following columns: 
			stop position:
				> on first 1 column(s).
				Ordered null semantics on the following columns: 
			qualifiers:
				None
			optimizer estimated row count: 129341.00
			optimizer estimated cost: 38529.20
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88634), (SESSIONID = 1), CALL SYSIBM.SQLTABLES(?,?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88634), (SESSIONID = 1), EXECUTE STATEMENT SYS."getTables" ******* Sort ResultSet:
Number of opens = 1
Rows input = 0
Rows returned = 0
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=0
	Number of rows output=0
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 0.00
	optimizer estimated cost: 6.08
Source result set:
	Project-Restrict ResultSet (19):
	Number of opens = 1
	Rows seen = 0
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 0.00
		optimizer estimated cost: 6.08
	Source result set:
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 48
		Rows seen from the right = 0
		Rows filtered = 0
		Rows returned = 0
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 0.00
			optimizer estimated cost: 6.08
		Left result set:
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 12
			Rows seen from the right = 48
			Rows filtered = 0
			Rows returned = 48
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 1.70
				optimizer estimated cost: 4.99
			Left result set:
				Project-Restrict ResultSet (5):
				Number of opens = 1
				Rows seen = 12
				Rows filtered = 0
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 0.43
					optimizer estimated cost: 4.99
				Source result set:
					Index Row to Base Row ResultSet for SYSSCHEMAS:
					Number of opens = 1
					Rows seen = 12
					Columns accessed from heap = {0, 1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 0.43
						optimizer estimated cost: 4.99
						Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 12
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=12
							Number of rows visited=12
							Scan type=btree
							Tree height=1
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							stop position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							qualifiers:
								None
							optimizer estimated row count: 0.43
							optimizer estimated cost: 4.99

			Right result set:
				Union ResultSet:
				Number of opens = 12
				Rows seen from the left = 36
				Rows seen from the right = 12
				Rows returned = 48
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 1.70
					optimizer estimated cost: 0.00
				Left result set:
					Union ResultSet:
					Number of opens = 12
					Rows seen from the left = 24
					Rows seen from the right = 12
					Rows returned = 36
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 1.28
						optimizer estimated cost: 0.00
					Left result set:
						Union ResultSet:
						Number of opens = 12
						Rows seen from the left = 12
						Rows seen from the right = 12
						Rows returned = 24
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 0.85
							optimizer estimated cost: 0.00
						Left result set:
							Normalize ResultSet:
							Number of opens = 12
							Rows seen = 12
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 0.85
								optimizer estimated cost: 0.00
							Source result set:
								Row ResultSet:
								Number of opens = 12
								Rows returned = 12
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 0.43
									optimizer estimated cost: 0.00


						Right result set:
							Row ResultSet:
							Number of opens = 12
							Rows returned = 12
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 0.43
								optimizer estimated cost: 0.00


					Right result set:
						Normalize ResultSet:
						Number of opens = 12
						Rows seen = 12
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 1.28
							optimizer estimated cost: 0.00
						Source result set:
							Row ResultSet:
							Number of opens = 12
							Rows returned = 12
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 0.43
								optimizer estimated cost: 0.00



				Right result set:
					Normalize ResultSet:
					Number of opens = 12
					Rows seen = 12
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 1.70
						optimizer estimated cost: 0.00
					Source result set:
						Row ResultSet:
						Number of opens = 12
						Rows returned = 12
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 0.43
							optimizer estimated cost: 0.00




		Right result set:
			Project-Restrict ResultSet (18):
			Number of opens = 48
			Rows seen = 0
			Rows filtered = 0
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 0.00
				optimizer estimated cost: 1.08
			Source result set:
				Index Row to Base Row ResultSet for SYSTABLES:
				Number of opens = 48
				Rows seen = 0
				Columns accessed from heap = {1, 2, 3}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 0.00
					optimizer estimated cost: 1.08
					Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 48
					Rows seen = 0
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0

					scan information:
						Bit set of columns fetched=All
						Number of columns fetched=3
						Number of deleted rows visited=0
						Number of pages visited=48
						Number of rows qualified=0
						Number of rows visited=48
						Scan type=btree
						Tree height=1
						start position:
							>= on first 2 column(s).
							Ordered null semantics on the following columns: 
							1 
						stop position:
							>= on first 1 column(s).
							Ordered null semantics on the following columns: 
						qualifiers:
							Column[0][0] Id: 1
							Operator: =
							Ordered nulls: false
							Unknown return value: false
							Negate comparison result: false
						optimizer estimated row count: 0.00
						optimizer estimated cost: 1.08



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88635), (SESSIONID = 1), CALL SYSIBM.SQLPRIMARYKEYS(?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88635), (SESSIONID = 1), EXECUTE STATEMENT SYS."getPrimaryKeys" ******* Sort ResultSet:
Number of opens = 1
Rows input = 1
Rows returned = 1
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=1
	Number of rows output=1
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 47.58
	optimizer estimated cost: 688.05
Source result set:
	Project-Restrict ResultSet (23):
	Number of opens = 1
	Rows seen = 1
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 47.58
		optimizer estimated cost: 688.05
	Source result set:
		User supplied optimizer overrides for join are { joinOrder=FIXED }
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 1
		Rows seen from the right = 1
		Rows filtered = 0
		Rows returned = 1
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 47.58
			optimizer estimated cost: 688.05
		Left result set:
			User supplied optimizer overrides for join are { joinOrder=FIXED }
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 1
			Rows seen from the right = 1
			Rows filtered = 0
			Rows returned = 1
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 4.82
				optimizer estimated cost: 290.29
			Left result set:
				User supplied optimizer overrides for join are { joinOrder=FIXED }
				Nested Loop Exists Join ResultSet:
				Number of opens = 1
				Rows seen from the left = 1
				Rows seen from the right = 1
				Rows filtered = 0
				Rows returned = 1
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 2.34
					optimizer estimated cost: 121.22
				Left result set:
					User supplied optimizer overrides for join are { joinOrder=FIXED }
					Nested Loop Join ResultSet:
					Number of opens = 1
					Rows seen from the left = 1
					Rows seen from the right = 1
					Rows filtered = 0
					Rows returned = 1
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 2.34
						optimizer estimated cost: 112.46
					Left result set:
						User supplied optimizer overrides for join are { joinOrder=FIXED }
						Nested Loop Join ResultSet:
						Number of opens = 1
						Rows seen from the left = 1
						Rows seen from the right = 1
						Rows filtered = 0
						Rows returned = 1
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 2.30
							optimizer estimated cost: 35.38
						Left result set:
							Project-Restrict ResultSet (8):
							Number of opens = 1
							Rows seen = 1
							Rows filtered = 0
							restriction = false
							projection = true
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								restriction time (milliseconds) = 0
								projection time (milliseconds) = 0
								optimizer estimated row count: 2.70
								optimizer estimated cost: 15.68
							Source result set:
								Index Row to Base Row ResultSet for SYSTABLES:
								Number of opens = 1
								Rows seen = 1
								Columns accessed from heap = {0, 1, 3}
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 2.70
									optimizer estimated cost: 15.68
									User supplied optimizer overrides on SYSTABLES are { index=SYSTABLES_INDEX1 }
									Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
									Number of opens = 1
									Rows seen = 1
									Rows filtered = 0
									Fetch Size = 16
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0
										next time in milliseconds/row = 0

									scan information:
										Bit set of columns fetched={0, 2}
										Number of columns fetched=2
										Number of deleted rows visited=0
										Number of pages visited=1
										Number of rows qualified=1
										Number of rows visited=2
										Scan type=btree
										Tree height=1
										start position:
											>= on first 1 column(s).
											Ordered null semantics on the following columns: 
										stop position:
											> on first 1 column(s).
											Ordered null semantics on the following columns: 
										qualifiers:
											None
										optimizer estimated row count: 2.70
										optimizer estimated cost: 15.68

						Right result set:
							Project-Restrict ResultSet (11):
							Number of opens = 1
							Rows seen = 1
							Rows filtered = 0
							restriction = true
							projection = true
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								restriction time (milliseconds) = 0
								projection time (milliseconds) = 0
								optimizer estimated row count: 2.30
								optimizer estimated cost: 19.70
							Source result set:
								Index Row to Base Row ResultSet for SYSSCHEMAS:
								Number of opens = 1
								Rows seen = 1
								Columns accessed from heap = {0, 1}
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 2.30
									optimizer estimated cost: 19.70
									User supplied optimizer overrides on SYSSCHEMAS are { index=SYSSCHEMAS_INDEX1, joinStrategy=NESTEDLOOP }
									Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
									Number of opens = 1
									Rows seen = 1
									Rows filtered = 0
									Fetch Size = 16
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0
										next time in milliseconds/row = 0

									scan information:
										Bit set of columns fetched=All
										Number of columns fetched=2
										Number of deleted rows visited=0
										Number of pages visited=1
										Number of rows qualified=1
										Number of rows visited=2
										Scan type=btree
										Tree height=1
										start position:
											>= on first 1 column(s).
											Ordered null semantics on the following columns: 
										stop position:
											>= on first 1 column(s).
											Ordered null semantics on the following columns: 
										qualifiers:
											None
										optimizer estimated row count: 2.30
										optimizer estimated cost: 19.70


					Right result set:
						Project-Restrict ResultSet (14):
						Number of opens = 1
						Rows seen = 2
						Rows filtered = 1
						restriction = true
						projection = true
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							restriction time (milliseconds) = 0
							projection time (milliseconds) = 0
							optimizer estimated row count: 2.34
							optimizer estimated cost: 77.08
						Source result set:
							Index Row to Base Row ResultSet for SYSCONSTRAINTS:
							Number of opens = 1
							Rows seen = 2
							Columns accessed from heap = {0, 1, 2, 3}
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 2.34
								optimizer estimated cost: 77.08
								User supplied optimizer overrides on SYSCONSTRAINTS are { index=SYSCONSTRAINTS_INDEX3, joinStrategy=NESTEDLOOP }
								Index Scan ResultSet for SYSCONSTRAINTS using index SYSCONSTRAINTS_INDEX3 at read committed isolation level using instantaneous share row locking chosen by the optimizer
								Number of opens = 1
								Rows seen = 2
								Rows filtered = 0
								Fetch Size = 16
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									next time in milliseconds/row = 0

								scan information:
									Bit set of columns fetched=All
									Number of columns fetched=2
									Number of deleted rows visited=0
									Number of pages visited=2
									Number of rows qualified=2
									Number of rows visited=3
									Scan type=btree
									Tree height=2
									start position:
										>= on first 1 column(s).
										Ordered null semantics on the following columns: 
										0 
									stop position:
										> on first 1 column(s).
										Ordered null semantics on the following columns: 
										0 
									qualifiers:
										None
									optimizer estimated row count: 2.34
									optimizer estimated cost: 77.08


				Right result set:
					Index Row to Base Row ResultSet for SYSKEYS:
					Number of opens = 1
					Rows seen = 1
					Columns accessed from heap = {1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 2.34
						optimizer estimated cost: 8.75
						User supplied optimizer overrides on SYSKEYS are { index=SYSKEYS_INDEX1, joinStrategy=NESTEDLOOP }
						Index Scan ResultSet for SYSKEYS using index SYSKEYS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 1
						Rows filtered = 0
						Fetch Size = 1
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=1
							Number of rows visited=1
							Scan type=btree
							Tree height=1
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							stop position:
								> on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							qualifiers:
								None
							optimizer estimated row count: 2.34
							optimizer estimated cost: 8.75


			Right result set:
				Project-Restrict ResultSet (19):
				Number of opens = 1
				Rows seen = 1
				Rows filtered = 0
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 4.82
					optimizer estimated cost: 169.07
				Source result set:
					Index Row to Base Row ResultSet for SYSCONGLOMERATES:
					Number of opens = 1
					Rows seen = 1
					Columns accessed from heap = {1, 5, 7}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 4.82
						optimizer estimated cost: 169.07
						User supplied optimizer overrides on SYSCONGLOMERATES are { index=SYSCONGLOMERATES_INDEX1, joinStrategy=NESTEDLOOP }
						Index Scan ResultSet for SYSCONGLOMERATES using index SYSCONGLOMERATES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 1
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=2
							Number of rows qualified=1
							Number of rows visited=2
							Scan type=btree
							Tree height=2
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							stop position:
								> on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							qualifiers:
								None
							optimizer estimated row count: 4.82
							optimizer estimated cost: 169.07


		Right result set:
			Project-Restrict ResultSet (22):
			Number of opens = 1
			Rows seen = 6
			Rows filtered = 5
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 47.58
				optimizer estimated cost: 397.76
			Source result set:
				Index Row to Base Row ResultSet for SYSCOLUMNS:
				Number of opens = 1
				Rows seen = 6
				Columns accessed from heap = {0, 1, 2}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 47.58
					optimizer estimated cost: 397.76
					User supplied optimizer overrides on SYSCOLUMNS are { index=SYSCOLUMNS_INDEX1, joinStrategy=NESTEDLOOP }
					Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 1
					Rows seen = 6
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						next time in milliseconds/row = 0

					scan information:
						Bit set of columns fetched={0, 2}
						Number of columns fetched=2
						Number of deleted rows visited=0
						Number of pages visited=2
						Number of rows qualified=6
						Number of rows visited=7
						Scan type=btree
						Tree height=2
						start position:
							>= on first 1 column(s).
							Ordered null semantics on the following columns: 
							0 
						stop position:
							> on first 1 column(s).
							Ordered null semantics on the following columns: 
							0 
						qualifiers:
							None
						optimizer estimated row count: 47.58
						optimizer estimated cost: 397.76



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88638), (SESSIONID = 1), CALL SYSIBM.SQLFOREIGNKEYS(?,?,?,?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88638), (SESSIONID = 1), EXECUTE STATEMENT SYS."getImportedKeys" ******* Sort ResultSet:
Number of opens = 1
Rows input = 0
Rows returned = 0
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=0
	Number of rows output=0
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 17909.93
	optimizer estimated cost: 1583882.90
Source result set:
	Project-Restrict ResultSet (41):
	Number of opens = 1
	Rows seen = 0
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 17909.93
		optimizer estimated cost: 1583882.90
	Source result set:
		User supplied optimizer overrides for join are { joinOrder=FIXED }
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 0
		Rows seen from the right = 0
		Rows filtered = 0
		Rows returned = 0
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 17909.93
			optimizer estimated cost: 1583882.90
		Left result set:
			User supplied optimizer overrides for join are { joinOrder=FIXED }
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 0
			Rows seen from the right = 0
			Rows filtered = 0
			Rows returned = 0
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 18151.34
				optimizer estimated cost: 86691.48
			Left result set:
				User supplied optimizer overrides for join are { joinOrder=FIXED }
				Nested Loop Exists Join ResultSet:
				Number of opens = 1
				Rows seen from the left = 0
				Rows seen from the right = 0
				Rows filtered = 0
				Rows returned = 0
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 881.13
					optimizer estimated cost: 23051.65
				Left result set:
					User supplied optimizer overrides for join are { joinOrder=FIXED }
					Nested Loop Exists Join ResultSet:
					Number of opens = 1
					Rows seen from the left = 0
					Rows seen from the right = 0
					Rows filtered = 0
					Rows returned = 0
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 881.13
						optimizer estimated cost: 19756.65
					Left result set:
						User supplied optimizer overrides for join are { joinOrder=FIXED }
						Nested Loop Exists Join ResultSet:
						Number of opens = 1
						Rows seen from the left = 0
						Rows seen from the right = 0
						Rows filtered = 0
						Rows returned = 0
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 881.13
							optimizer estimated cost: 15848.39
						Left result set:
							User supplied optimizer overrides for join are { joinOrder=FIXED }
							Nested Loop Exists Join ResultSet:
							Number of opens = 1
							Rows seen from the left = 0
							Rows seen from the right = 0
							Rows filtered = 0
							Rows returned = 0
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 881.13
								optimizer estimated cost: 12553.39
							Left result set:
								Project-Restrict ResultSet (27):
								Number of opens = 1
								Rows seen = 0
								Rows filtered = 0
								restriction = false
								projection = true
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									restriction time (milliseconds) = 0
									projection time (milliseconds) = 0
									optimizer estimated row count: 881.13
									optimizer estimated cost: 7718.61
								Source result set:
									User supplied optimizer overrides for join are { joinOrder=FIXED }
									Nested Loop Join ResultSet:
									Number of opens = 1
									Rows seen from the left = 0
									Rows seen from the right = 0
									Rows filtered = 0
									Rows returned = 0
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0
										optimizer estimated row count: 881.13
										optimizer estimated cost: 7718.61
									Left result set:
										User supplied optimizer overrides for join are { joinOrder=FIXED }
										Nested Loop Join ResultSet:
										Number of opens = 1
										Rows seen from the left = 0
										Rows seen from the right = 0
										Rows filtered = 0
										Rows returned = 0
											constructor time (milliseconds) = 0
											open time (milliseconds) = 0
											next time (milliseconds) = 0
											close time (milliseconds) = 0
											optimizer estimated row count: 89.30
											optimizer estimated cost: 352.73
										Left result set:
											User supplied optimizer overrides for join are { joinOrder=FIXED }
											Nested Loop Exists Join ResultSet:
											Number of opens = 1
											Rows seen from the left = 2
											Rows seen from the right = 0
											Rows filtered = 0
											Rows returned = 0
												constructor time (milliseconds) = 0
												open time (milliseconds) = 0
												next time (milliseconds) = 0
												close time (milliseconds) = 0
												optimizer estimated row count: 4.34
												optimizer estimated cost: 39.63
											Left result set:
												User supplied optimizer overrides for join are { joinOrder=FIXED }
												Nested Loop Join ResultSet:
												Number of opens = 1
												Rows seen from the left = 1
												Rows seen from the right = 2
												Rows filtered = 0
												Rows returned = 2
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0
													optimizer estimated row count: 4.34
													optimizer estimated cost: 20.86
												Left result set:
													Project-Restrict ResultSet (17):
													Number of opens = 1
													Rows seen = 1
													Rows filtered = 0
													restriction = false
													projection = true
														constructor time (milliseconds) = 0
														open time (milliseconds) = 0
														next time (milliseconds) = 0
														close time (milliseconds) = 0
														restriction time (milliseconds) = 0
														projection time (milliseconds) = 0
														optimizer estimated row count: 0.43
														optimizer estimated cost: 6.58
													Source result set:
														Nested Loop Exists Join ResultSet:
														Number of opens = 1
														Rows seen from the left = 1
														Rows seen from the right = 1
														Rows filtered = 0
														Rows returned = 1
															constructor time (milliseconds) = 0
															open time (milliseconds) = 0
															next time (milliseconds) = 0
															close time (milliseconds) = 0
															optimizer estimated row count: 0.43
															optimizer estimated cost: 6.58
														Left result set:
															Project-Restrict ResultSet (14):
															Number of opens = 1
															Rows seen = 1
															Rows filtered = 0
															restriction = true
															projection = true
																constructor time (milliseconds) = 0
																open time (milliseconds) = 0
																next time (milliseconds) = 0
																close time (milliseconds) = 0
																restriction time (milliseconds) = 0
																projection time (milliseconds) = 0
																optimizer estimated row count: 0.43
																optimizer estimated cost: 4.99
															Source result set:
																Index Row to Base Row ResultSet for SYSSCHEMAS:
																Number of opens = 1
																Rows seen = 1
																Columns accessed from heap = {0}
																	constructor time (milliseconds) = 0
																	open time (milliseconds) = 0
																	next time (milliseconds) = 0
																	close time (milliseconds) = 0
																	optimizer estimated row count: 0.43
																	optimizer estimated cost: 4.99
																	User supplied optimizer overrides on SYSSCHEMAS are { index=SYSSCHEMAS_INDEX1, joinStrategy=NESTEDLOOP }
																	Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
																	Number of opens = 1
																	Rows seen = 1
																	Rows filtered = 0
																	Fetch Size = 1
																		constructor time (milliseconds) = 0
																		open time (milliseconds) = 0
																		next time (milliseconds) = 0
																		close time (milliseconds) = 0
																		next time in milliseconds/row = 0

																	scan information:
																		Bit set of columns fetched=All
																		Number of columns fetched=2
																		Number of deleted rows visited=0
																		Number of pages visited=1
																		Number of rows qualified=1
																		Number of rows visited=2
																		Scan type=btree
																		Tree height=1
																		start position:
																			>= on first 1 column(s).
																			Ordered null semantics on the following columns: 
																		stop position:
																			>= on first 1 column(s).
																			Ordered null semantics on the following columns: 
																		qualifiers:
																			None
																		optimizer estimated row count: 0.43
																		optimizer estimated cost: 4.99

														Right result set:
															Index Row to Base Row ResultSet for SYSTABLES:
															Number of opens = 1
															Rows seen = 1
															Columns accessed from heap = {0}
																constructor time (milliseconds) = 0
																open time (milliseconds) = 0
																next time (milliseconds) = 0
																close time (milliseconds) = 0
																optimizer estimated row count: 0.43
																optimizer estimated cost: 1.59
																User supplied optimizer overrides on SYSTABLES are { index=SYSTABLES_INDEX1 }
																Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
																Number of opens = 1
																Rows seen = 1
																Rows filtered = 0
																Fetch Size = 1
																	constructor time (milliseconds) = 0
																	open time (milliseconds) = 0
																	next time (milliseconds) = 0
																	close time (milliseconds) = 0
																	next time in milliseconds/row = 0

																scan information:
																	Bit set of columns fetched=All
																	Number of columns fetched=3
																	Number of deleted rows visited=0
																	Number of pages visited=1
																	Number of rows qualified=1
																	Number of rows visited=1
																	Scan type=btree
																	Tree height=1
																	start position:
																		>= on first 2 column(s).
																		Ordered null semantics on the following columns: 
																		1 
																	stop position:
																		> on first 2 column(s).
																		Ordered null semantics on the following columns: 
																		1 
																	qualifiers:
																		None
																	optimizer estimated row count: 0.43
																	optimizer estimated cost: 1.59


												Right result set:
													Index Row to Base Row ResultSet for SYSCONSTRAINTS:
													Number of opens = 1
													Rows seen = 2
													Columns accessed from heap = {0, 2}
														constructor time (milliseconds) = 0
														open time (milliseconds) = 0
														next time (milliseconds) = 0
														close time (milliseconds) = 0
														optimizer estimated row count: 4.34
														optimizer estimated cost: 14.27
														User supplied optimizer overrides on SYSCONSTRAINTS are { index=SYSCONSTRAINTS_INDEX3, joinStrategy=NESTEDLOOP }
														Index Scan ResultSet for SYSCONSTRAINTS using index SYSCONSTRAINTS_INDEX3 at read committed isolation level using share row locking chosen by the optimizer
														Number of opens = 1
														Rows seen = 2
														Rows filtered = 0
														Fetch Size = 1
															constructor time (milliseconds) = 0
															open time (milliseconds) = 0
															next time (milliseconds) = 0
															close time (milliseconds) = 0
															next time in milliseconds/row = 0

														scan information:
															Bit set of columns fetched=All
															Number of columns fetched=2
															Number of deleted rows visited=0
															Number of pages visited=2
															Number of rows qualified=2
															Number of rows visited=3
															Scan type=btree
															Tree height=2
															start position:
																>= on first 1 column(s).
																Ordered null semantics on the following columns: 
																0 
															stop position:
																> on first 1 column(s).
																Ordered null semantics on the following columns: 
																0 
															qualifiers:
																None
															optimizer estimated row count: 4.34
															optimizer estimated cost: 14.27


											Right result set:
												Index Row to Base Row ResultSet for SYSFOREIGNKEYS:
												Number of opens = 2
												Rows seen = 0
												Columns accessed from heap = {1, 2, 3}
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0
													optimizer estimated row count: 4.34
													optimizer estimated cost: 18.78
													User supplied optimizer overrides on SYSFOREIGNKEYS are { index=SYSFOREIGNKEYS_INDEX1, joinStrategy=NESTEDLOOP }
													Index Scan ResultSet for SYSFOREIGNKEYS using index SYSFOREIGNKEYS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
													Number of opens = 2
													Rows seen = 0
													Rows filtered = 0
													Fetch Size = 1
														constructor time (milliseconds) = 0
														open time (milliseconds) = 0
														next time (milliseconds) = 0
														close time (milliseconds) = 0

													scan information:
														Bit set of columns fetched=All
														Number of columns fetched=2
														Number of deleted rows visited=0
														Number of pages visited=2
														Number of rows qualified=0
														Number of rows visited=2
														Scan type=btree
														Tree height=1
														start position:
															>= on first 1 column(s).
															Ordered null semantics on the following columns: 
															0 
														stop position:
															> on first 1 column(s).
															Ordered null semantics on the following columns: 
															0 
														qualifiers:
															None
														optimizer estimated row count: 4.34
														optimizer estimated cost: 18.78


										Right result set:
											Index Row to Base Row ResultSet for SYSCONGLOMERATES:
											Number of opens = 0
											Rows seen = 0
											Columns accessed from heap = {5}
												constructor time (milliseconds) = 0
												open time (milliseconds) = 0
												next time (milliseconds) = 0
												close time (milliseconds) = 0
												optimizer estimated row count: 89.30
												optimizer estimated cost: 313.10
												User supplied optimizer overrides on SYSCONGLOMERATES are { index=SYSCONGLOMERATES_INDEX1, joinStrategy=NESTEDLOOP }
												Index Scan ResultSet for SYSCONGLOMERATES using index SYSCONGLOMERATES_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
												Number of opens = 0
												Rows seen = 0
												Rows filtered = 0
												Fetch Size = 1
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0

												scan information:
													start position:
														Positioning information not available because this ResultSet was never opened.
													stop position:
														Positioning information not available because this ResultSet was never opened.
													qualifiers:
														None
													optimizer estimated row count: 89.30
													optimizer estimated cost: 313.10


									Right result set:
										Project-Restrict ResultSet (26):
										Number of opens = 0
										Rows seen = 0
										Rows filtered = 0
										restriction = true
										projection = true
											constructor time (milliseconds) = 0
											open time (milliseconds) = 0
											next time (milliseconds) = 0
											close time (milliseconds) = 0
											restriction time (milliseconds) = 0
											projection time (milliseconds) = 0
											optimizer estimated row count: 881.13
											optimizer estimated cost: 7365.89
										Source result set:
											Index Row to Base Row ResultSet for SYSCOLUMNS:
											Number of opens = 0
											Rows seen = 0
											Columns accessed from heap = {2}
												constructor time (milliseconds) = 0
												open time (milliseconds) = 0
												next time (milliseconds) = 0
												close time (milliseconds) = 0
												optimizer estimated row count: 881.13
												optimizer estimated cost: 7365.89
												User supplied optimizer overrides on SYSCOLUMNS are { index=SYSCOLUMNS_INDEX1, joinStrategy=NESTEDLOOP }
												Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
												Number of opens = 0
												Rows seen = 0
												Rows filtered = 0
												Fetch Size = 1
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0

												scan information:
													start position:
														Positioning information not available because this ResultSet was never opened.
													stop position:
														Positioning information not available because this ResultSet was never opened.
													qualifiers:
														None
													optimizer estimated row count: 881.13
													optimizer estimated cost: 7365.89


							Right result set:
								Index Row to Base Row ResultSet for SYSCONSTRAINTS:
								Number of opens = 0
								Rows seen = 0
								Columns accessed from heap = {1, 2}
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 881.13
									optimizer estimated cost: 4834.78
									User supplied optimizer overrides on SYSCONSTRAINTS are { index=SYSCONSTRAINTS_INDEX1, joinStrategy=NESTEDLOOP }
									Index Scan ResultSet for SYSCONSTRAINTS using index SYSCONSTRAINTS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
									Number of opens = 0
									Rows seen = 0
									Rows filtered = 0
									Fetch Size = 1
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0

									scan information:
										start position:
											Positioning information not available because this ResultSet was never opened.
										stop position:
											Positioning information not available because this ResultSet was never opened.
										qualifiers:
											None
										optimizer estimated row count: 881.13
										optimizer estimated cost: 4834.78


						Right result set:
							Index Row to Base Row ResultSet for SYSTABLES:
							Number of opens = 0
							Rows seen = 0
							Columns accessed from heap = {1, 3}
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 881.13
								optimizer estimated cost: 3295.00
								User supplied optimizer overrides on SYSTABLES are { index=SYSTABLES_INDEX2, joinStrategy=NESTEDLOOP }
								Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX2 at read committed isolation level using share row locking chosen by the optimizer
								Number of opens = 0
								Rows seen = 0
								Rows filtered = 0
								Fetch Size = 1
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0

								scan information:
									start position:
										Positioning information not available because this ResultSet was never opened.
									stop position:
										Positioning information not available because this ResultSet was never opened.
									qualifiers:
										None
									optimizer estimated row count: 881.13
									optimizer estimated cost: 3295.00


					Right result set:
						Index Row to Base Row ResultSet for SYSSCHEMAS:
						Number of opens = 0
						Rows seen = 0
						Columns accessed from heap = {1}
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 881.13
							optimizer estimated cost: 3908.27
							User supplied optimizer overrides on SYSSCHEMAS are { index=SYSSCHEMAS_INDEX2, joinStrategy=NESTEDLOOP }
							Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX2 at read committed isolation level using share row locking chosen by the optimizer
							Number of opens = 0
							Rows seen = 0
							Rows filtered = 0
							Fetch Size = 1
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0

							scan information:
								start position:
									Positioning information not available because this ResultSet was never opened.
								stop position:
									Positioning information not available because this ResultSet was never opened.
								qualifiers:
									None
								optimizer estimated row count: 881.13
								optimizer estimated cost: 3908.27


				Right result set:
					Index Row to Base Row ResultSet for SYSKEYS:
					Number of opens = 0
					Rows seen = 0
					Columns accessed from heap = {1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 881.13
						optimizer estimated cost: 3295.00
						User supplied optimizer overrides on SYSKEYS are { index=SYSKEYS_INDEX1, joinStrategy=NESTEDLOOP }
						Index Scan ResultSet for SYSKEYS using index SYSKEYS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
						Number of opens = 0
						Rows seen = 0
						Rows filtered = 0
						Fetch Size = 1
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0

						scan information:
							start position:
								Positioning information not available because this ResultSet was never opened.
							stop position:
								Positioning information not available because this ResultSet was never opened.
							qualifiers:
								Column[0][0] Id: 0
								Operator: =
								Ordered nulls: false
								Unknown return value: false
								Negate comparison result: false
							optimizer estimated row count: 881.13
							optimizer estimated cost: 3295.00


			Right result set:
				Index Row to Base Row ResultSet for SYSCONGLOMERATES:
				Number of opens = 0
				Rows seen = 0
				Columns accessed from heap = {5, 7}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 18151.34
					optimizer estimated cost: 63639.83
					User supplied optimizer overrides on SYSCONGLOMERATES are { index=SYSCONGLOMERATES_INDEX1, joinStrategy=NESTEDLOOP }
					Index Scan ResultSet for SYSCONGLOMERATES using index SYSCONGLOMERATES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 0
					Rows seen = 0
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0

					scan information:
						start position:
							Positioning information not available because this ResultSet was never opened.
						stop position:
							Positioning information not available because this ResultSet was never opened.
						qualifiers:
							None
						optimizer estimated row count: 18151.34
						optimizer estimated cost: 63639.83


		Right result set:
			Project-Restrict ResultSet (40):
			Number of opens = 0
			Rows seen = 0
			Rows filtered = 0
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 17909.93
				optimizer estimated cost: 1497191.43
			Source result set:
				Index Row to Base Row ResultSet for SYSCOLUMNS:
				Number of opens = 0
				Rows seen = 0
				Columns accessed from heap = {0, 1, 2}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 17909.93
					optimizer estimated cost: 1497191.43
					User supplied optimizer overrides on SYSCOLUMNS are { index=SYSCOLUMNS_INDEX1, joinStrategy=NESTEDLOOP }
					Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 0
					Rows seen = 0
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0

					scan information:
						start position:
							Positioning information not available because this ResultSet was never opened.
						stop position:
							Positioning information not available because this ResultSet was never opened.
						qualifiers:
							None
						optimizer estimated row count: 17909.93
						optimizer estimated cost: 1497191.43



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88640), (SESSIONID = 1), CALL SYSIBM.SQLCOLUMNS(?,?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88640), (SESSIONID = 1), EXECUTE STATEMENT SYS."getColumns" ******* Sort ResultSet:
Number of opens = 1
Rows input = 6
Rows returned = 6
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=6
	Number of rows output=6
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 0.86
	optimizer estimated cost: 10.74
Source result set:
	Project-Restrict ResultSet (12):
	Number of opens = 1
	Rows seen = 6
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 0.86
		optimizer estimated cost: 10.74
	Source result set:
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 1
		Rows seen from the right = 6
		Rows filtered = 0
		Rows returned = 6
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 0.86
			optimizer estimated cost: 10.74
		Left result set:
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 1
			Rows seen from the right = 1
			Rows filtered = 0
			Rows returned = 1
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 0.11
				optimizer estimated cost: 6.42
			Left result set:
				Project-Restrict ResultSet (5):
				Number of opens = 1
				Rows seen = 1
				Rows filtered = 0
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 0.43
					optimizer estimated cost: 4.99
				Source result set:
					Index Row to Base Row ResultSet for SYSSCHEMAS:
					Number of opens = 1
					Rows seen = 1
					Columns accessed from heap = {0, 1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 0.43
						optimizer estimated cost: 4.99
						Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 1
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=1
							Number of rows visited=2
							Scan type=btree
							Tree height=1
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							stop position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							qualifiers:
								None
							optimizer estimated row count: 0.43
							optimizer estimated cost: 4.99

			Right result set:
				Project-Restrict ResultSet (8):
				Number of opens = 1
				Rows seen = 3
				Rows filtered = 2
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 0.11
					optimizer estimated cost: 1.43
				Source result set:
					Index Row to Base Row ResultSet for SYSTABLES:
					Number of opens = 1
					Rows seen = 3
					Columns accessed from heap = {0, 1, 3}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 0.11
						optimizer estimated cost: 1.43
						Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 3
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=3
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=3
							Number of rows visited=4
							Scan type=btree
							Tree height=1
							start position:
								>= on first 2 column(s).
								Ordered null semantics on the following columns: 
								1 
							stop position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							qualifiers:
								Column[0][0] Id: 1
								Operator: =
								Ordered nulls: false
								Unknown return value: false
								Negate comparison result: false
							optimizer estimated row count: 0.11
							optimizer estimated cost: 1.43


		Right result set:
			Project-Restrict ResultSet (11):
			Number of opens = 1
			Rows seen = 6
			Rows filtered = 0
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 0.86
				optimizer estimated cost: 4.32
			Source result set:
				Index Row to Base Row ResultSet for SYSCOLUMNS:
				Number of opens = 1
				Rows seen = 6
				Columns accessed from heap = {0, 1, 2, 3, 4, 7, 8}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 0.86
					optimizer estimated cost: 4.32
					Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 1
					Rows seen = 6
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						next time in milliseconds/row = 0

					scan information:
						Bit set of columns fetched=All
						Number of columns fetched=3
						Number of deleted rows visited=0
						Number of pages visited=2
						Number of rows qualified=6
						Number of rows visited=7
						Scan type=btree
						Tree height=2
						start position:
							>= on first 2 column(s).
							Ordered null semantics on the following columns: 
							0 
						stop position:
							>= on first 2 column(s).
							Ordered null semantics on the following columns: 
							0 
						qualifiers:
							None
						optimizer estimated row count: 0.86
						optimizer estimated cost: 4.32


                
      was (Author: apb):
    Here is the query plan for the disjunctive delete, notice the full table scan on 29K rows even though term, pred and mt all have individual indexes (which I've confirmed):

Tue May 21 11:28:34 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88632), (SESSIONID = 1), DELETE FROM kb.mt_gaf_top_level_term_counts WHERE (term = 1407374883554049) OR (pred = 1407374883554049) OR (mt = 1407374883554049) ******* Delete Cascade ResultSet using row locking:
deferred: false
Rows deleted = 418
Indexes updated = 9
Execute Time = 0
	Project-Restrict ResultSet (1):
	Number of opens = 1
	Rows seen = 418
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 14751.50
		optimizer estimated cost: 12365.71
	Source result set:
		Table Scan ResultSet for MT_GAF_TOP_LEVEL_TERM_COUNTS at read committed isolation level using exclusive row locking chosen by the optimizer
		Number of opens = 1
		Rows seen = 418
		Rows filtered = 0
		Fetch Size = 1
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			next time in milliseconds/row = 0

		scan information:
			Bit set of columns fetched={0, 1, 2, 3, 4}
			Number of columns fetched=5
			Number of pages visited=67
			Number of rows qualified=418
			Number of rows visited=29498
			Scan type=heap
			start position:
				null
			stop position:
				null
			qualifiers:
				Column[1][0] Id: 3
				Operator: =
				Ordered nulls: false
				Unknown return value: false
				Negate comparison result: false
				Column[1][1] Id: 2
				Operator: =
				Ordered nulls: false
				Unknown return value: false
				Negate comparison result: false
				Column[1][2] Id: 1
				Operator: =
				Ordered nulls: false
				Unknown return value: false
				Negate comparison result: false
			optimizer estimated row count: 14751.50
			optimizer estimated cost: 12365.71
Referential Actions On Dependent Tables:
	Delete Cascade ResultSet using row locking:
	deferred: false
	Rows deleted = 860
	Indexes updated = 3
	Execute Time = 0
	Project-Restrict ResultSet (3):
	Number of opens = 1
	Rows seen = 860
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 129341.00
		optimizer estimated cost: 38529.20
	Source result set:
		Index Scan ResultSet for GAF_ARG using index On Foreign Key at serializable isolation level using exclusive table locking chosen by the optimizer
		Number of opens = 1
		Rows seen = 860
		Rows filtered = 0
		Fetch Size = 1
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			next time in milliseconds/row = 0

		scan information:
			Bit set of columns fetched=All
			Number of columns fetched=2
			Number of deleted rows visited=0
			Number of pages visited=837
			Number of rows qualified=860
			Number of rows visited=1278
			Scan type=btree
			Tree height=2
			start position:
				>= on first 1 column(s).
				Ordered null semantics on the following columns: 
			stop position:
				> on first 1 column(s).
				Ordered null semantics on the following columns: 
			qualifiers:
				None
			optimizer estimated row count: 129341.00
			optimizer estimated cost: 38529.20
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88634), (SESSIONID = 1), CALL SYSIBM.SQLTABLES(?,?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88634), (SESSIONID = 1), EXECUTE STATEMENT SYS."getTables" ******* Sort ResultSet:
Number of opens = 1
Rows input = 0
Rows returned = 0
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=0
	Number of rows output=0
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 0.00
	optimizer estimated cost: 6.08
Source result set:
	Project-Restrict ResultSet (19):
	Number of opens = 1
	Rows seen = 0
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 0.00
		optimizer estimated cost: 6.08
	Source result set:
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 48
		Rows seen from the right = 0
		Rows filtered = 0
		Rows returned = 0
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 0.00
			optimizer estimated cost: 6.08
		Left result set:
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 12
			Rows seen from the right = 48
			Rows filtered = 0
			Rows returned = 48
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 1.70
				optimizer estimated cost: 4.99
			Left result set:
				Project-Restrict ResultSet (5):
				Number of opens = 1
				Rows seen = 12
				Rows filtered = 0
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 0.43
					optimizer estimated cost: 4.99
				Source result set:
					Index Row to Base Row ResultSet for SYSSCHEMAS:
					Number of opens = 1
					Rows seen = 12
					Columns accessed from heap = {0, 1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 0.43
						optimizer estimated cost: 4.99
						Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 12
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=12
							Number of rows visited=12
							Scan type=btree
							Tree height=1
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							stop position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							qualifiers:
								None
							optimizer estimated row count: 0.43
							optimizer estimated cost: 4.99

			Right result set:
				Union ResultSet:
				Number of opens = 12
				Rows seen from the left = 36
				Rows seen from the right = 12
				Rows returned = 48
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 1.70
					optimizer estimated cost: 0.00
				Left result set:
					Union ResultSet:
					Number of opens = 12
					Rows seen from the left = 24
					Rows seen from the right = 12
					Rows returned = 36
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 1.28
						optimizer estimated cost: 0.00
					Left result set:
						Union ResultSet:
						Number of opens = 12
						Rows seen from the left = 12
						Rows seen from the right = 12
						Rows returned = 24
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 0.85
							optimizer estimated cost: 0.00
						Left result set:
							Normalize ResultSet:
							Number of opens = 12
							Rows seen = 12
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 0.85
								optimizer estimated cost: 0.00
							Source result set:
								Row ResultSet:
								Number of opens = 12
								Rows returned = 12
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 0.43
									optimizer estimated cost: 0.00


						Right result set:
							Row ResultSet:
							Number of opens = 12
							Rows returned = 12
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 0.43
								optimizer estimated cost: 0.00


					Right result set:
						Normalize ResultSet:
						Number of opens = 12
						Rows seen = 12
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 1.28
							optimizer estimated cost: 0.00
						Source result set:
							Row ResultSet:
							Number of opens = 12
							Rows returned = 12
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 0.43
								optimizer estimated cost: 0.00



				Right result set:
					Normalize ResultSet:
					Number of opens = 12
					Rows seen = 12
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 1.70
						optimizer estimated cost: 0.00
					Source result set:
						Row ResultSet:
						Number of opens = 12
						Rows returned = 12
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 0.43
							optimizer estimated cost: 0.00




		Right result set:
			Project-Restrict ResultSet (18):
			Number of opens = 48
			Rows seen = 0
			Rows filtered = 0
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 0.00
				optimizer estimated cost: 1.08
			Source result set:
				Index Row to Base Row ResultSet for SYSTABLES:
				Number of opens = 48
				Rows seen = 0
				Columns accessed from heap = {1, 2, 3}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 0.00
					optimizer estimated cost: 1.08
					Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 48
					Rows seen = 0
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0

					scan information:
						Bit set of columns fetched=All
						Number of columns fetched=3
						Number of deleted rows visited=0
						Number of pages visited=48
						Number of rows qualified=0
						Number of rows visited=48
						Scan type=btree
						Tree height=1
						start position:
							>= on first 2 column(s).
							Ordered null semantics on the following columns: 
							1 
						stop position:
							>= on first 1 column(s).
							Ordered null semantics on the following columns: 
						qualifiers:
							Column[0][0] Id: 1
							Operator: =
							Ordered nulls: false
							Unknown return value: false
							Negate comparison result: false
						optimizer estimated row count: 0.00
						optimizer estimated cost: 1.08



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88635), (SESSIONID = 1), CALL SYSIBM.SQLPRIMARYKEYS(?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88635), (SESSIONID = 1), EXECUTE STATEMENT SYS."getPrimaryKeys" ******* Sort ResultSet:
Number of opens = 1
Rows input = 1
Rows returned = 1
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=1
	Number of rows output=1
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 47.58
	optimizer estimated cost: 688.05
Source result set:
	Project-Restrict ResultSet (23):
	Number of opens = 1
	Rows seen = 1
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 47.58
		optimizer estimated cost: 688.05
	Source result set:
		User supplied optimizer overrides for join are { joinOrder=FIXED }
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 1
		Rows seen from the right = 1
		Rows filtered = 0
		Rows returned = 1
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 47.58
			optimizer estimated cost: 688.05
		Left result set:
			User supplied optimizer overrides for join are { joinOrder=FIXED }
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 1
			Rows seen from the right = 1
			Rows filtered = 0
			Rows returned = 1
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 4.82
				optimizer estimated cost: 290.29
			Left result set:
				User supplied optimizer overrides for join are { joinOrder=FIXED }
				Nested Loop Exists Join ResultSet:
				Number of opens = 1
				Rows seen from the left = 1
				Rows seen from the right = 1
				Rows filtered = 0
				Rows returned = 1
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 2.34
					optimizer estimated cost: 121.22
				Left result set:
					User supplied optimizer overrides for join are { joinOrder=FIXED }
					Nested Loop Join ResultSet:
					Number of opens = 1
					Rows seen from the left = 1
					Rows seen from the right = 1
					Rows filtered = 0
					Rows returned = 1
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 2.34
						optimizer estimated cost: 112.46
					Left result set:
						User supplied optimizer overrides for join are { joinOrder=FIXED }
						Nested Loop Join ResultSet:
						Number of opens = 1
						Rows seen from the left = 1
						Rows seen from the right = 1
						Rows filtered = 0
						Rows returned = 1
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 2.30
							optimizer estimated cost: 35.38
						Left result set:
							Project-Restrict ResultSet (8):
							Number of opens = 1
							Rows seen = 1
							Rows filtered = 0
							restriction = false
							projection = true
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								restriction time (milliseconds) = 0
								projection time (milliseconds) = 0
								optimizer estimated row count: 2.70
								optimizer estimated cost: 15.68
							Source result set:
								Index Row to Base Row ResultSet for SYSTABLES:
								Number of opens = 1
								Rows seen = 1
								Columns accessed from heap = {0, 1, 3}
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 2.70
									optimizer estimated cost: 15.68
									User supplied optimizer overrides on SYSTABLES are { index=SYSTABLES_INDEX1 }
									Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
									Number of opens = 1
									Rows seen = 1
									Rows filtered = 0
									Fetch Size = 16
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0
										next time in milliseconds/row = 0

									scan information:
										Bit set of columns fetched={0, 2}
										Number of columns fetched=2
										Number of deleted rows visited=0
										Number of pages visited=1
										Number of rows qualified=1
										Number of rows visited=2
										Scan type=btree
										Tree height=1
										start position:
											>= on first 1 column(s).
											Ordered null semantics on the following columns: 
										stop position:
											> on first 1 column(s).
											Ordered null semantics on the following columns: 
										qualifiers:
											None
										optimizer estimated row count: 2.70
										optimizer estimated cost: 15.68

						Right result set:
							Project-Restrict ResultSet (11):
							Number of opens = 1
							Rows seen = 1
							Rows filtered = 0
							restriction = true
							projection = true
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								restriction time (milliseconds) = 0
								projection time (milliseconds) = 0
								optimizer estimated row count: 2.30
								optimizer estimated cost: 19.70
							Source result set:
								Index Row to Base Row ResultSet for SYSSCHEMAS:
								Number of opens = 1
								Rows seen = 1
								Columns accessed from heap = {0, 1}
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 2.30
									optimizer estimated cost: 19.70
									User supplied optimizer overrides on SYSSCHEMAS are { index=SYSSCHEMAS_INDEX1, joinStrategy=NESTEDLOOP }
									Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
									Number of opens = 1
									Rows seen = 1
									Rows filtered = 0
									Fetch Size = 16
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0
										next time in milliseconds/row = 0

									scan information:
										Bit set of columns fetched=All
										Number of columns fetched=2
										Number of deleted rows visited=0
										Number of pages visited=1
										Number of rows qualified=1
										Number of rows visited=2
										Scan type=btree
										Tree height=1
										start position:
											>= on first 1 column(s).
											Ordered null semantics on the following columns: 
										stop position:
											>= on first 1 column(s).
											Ordered null semantics on the following columns: 
										qualifiers:
											None
										optimizer estimated row count: 2.30
										optimizer estimated cost: 19.70


					Right result set:
						Project-Restrict ResultSet (14):
						Number of opens = 1
						Rows seen = 2
						Rows filtered = 1
						restriction = true
						projection = true
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							restriction time (milliseconds) = 0
							projection time (milliseconds) = 0
							optimizer estimated row count: 2.34
							optimizer estimated cost: 77.08
						Source result set:
							Index Row to Base Row ResultSet for SYSCONSTRAINTS:
							Number of opens = 1
							Rows seen = 2
							Columns accessed from heap = {0, 1, 2, 3}
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 2.34
								optimizer estimated cost: 77.08
								User supplied optimizer overrides on SYSCONSTRAINTS are { index=SYSCONSTRAINTS_INDEX3, joinStrategy=NESTEDLOOP }
								Index Scan ResultSet for SYSCONSTRAINTS using index SYSCONSTRAINTS_INDEX3 at read committed isolation level using instantaneous share row locking chosen by the optimizer
								Number of opens = 1
								Rows seen = 2
								Rows filtered = 0
								Fetch Size = 16
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									next time in milliseconds/row = 0

								scan information:
									Bit set of columns fetched=All
									Number of columns fetched=2
									Number of deleted rows visited=0
									Number of pages visited=2
									Number of rows qualified=2
									Number of rows visited=3
									Scan type=btree
									Tree height=2
									start position:
										>= on first 1 column(s).
										Ordered null semantics on the following columns: 
										0 
									stop position:
										> on first 1 column(s).
										Ordered null semantics on the following columns: 
										0 
									qualifiers:
										None
									optimizer estimated row count: 2.34
									optimizer estimated cost: 77.08


				Right result set:
					Index Row to Base Row ResultSet for SYSKEYS:
					Number of opens = 1
					Rows seen = 1
					Columns accessed from heap = {1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 2.34
						optimizer estimated cost: 8.75
						User supplied optimizer overrides on SYSKEYS are { index=SYSKEYS_INDEX1, joinStrategy=NESTEDLOOP }
						Index Scan ResultSet for SYSKEYS using index SYSKEYS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 1
						Rows filtered = 0
						Fetch Size = 1
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=1
							Number of rows visited=1
							Scan type=btree
							Tree height=1
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							stop position:
								> on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							qualifiers:
								None
							optimizer estimated row count: 2.34
							optimizer estimated cost: 8.75


			Right result set:
				Project-Restrict ResultSet (19):
				Number of opens = 1
				Rows seen = 1
				Rows filtered = 0
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 4.82
					optimizer estimated cost: 169.07
				Source result set:
					Index Row to Base Row ResultSet for SYSCONGLOMERATES:
					Number of opens = 1
					Rows seen = 1
					Columns accessed from heap = {1, 5, 7}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 4.82
						optimizer estimated cost: 169.07
						User supplied optimizer overrides on SYSCONGLOMERATES are { index=SYSCONGLOMERATES_INDEX1, joinStrategy=NESTEDLOOP }
						Index Scan ResultSet for SYSCONGLOMERATES using index SYSCONGLOMERATES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 1
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=2
							Number of rows qualified=1
							Number of rows visited=2
							Scan type=btree
							Tree height=2
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							stop position:
								> on first 1 column(s).
								Ordered null semantics on the following columns: 
								0 
							qualifiers:
								None
							optimizer estimated row count: 4.82
							optimizer estimated cost: 169.07


		Right result set:
			Project-Restrict ResultSet (22):
			Number of opens = 1
			Rows seen = 6
			Rows filtered = 5
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 47.58
				optimizer estimated cost: 397.76
			Source result set:
				Index Row to Base Row ResultSet for SYSCOLUMNS:
				Number of opens = 1
				Rows seen = 6
				Columns accessed from heap = {0, 1, 2}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 47.58
					optimizer estimated cost: 397.76
					User supplied optimizer overrides on SYSCOLUMNS are { index=SYSCOLUMNS_INDEX1, joinStrategy=NESTEDLOOP }
					Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 1
					Rows seen = 6
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						next time in milliseconds/row = 0

					scan information:
						Bit set of columns fetched={0, 2}
						Number of columns fetched=2
						Number of deleted rows visited=0
						Number of pages visited=2
						Number of rows qualified=6
						Number of rows visited=7
						Scan type=btree
						Tree height=2
						start position:
							>= on first 1 column(s).
							Ordered null semantics on the following columns: 
							0 
						stop position:
							> on first 1 column(s).
							Ordered null semantics on the following columns: 
							0 
						qualifiers:
							None
						optimizer estimated row count: 47.58
						optimizer estimated cost: 397.76



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88638), (SESSIONID = 1), CALL SYSIBM.SQLFOREIGNKEYS(?,?,?,?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88638), (SESSIONID = 1), EXECUTE STATEMENT SYS."getImportedKeys" ******* Sort ResultSet:
Number of opens = 1
Rows input = 0
Rows returned = 0
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=0
	Number of rows output=0
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 17909.93
	optimizer estimated cost: 1583882.90
Source result set:
	Project-Restrict ResultSet (41):
	Number of opens = 1
	Rows seen = 0
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 17909.93
		optimizer estimated cost: 1583882.90
	Source result set:
		User supplied optimizer overrides for join are { joinOrder=FIXED }
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 0
		Rows seen from the right = 0
		Rows filtered = 0
		Rows returned = 0
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 17909.93
			optimizer estimated cost: 1583882.90
		Left result set:
			User supplied optimizer overrides for join are { joinOrder=FIXED }
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 0
			Rows seen from the right = 0
			Rows filtered = 0
			Rows returned = 0
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 18151.34
				optimizer estimated cost: 86691.48
			Left result set:
				User supplied optimizer overrides for join are { joinOrder=FIXED }
				Nested Loop Exists Join ResultSet:
				Number of opens = 1
				Rows seen from the left = 0
				Rows seen from the right = 0
				Rows filtered = 0
				Rows returned = 0
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 881.13
					optimizer estimated cost: 23051.65
				Left result set:
					User supplied optimizer overrides for join are { joinOrder=FIXED }
					Nested Loop Exists Join ResultSet:
					Number of opens = 1
					Rows seen from the left = 0
					Rows seen from the right = 0
					Rows filtered = 0
					Rows returned = 0
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 881.13
						optimizer estimated cost: 19756.65
					Left result set:
						User supplied optimizer overrides for join are { joinOrder=FIXED }
						Nested Loop Exists Join ResultSet:
						Number of opens = 1
						Rows seen from the left = 0
						Rows seen from the right = 0
						Rows filtered = 0
						Rows returned = 0
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 881.13
							optimizer estimated cost: 15848.39
						Left result set:
							User supplied optimizer overrides for join are { joinOrder=FIXED }
							Nested Loop Exists Join ResultSet:
							Number of opens = 1
							Rows seen from the left = 0
							Rows seen from the right = 0
							Rows filtered = 0
							Rows returned = 0
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 881.13
								optimizer estimated cost: 12553.39
							Left result set:
								Project-Restrict ResultSet (27):
								Number of opens = 1
								Rows seen = 0
								Rows filtered = 0
								restriction = false
								projection = true
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									restriction time (milliseconds) = 0
									projection time (milliseconds) = 0
									optimizer estimated row count: 881.13
									optimizer estimated cost: 7718.61
								Source result set:
									User supplied optimizer overrides for join are { joinOrder=FIXED }
									Nested Loop Join ResultSet:
									Number of opens = 1
									Rows seen from the left = 0
									Rows seen from the right = 0
									Rows filtered = 0
									Rows returned = 0
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0
										optimizer estimated row count: 881.13
										optimizer estimated cost: 7718.61
									Left result set:
										User supplied optimizer overrides for join are { joinOrder=FIXED }
										Nested Loop Join ResultSet:
										Number of opens = 1
										Rows seen from the left = 0
										Rows seen from the right = 0
										Rows filtered = 0
										Rows returned = 0
											constructor time (milliseconds) = 0
											open time (milliseconds) = 0
											next time (milliseconds) = 0
											close time (milliseconds) = 0
											optimizer estimated row count: 89.30
											optimizer estimated cost: 352.73
										Left result set:
											User supplied optimizer overrides for join are { joinOrder=FIXED }
											Nested Loop Exists Join ResultSet:
											Number of opens = 1
											Rows seen from the left = 2
											Rows seen from the right = 0
											Rows filtered = 0
											Rows returned = 0
												constructor time (milliseconds) = 0
												open time (milliseconds) = 0
												next time (milliseconds) = 0
												close time (milliseconds) = 0
												optimizer estimated row count: 4.34
												optimizer estimated cost: 39.63
											Left result set:
												User supplied optimizer overrides for join are { joinOrder=FIXED }
												Nested Loop Join ResultSet:
												Number of opens = 1
												Rows seen from the left = 1
												Rows seen from the right = 2
												Rows filtered = 0
												Rows returned = 2
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0
													optimizer estimated row count: 4.34
													optimizer estimated cost: 20.86
												Left result set:
													Project-Restrict ResultSet (17):
													Number of opens = 1
													Rows seen = 1
													Rows filtered = 0
													restriction = false
													projection = true
														constructor time (milliseconds) = 0
														open time (milliseconds) = 0
														next time (milliseconds) = 0
														close time (milliseconds) = 0
														restriction time (milliseconds) = 0
														projection time (milliseconds) = 0
														optimizer estimated row count: 0.43
														optimizer estimated cost: 6.58
													Source result set:
														Nested Loop Exists Join ResultSet:
														Number of opens = 1
														Rows seen from the left = 1
														Rows seen from the right = 1
														Rows filtered = 0
														Rows returned = 1
															constructor time (milliseconds) = 0
															open time (milliseconds) = 0
															next time (milliseconds) = 0
															close time (milliseconds) = 0
															optimizer estimated row count: 0.43
															optimizer estimated cost: 6.58
														Left result set:
															Project-Restrict ResultSet (14):
															Number of opens = 1
															Rows seen = 1
															Rows filtered = 0
															restriction = true
															projection = true
																constructor time (milliseconds) = 0
																open time (milliseconds) = 0
																next time (milliseconds) = 0
																close time (milliseconds) = 0
																restriction time (milliseconds) = 0
																projection time (milliseconds) = 0
																optimizer estimated row count: 0.43
																optimizer estimated cost: 4.99
															Source result set:
																Index Row to Base Row ResultSet for SYSSCHEMAS:
																Number of opens = 1
																Rows seen = 1
																Columns accessed from heap = {0}
																	constructor time (milliseconds) = 0
																	open time (milliseconds) = 0
																	next time (milliseconds) = 0
																	close time (milliseconds) = 0
																	optimizer estimated row count: 0.43
																	optimizer estimated cost: 4.99
																	User supplied optimizer overrides on SYSSCHEMAS are { index=SYSSCHEMAS_INDEX1, joinStrategy=NESTEDLOOP }
																	Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
																	Number of opens = 1
																	Rows seen = 1
																	Rows filtered = 0
																	Fetch Size = 1
																		constructor time (milliseconds) = 0
																		open time (milliseconds) = 0
																		next time (milliseconds) = 0
																		close time (milliseconds) = 0
																		next time in milliseconds/row = 0

																	scan information:
																		Bit set of columns fetched=All
																		Number of columns fetched=2
																		Number of deleted rows visited=0
																		Number of pages visited=1
																		Number of rows qualified=1
																		Number of rows visited=2
																		Scan type=btree
																		Tree height=1
																		start position:
																			>= on first 1 column(s).
																			Ordered null semantics on the following columns: 
																		stop position:
																			>= on first 1 column(s).
																			Ordered null semantics on the following columns: 
																		qualifiers:
																			None
																		optimizer estimated row count: 0.43
																		optimizer estimated cost: 4.99

														Right result set:
															Index Row to Base Row ResultSet for SYSTABLES:
															Number of opens = 1
															Rows seen = 1
															Columns accessed from heap = {0}
																constructor time (milliseconds) = 0
																open time (milliseconds) = 0
																next time (milliseconds) = 0
																close time (milliseconds) = 0
																optimizer estimated row count: 0.43
																optimizer estimated cost: 1.59
																User supplied optimizer overrides on SYSTABLES are { index=SYSTABLES_INDEX1 }
																Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
																Number of opens = 1
																Rows seen = 1
																Rows filtered = 0
																Fetch Size = 1
																	constructor time (milliseconds) = 0
																	open time (milliseconds) = 0
																	next time (milliseconds) = 0
																	close time (milliseconds) = 0
																	next time in milliseconds/row = 0

																scan information:
																	Bit set of columns fetched=All
																	Number of columns fetched=3
																	Number of deleted rows visited=0
																	Number of pages visited=1
																	Number of rows qualified=1
																	Number of rows visited=1
																	Scan type=btree
																	Tree height=1
																	start position:
																		>= on first 2 column(s).
																		Ordered null semantics on the following columns: 
																		1 
																	stop position:
																		> on first 2 column(s).
																		Ordered null semantics on the following columns: 
																		1 
																	qualifiers:
																		None
																	optimizer estimated row count: 0.43
																	optimizer estimated cost: 1.59


												Right result set:
													Index Row to Base Row ResultSet for SYSCONSTRAINTS:
													Number of opens = 1
													Rows seen = 2
													Columns accessed from heap = {0, 2}
														constructor time (milliseconds) = 0
														open time (milliseconds) = 0
														next time (milliseconds) = 0
														close time (milliseconds) = 0
														optimizer estimated row count: 4.34
														optimizer estimated cost: 14.27
														User supplied optimizer overrides on SYSCONSTRAINTS are { index=SYSCONSTRAINTS_INDEX3, joinStrategy=NESTEDLOOP }
														Index Scan ResultSet for SYSCONSTRAINTS using index SYSCONSTRAINTS_INDEX3 at read committed isolation level using share row locking chosen by the optimizer
														Number of opens = 1
														Rows seen = 2
														Rows filtered = 0
														Fetch Size = 1
															constructor time (milliseconds) = 0
															open time (milliseconds) = 0
															next time (milliseconds) = 0
															close time (milliseconds) = 0
															next time in milliseconds/row = 0

														scan information:
															Bit set of columns fetched=All
															Number of columns fetched=2
															Number of deleted rows visited=0
															Number of pages visited=2
															Number of rows qualified=2
															Number of rows visited=3
															Scan type=btree
															Tree height=2
															start position:
																>= on first 1 column(s).
																Ordered null semantics on the following columns: 
																0 
															stop position:
																> on first 1 column(s).
																Ordered null semantics on the following columns: 
																0 
															qualifiers:
																None
															optimizer estimated row count: 4.34
															optimizer estimated cost: 14.27


											Right result set:
												Index Row to Base Row ResultSet for SYSFOREIGNKEYS:
												Number of opens = 2
												Rows seen = 0
												Columns accessed from heap = {1, 2, 3}
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0
													optimizer estimated row count: 4.34
													optimizer estimated cost: 18.78
													User supplied optimizer overrides on SYSFOREIGNKEYS are { index=SYSFOREIGNKEYS_INDEX1, joinStrategy=NESTEDLOOP }
													Index Scan ResultSet for SYSFOREIGNKEYS using index SYSFOREIGNKEYS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
													Number of opens = 2
													Rows seen = 0
													Rows filtered = 0
													Fetch Size = 1
														constructor time (milliseconds) = 0
														open time (milliseconds) = 0
														next time (milliseconds) = 0
														close time (milliseconds) = 0

													scan information:
														Bit set of columns fetched=All
														Number of columns fetched=2
														Number of deleted rows visited=0
														Number of pages visited=2
														Number of rows qualified=0
														Number of rows visited=2
														Scan type=btree
														Tree height=1
														start position:
															>= on first 1 column(s).
															Ordered null semantics on the following columns: 
															0 
														stop position:
															> on first 1 column(s).
															Ordered null semantics on the following columns: 
															0 
														qualifiers:
															None
														optimizer estimated row count: 4.34
														optimizer estimated cost: 18.78


										Right result set:
											Index Row to Base Row ResultSet for SYSCONGLOMERATES:
											Number of opens = 0
											Rows seen = 0
											Columns accessed from heap = {5}
												constructor time (milliseconds) = 0
												open time (milliseconds) = 0
												next time (milliseconds) = 0
												close time (milliseconds) = 0
												optimizer estimated row count: 89.30
												optimizer estimated cost: 313.10
												User supplied optimizer overrides on SYSCONGLOMERATES are { index=SYSCONGLOMERATES_INDEX1, joinStrategy=NESTEDLOOP }
												Index Scan ResultSet for SYSCONGLOMERATES using index SYSCONGLOMERATES_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
												Number of opens = 0
												Rows seen = 0
												Rows filtered = 0
												Fetch Size = 1
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0

												scan information:
													start position:
														Positioning information not available because this ResultSet was never opened.
													stop position:
														Positioning information not available because this ResultSet was never opened.
													qualifiers:
														None
													optimizer estimated row count: 89.30
													optimizer estimated cost: 313.10


									Right result set:
										Project-Restrict ResultSet (26):
										Number of opens = 0
										Rows seen = 0
										Rows filtered = 0
										restriction = true
										projection = true
											constructor time (milliseconds) = 0
											open time (milliseconds) = 0
											next time (milliseconds) = 0
											close time (milliseconds) = 0
											restriction time (milliseconds) = 0
											projection time (milliseconds) = 0
											optimizer estimated row count: 881.13
											optimizer estimated cost: 7365.89
										Source result set:
											Index Row to Base Row ResultSet for SYSCOLUMNS:
											Number of opens = 0
											Rows seen = 0
											Columns accessed from heap = {2}
												constructor time (milliseconds) = 0
												open time (milliseconds) = 0
												next time (milliseconds) = 0
												close time (milliseconds) = 0
												optimizer estimated row count: 881.13
												optimizer estimated cost: 7365.89
												User supplied optimizer overrides on SYSCOLUMNS are { index=SYSCOLUMNS_INDEX1, joinStrategy=NESTEDLOOP }
												Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
												Number of opens = 0
												Rows seen = 0
												Rows filtered = 0
												Fetch Size = 1
													constructor time (milliseconds) = 0
													open time (milliseconds) = 0
													next time (milliseconds) = 0
													close time (milliseconds) = 0

												scan information:
													start position:
														Positioning information not available because this ResultSet was never opened.
													stop position:
														Positioning information not available because this ResultSet was never opened.
													qualifiers:
														None
													optimizer estimated row count: 881.13
													optimizer estimated cost: 7365.89


							Right result set:
								Index Row to Base Row ResultSet for SYSCONSTRAINTS:
								Number of opens = 0
								Rows seen = 0
								Columns accessed from heap = {1, 2}
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0
									optimizer estimated row count: 881.13
									optimizer estimated cost: 4834.78
									User supplied optimizer overrides on SYSCONSTRAINTS are { index=SYSCONSTRAINTS_INDEX1, joinStrategy=NESTEDLOOP }
									Index Scan ResultSet for SYSCONSTRAINTS using index SYSCONSTRAINTS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
									Number of opens = 0
									Rows seen = 0
									Rows filtered = 0
									Fetch Size = 1
										constructor time (milliseconds) = 0
										open time (milliseconds) = 0
										next time (milliseconds) = 0
										close time (milliseconds) = 0

									scan information:
										start position:
											Positioning information not available because this ResultSet was never opened.
										stop position:
											Positioning information not available because this ResultSet was never opened.
										qualifiers:
											None
										optimizer estimated row count: 881.13
										optimizer estimated cost: 4834.78


						Right result set:
							Index Row to Base Row ResultSet for SYSTABLES:
							Number of opens = 0
							Rows seen = 0
							Columns accessed from heap = {1, 3}
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0
								optimizer estimated row count: 881.13
								optimizer estimated cost: 3295.00
								User supplied optimizer overrides on SYSTABLES are { index=SYSTABLES_INDEX2, joinStrategy=NESTEDLOOP }
								Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX2 at read committed isolation level using share row locking chosen by the optimizer
								Number of opens = 0
								Rows seen = 0
								Rows filtered = 0
								Fetch Size = 1
									constructor time (milliseconds) = 0
									open time (milliseconds) = 0
									next time (milliseconds) = 0
									close time (milliseconds) = 0

								scan information:
									start position:
										Positioning information not available because this ResultSet was never opened.
									stop position:
										Positioning information not available because this ResultSet was never opened.
									qualifiers:
										None
									optimizer estimated row count: 881.13
									optimizer estimated cost: 3295.00


					Right result set:
						Index Row to Base Row ResultSet for SYSSCHEMAS:
						Number of opens = 0
						Rows seen = 0
						Columns accessed from heap = {1}
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							optimizer estimated row count: 881.13
							optimizer estimated cost: 3908.27
							User supplied optimizer overrides on SYSSCHEMAS are { index=SYSSCHEMAS_INDEX2, joinStrategy=NESTEDLOOP }
							Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX2 at read committed isolation level using share row locking chosen by the optimizer
							Number of opens = 0
							Rows seen = 0
							Rows filtered = 0
							Fetch Size = 1
								constructor time (milliseconds) = 0
								open time (milliseconds) = 0
								next time (milliseconds) = 0
								close time (milliseconds) = 0

							scan information:
								start position:
									Positioning information not available because this ResultSet was never opened.
								stop position:
									Positioning information not available because this ResultSet was never opened.
								qualifiers:
									None
								optimizer estimated row count: 881.13
								optimizer estimated cost: 3908.27


				Right result set:
					Index Row to Base Row ResultSet for SYSKEYS:
					Number of opens = 0
					Rows seen = 0
					Columns accessed from heap = {1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 881.13
						optimizer estimated cost: 3295.00
						User supplied optimizer overrides on SYSKEYS are { index=SYSKEYS_INDEX1, joinStrategy=NESTEDLOOP }
						Index Scan ResultSet for SYSKEYS using index SYSKEYS_INDEX1 at read committed isolation level using share row locking chosen by the optimizer
						Number of opens = 0
						Rows seen = 0
						Rows filtered = 0
						Fetch Size = 1
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0

						scan information:
							start position:
								Positioning information not available because this ResultSet was never opened.
							stop position:
								Positioning information not available because this ResultSet was never opened.
							qualifiers:
								Column[0][0] Id: 0
								Operator: =
								Ordered nulls: false
								Unknown return value: false
								Negate comparison result: false
							optimizer estimated row count: 881.13
							optimizer estimated cost: 3295.00


			Right result set:
				Index Row to Base Row ResultSet for SYSCONGLOMERATES:
				Number of opens = 0
				Rows seen = 0
				Columns accessed from heap = {5, 7}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 18151.34
					optimizer estimated cost: 63639.83
					User supplied optimizer overrides on SYSCONGLOMERATES are { index=SYSCONGLOMERATES_INDEX1, joinStrategy=NESTEDLOOP }
					Index Scan ResultSet for SYSCONGLOMERATES using index SYSCONGLOMERATES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 0
					Rows seen = 0
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0

					scan information:
						start position:
							Positioning information not available because this ResultSet was never opened.
						stop position:
							Positioning information not available because this ResultSet was never opened.
						qualifiers:
							None
						optimizer estimated row count: 18151.34
						optimizer estimated cost: 63639.83


		Right result set:
			Project-Restrict ResultSet (40):
			Number of opens = 0
			Rows seen = 0
			Rows filtered = 0
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 17909.93
				optimizer estimated cost: 1497191.43
			Source result set:
				Index Row to Base Row ResultSet for SYSCOLUMNS:
				Number of opens = 0
				Rows seen = 0
				Columns accessed from heap = {0, 1, 2}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 17909.93
					optimizer estimated cost: 1497191.43
					User supplied optimizer overrides on SYSCOLUMNS are { index=SYSCOLUMNS_INDEX1, joinStrategy=NESTEDLOOP }
					Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 0
					Rows seen = 0
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0

					scan information:
						start position:
							Positioning information not available because this ResultSet was never opened.
						stop position:
							Positioning information not available because this ResultSet was never opened.
						qualifiers:
							None
						optimizer estimated row count: 17909.93
						optimizer estimated cost: 1497191.43



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88640), (SESSIONID = 1), CALL SYSIBM.SQLCOLUMNS(?,?,?,?,?) ******* null
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88640), (SESSIONID = 1), EXECUTE STATEMENT SYS."getColumns" ******* Sort ResultSet:
Number of opens = 1
Rows input = 6
Rows returned = 6
Eliminate duplicates = false
In sorted order = false
Sort information: 
	Number of rows input=6
	Number of rows output=6
	Sort type=internal
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	optimizer estimated row count: 0.86
	optimizer estimated cost: 10.74
Source result set:
	Project-Restrict ResultSet (12):
	Number of opens = 1
	Rows seen = 6
	Rows filtered = 0
	restriction = false
	projection = true
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		restriction time (milliseconds) = 0
		projection time (milliseconds) = 0
		optimizer estimated row count: 0.86
		optimizer estimated cost: 10.74
	Source result set:
		Nested Loop Join ResultSet:
		Number of opens = 1
		Rows seen from the left = 1
		Rows seen from the right = 6
		Rows filtered = 0
		Rows returned = 6
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			optimizer estimated row count: 0.86
			optimizer estimated cost: 10.74
		Left result set:
			Nested Loop Join ResultSet:
			Number of opens = 1
			Rows seen from the left = 1
			Rows seen from the right = 1
			Rows filtered = 0
			Rows returned = 1
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				optimizer estimated row count: 0.11
				optimizer estimated cost: 6.42
			Left result set:
				Project-Restrict ResultSet (5):
				Number of opens = 1
				Rows seen = 1
				Rows filtered = 0
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 0.43
					optimizer estimated cost: 4.99
				Source result set:
					Index Row to Base Row ResultSet for SYSSCHEMAS:
					Number of opens = 1
					Rows seen = 1
					Columns accessed from heap = {0, 1}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 0.43
						optimizer estimated cost: 4.99
						Index Scan ResultSet for SYSSCHEMAS using index SYSSCHEMAS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 1
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=2
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=1
							Number of rows visited=2
							Scan type=btree
							Tree height=1
							start position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							stop position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							qualifiers:
								None
							optimizer estimated row count: 0.43
							optimizer estimated cost: 4.99

			Right result set:
				Project-Restrict ResultSet (8):
				Number of opens = 1
				Rows seen = 3
				Rows filtered = 2
				restriction = true
				projection = true
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					restriction time (milliseconds) = 0
					projection time (milliseconds) = 0
					optimizer estimated row count: 0.11
					optimizer estimated cost: 1.43
				Source result set:
					Index Row to Base Row ResultSet for SYSTABLES:
					Number of opens = 1
					Rows seen = 3
					Columns accessed from heap = {0, 1, 3}
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						optimizer estimated row count: 0.11
						optimizer estimated cost: 1.43
						Index Scan ResultSet for SYSTABLES using index SYSTABLES_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
						Number of opens = 1
						Rows seen = 3
						Rows filtered = 0
						Fetch Size = 16
							constructor time (milliseconds) = 0
							open time (milliseconds) = 0
							next time (milliseconds) = 0
							close time (milliseconds) = 0
							next time in milliseconds/row = 0

						scan information:
							Bit set of columns fetched=All
							Number of columns fetched=3
							Number of deleted rows visited=0
							Number of pages visited=1
							Number of rows qualified=3
							Number of rows visited=4
							Scan type=btree
							Tree height=1
							start position:
								>= on first 2 column(s).
								Ordered null semantics on the following columns: 
								1 
							stop position:
								>= on first 1 column(s).
								Ordered null semantics on the following columns: 
							qualifiers:
								Column[0][0] Id: 1
								Operator: =
								Ordered nulls: false
								Unknown return value: false
								Negate comparison result: false
							optimizer estimated row count: 0.11
							optimizer estimated cost: 1.43


		Right result set:
			Project-Restrict ResultSet (11):
			Number of opens = 1
			Rows seen = 6
			Rows filtered = 0
			restriction = true
			projection = true
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				restriction time (milliseconds) = 0
				projection time (milliseconds) = 0
				optimizer estimated row count: 0.86
				optimizer estimated cost: 4.32
			Source result set:
				Index Row to Base Row ResultSet for SYSCOLUMNS:
				Number of opens = 1
				Rows seen = 6
				Columns accessed from heap = {0, 1, 2, 3, 4, 7, 8}
					constructor time (milliseconds) = 0
					open time (milliseconds) = 0
					next time (milliseconds) = 0
					close time (milliseconds) = 0
					optimizer estimated row count: 0.86
					optimizer estimated cost: 4.32
					Index Scan ResultSet for SYSCOLUMNS using index SYSCOLUMNS_INDEX1 at read committed isolation level using instantaneous share row locking chosen by the optimizer
					Number of opens = 1
					Rows seen = 6
					Rows filtered = 0
					Fetch Size = 16
						constructor time (milliseconds) = 0
						open time (milliseconds) = 0
						next time (milliseconds) = 0
						close time (milliseconds) = 0
						next time in milliseconds/row = 0

					scan information:
						Bit set of columns fetched=All
						Number of columns fetched=3
						Number of deleted rows visited=0
						Number of pages visited=2
						Number of rows qualified=6
						Number of rows visited=7
						Scan type=btree
						Tree height=2
						start position:
							>= on first 2 column(s).
							Ordered null semantics on the following columns: 
							0 
						stop position:
							>= on first 2 column(s).
							Ordered null semantics on the following columns: 
							0 
						qualifiers:
							None
						optimizer estimated row count: 0.86
						optimizer estimated cost: 4.32



Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88640), (SESSIONID = 1), select * from KB.MT_GAF_TOP_LEVEL_TERM_COUNTS ******* Table Scan ResultSet for MT_GAF_TOP_LEVEL_TERM_COUNTS at read committed isolation level using instantaneous share row locking chosen by the optimizer
Number of opens = 1
Rows seen = 712
Rows filtered = 0
Fetch Size = 16
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	next time in milliseconds/row = 0

scan information:
	Bit set of columns fetched=All
	Number of columns fetched=6
	Number of pages visited=4
	Number of rows qualified=720
	Number of rows visited=1076
	Scan type=heap
	start position:
		null
	stop position:
		null
	qualifiers:
		None
	optimizer estimated row count: 29503.00
	optimizer estimated cost: 12365.71
Tue May 21 11:29:56 CDT 2013 Thread[DRDAConnThread_3,5,main] (XID = 88641), (SESSIONID = 1), SELECT COUNT(*) FROM (select * from KB.MT_GAF_TOP_LEVEL_TERM_COUNTS) C2668 ******* Project-Restrict ResultSet (4):
Number of opens = 1
Rows seen = 1
Rows filtered = 0
restriction = false
projection = true
	constructor time (milliseconds) = 0
	open time (milliseconds) = 0
	next time (milliseconds) = 0
	close time (milliseconds) = 0
	restriction time (milliseconds) = 0
	projection time (milliseconds) = 0
	optimizer estimated row count: 1.00
	optimizer estimated cost: 12365.71
Source result set:
	Scalar Aggregate ResultSet:
	Number of opens = 1
	Rows input = 29080
		constructor time (milliseconds) = 0
		open time (milliseconds) = 0
		next time (milliseconds) = 0
		close time (milliseconds) = 0
		optimizer estimated row count: 29503.00
		optimizer estimated cost: 12365.71
	Index Key Optimization = false
	Source result set:
		Project-Restrict ResultSet (3):
		Number of opens = 1
		Rows seen = 29080
		Rows filtered = 0
		restriction = false
		projection = true
			constructor time (milliseconds) = 0
			open time (milliseconds) = 0
			next time (milliseconds) = 0
			close time (milliseconds) = 0
			restriction time (milliseconds) = 0
			projection time (milliseconds) = 0
			optimizer estimated row count: 29503.00
			optimizer estimated cost: 12365.71
		Source result set:
			Table Scan ResultSet for MT_GAF_TOP_LEVEL_TERM_COUNTS at read committed isolation level using share row locking chosen by the optimizer
			Number of opens = 1
			Rows seen = 29080
			Rows filtered = 0
			Fetch Size = 1
				constructor time (milliseconds) = 0
				open time (milliseconds) = 0
				next time (milliseconds) = 0
				close time (milliseconds) = 0
				next time in milliseconds/row = 0

			scan information:
				Bit set of columns fetched=All
				Number of columns fetched=6
				Number of pages visited=67
				Number of rows qualified=29080
				Number of rows visited=29498
				Scan type=heap
				start position:
					null
				stop position:
					null
				qualifiers:
					None
				optimizer estimated row count: 29503.00
				optimizer estimated cost: 12365.71

                  
> in list multi-probe by primary key not chosen on tables with >256 rows
> ----------------------------------------------------------------------
>
>                 Key: DERBY-6045
>                 URL: https://issues.apache.org/jira/browse/DERBY-6045
>             Project: Derby
>          Issue Type: Bug
>          Components: SQL
>    Affects Versions: 10.9.1.0, 10.10.1.1
>         Environment: Linux Debian 6.0.5
>            Reporter: Tony Brusseau
>            Assignee: Mamta A. Satoor
>             Fix For: 10.8.3.1, 10.9.2.2, 10.10.1.2, 10.11.0.0
>
>         Attachments: DERBY6045_patch1_diff.txt, DERBY6045_patch2_diff.txt, optimizerTraceOutputFor10_8.txt, optimizerTraceOutputForTrunk.txt, optimizerTraceOutputWithUpdateStatsFor10_8.txt, optimizerTraceOutputWithUpdateStatsForTrunk.txt
>
>
> I have a table with a long integer primary key field and 11 million rows. I seem to be unable to load large chunks of rows via id in a reasonably efficient manner.
>   1. If I do individual lookups via the primary key, then a fast indexed lookup occurs. However, if I do large numbers of such queries, then the time is overwhelmed by round-trip overhead which makes everything incredibly slow.
>   2. If I use a single query with a disjunction of the primary keys of interest,  then a table scan is performed (even if the clause only contains 1-3 items), which walks over 11 million rows...incredibly inefficient.
>   3. If I use an IN clause, then a table scan is performed (even if the clause only contains 1-3 items), which walks over 11 million rows...incredibly inefficient.
> I'm guessing that this might have something to do with the fact that I'm using large integers and really big numbers that don't start anywhere at or about 1 for my keys. Could this possibly be confusing the optimizer?
> Here are the unlimited query plans for the 3 cases that I enumerated:
> *********************************************************************************************
> [EL Fine]: 2013-01-17 11:09:53.384--ServerSession(582235416)--Connection(1430986883)--Thread(Thread["Initial Lisp Listener",5,SubL Thread Group])--SELECT TERM_ID, ARG0, ARG1, ARG2, ARG3, FORMULA_HASH, FORMULA_LENGTH, FORMULA_TYPE, KB_STATUS FROM KB.FORMULA_TERM WHERE (TERM_ID = ?)
> 	bind => [2251799814033500]
> Thu Jan 17 11:09:53 CST 2013 Thread["Initial Lisp Listener",5,SubL Thread Group] (XID = 4711079), (SESSIONID = 3), SELECT TERM_ID, ARG0, ARG1, ARG2, ARG3, FORMULA_HASH, FORMULA_LENGTH, FORMULA_TYPE, KB_STATUS FROM KB.FORMULA_TERM WHERE (TERM_ID = ?) ******* Project-Restrict ResultSet (3):
> Number of opens = 1
> Rows seen = 1
> Rows filtered = 0
> restriction = false
> projection = true
> 	constructor time (milliseconds) = 0
> 	open time (milliseconds) = 0
> 	next time (milliseconds) = 0
> 	close time (milliseconds) = 0
> 	restriction time (milliseconds) = 0
> 	projection time (milliseconds) = 0
> 	optimizer estimated row count: 1.00
> 	optimizer estimated cost: 6.59
> Source result set:
> 	Index Row to Base Row ResultSet for FORMULA_TERM:
> 	Number of opens = 1
> 	Rows seen = 1
> 	Columns accessed from heap = {1, 2, 3, 4, 5, 6, 7, 8}
> 		constructor time (milliseconds) = 0
> 		open time (milliseconds) = 0
> 		next time (milliseconds) = 0
> 		close time (milliseconds) = 0
> 		optimizer estimated row count: 1.00
> 		optimizer estimated cost: 6.59
> 		Index Scan ResultSet for FORMULA_TERM using constraint KB_FORMULA_TERM_TERM_ID_PK at read committed isolation level using share row locking chosen by the optimizer
> 		Number of opens = 1
> 		Rows seen = 1
> 		Rows filtered = 0
> 		Fetch Size = 1
> 			constructor time (milliseconds) = 0
> 			open time (milliseconds) = 0
> 			next time (milliseconds) = 0
> 			close time (milliseconds) = 0
> 			next time in milliseconds/row = 0
> 		scan information:
> 			Bit set of columns fetched=All
> 			Number of columns fetched=2
> 			Number of deleted rows visited=0
> 			Number of pages visited=3
> 			Number of rows qualified=1
> 			Number of rows visited=1
> 			Scan type=btree
> 			Tree height=-1
> 			start position:
> 				>= on first 1 column(s).
> 				Ordered null semantics on the following columns: 
> 			stop position:
> 				> on first 1 column(s).
> 				Ordered null semantics on the following columns: 
> 			qualifiers:
> 				None
> 			optimizer estimated row count: 1.00
> 			optimizer estimated cost: 6.59
> [EL Fine]: 2013-01-17 11:01:00.732--ServerSession(1237006689)--Connection(927179828)--Thread(Thread["Initial Lisp Listener",5,SubL Thread Group])--SELECT TERM_ID, ARG0, ARG1, ARG2, ARG3, FORMULA_HASH, FORMULA_LENGTH, FORMULA_TYPE, KB_STATUS FROM KB.FORMULA_TERM WHERE (((TERM_ID = ?) OR (TERM_ID = ?)) OR (TERM_ID = ?))
> 	bind => [2251799814033500, 2251799814033501, 2251799814033499]
> Thu Jan 17 11:01:10 CST 2013 Thread["Initial Lisp Listener",5,SubL Thread Group] (XID = 4711078), (SESSIONID = 3), SELECT TERM_ID, ARG0, ARG1, ARG2, ARG3, FORMULA_HASH, FORMULA_LENGTH, FORMULA_TYPE, KB_STATUS FROM KB.FORMULA_TERM WHERE (((TERM_ID = ?) OR (TERM_ID = ?)) OR (TERM_ID = ?)) ******* Project-Restrict ResultSet (3):
> Number of opens = 1
> Rows seen = 3
> Rows filtered = 0
> restriction = false
> projection = true
> 	constructor time (milliseconds) = 0
> 	open time (milliseconds) = 0
> 	next time (milliseconds) = 0
> 	close time (milliseconds) = 0
> 	restriction time (milliseconds) = 0
> 	projection time (milliseconds) = 0
> 	optimizer estimated row count: 1176730.30
> 	optimizer estimated cost: 5931065.54
> Source result set:
> 	Project-Restrict ResultSet (2):
> 	Number of opens = 1
> 	Rows seen = 11767298
> 	Rows filtered = 11767295
> 	restriction = true
> 	projection = false
> 		constructor time (milliseconds) = 0
> 		open time (milliseconds) = 0
> 		next time (milliseconds) = 0
> 		close time (milliseconds) = 0
> 		restriction time (milliseconds) = 0
> 		projection time (milliseconds) = 0
> 		optimizer estimated row count: 1176730.30
> 		optimizer estimated cost: 5931065.54
> 	Source result set:
> 		Table Scan ResultSet for FORMULA_TERM at read committed isolation level using instantaneous share row locking chosen by the optimizer
> 		Number of opens = 1
> 		Rows seen = 11767298
> 		Rows filtered = 0
> 		Fetch Size = 16
> 			constructor time (milliseconds) = 0
> 			open time (milliseconds) = 0
> 			next time (milliseconds) = 0
> 			close time (milliseconds) = 0
> 			next time in milliseconds/row = 0
> 		scan information:
> 			Bit set of columns fetched=All
> 			Number of columns fetched=9
> 			Number of pages visited=34358
> 			Number of rows qualified=11767298
> 			Number of rows visited=11767298
> 			Scan type=heap
> 			start position:
> 				null
> 			stop position:
> 				null
> 			qualifiers:
> 				None
> 			optimizer estimated row count: 1176730.30
> 			optimizer estimated cost: 5931065.54
> [EL Fine]: 2013-01-17 11:27:00.627--ServerSession(1237006689)--Connection(1688096771)--Thread(Thread["Initial Lisp Listener",5,SubL Thread Group])--SELECT TERM_ID, ARG0, ARG1, ARG2, ARG3, FORMULA_HASH, FORMULA_LENGTH, FORMULA_TYPE, KB_STATUS FROM KB.FORMULA_TERM WHERE (TERM_ID IN (?,?,?))
> 	bind => [2251799814033500, 2251799814033501, 2251799814033499]
> Thu Jan 17 11:47:26 CST 2013 Thread["Initial Lisp Listener",5,SubL Thread Group] (XID = 4711080), (SESSIONID = 3), SELECT TERM_ID, ARG0, ARG1, ARG2, ARG3, FORMULA_HASH, FORMULA_LENGTH, FORMULA_TYPE, KB_STATUS FROM KB.FORMULA_TERM WHERE (TERM_ID IN (?,?,?)) ******* Project-Restrict ResultSet (3):
> Number of opens = 1
> Rows seen = 3
> Rows filtered = 0
> restriction = false
> projection = true
> 	constructor time (milliseconds) = 0
> 	open time (milliseconds) = 0
> 	next time (milliseconds) = 0
> 	close time (milliseconds) = 0
> 	restriction time (milliseconds) = 0
> 	projection time (milliseconds) = 0
> 	optimizer estimated row count: 1176730.30
> 	optimizer estimated cost: 5931065.54
> Source result set:
> 	Project-Restrict ResultSet (2):
> 	Number of opens = 1
> 	Rows seen = 11767298
> 	Rows filtered = 11767295
> 	restriction = true
> 	projection = false
> 		constructor time (milliseconds) = 0
> 		open time (milliseconds) = 0
> 		next time (milliseconds) = 0
> 		close time (milliseconds) = 0
> 		restriction time (milliseconds) = 0
> 		projection time (milliseconds) = 0
> 		optimizer estimated row count: 1176730.30
> 		optimizer estimated cost: 5931065.54
> 	Source result set:
> 		Table Scan ResultSet for FORMULA_TERM at read committed isolation level using instantaneous share row locking chosen by the optimizer
> 		Number of opens = 1
> 		Rows seen = 11767298
> 		Rows filtered = 0
> 		Fetch Size = 16
> 			constructor time (milliseconds) = 0
> 			open time (milliseconds) = 0
> 			next time (milliseconds) = 0
> 			close time (milliseconds) = 0
> 			next time in milliseconds/row = 0
> 		scan information:
> 			Bit set of columns fetched=All
> 			Number of columns fetched=9
> 			Number of pages visited=34358
> 			Number of rows qualified=11767298
> 			Number of rows visited=11767298
> 			Scan type=heap
> 			start position:
> 				null
> 			stop position:
> 				null
> 			qualifiers:
> 				None
> 			optimizer estimated row count: 1176730.30
> 			optimizer estimated cost: 5931065.54

--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira

Mime
View raw message