14-Dec-06 (Created: 14-Dec-06) | More in 'Howto-Advanced'

DatabaseTM: log file for single select

Thu Dec 14  13:35:35:313 06:tid:27379847:	Info: Calling Initializers
Thu Dec 14  13:35:35:313 06:tid:27379847:	Creating the creator :com.ai.application.defaultpkg.SystemPropertiesInitializer
Thu Dec 14  13:35:35:323 06:tid:27379847:	Info: Placing javax.xml.parsers.DocumentBuilderFactory in System Properties as org.apache.xerces.jaxp.DocumentBuilderFactoryImpl
Thu Dec 14  13:35:35:323 06:tid:27379847:	Info: Placing javax.xml.transform.TransformerFactory in System Properties as org.apache.xalan.processor.TransformerFactoryImpl
Thu Dec 14  13:35:35:323 06:tid:27379847:	Info: Placing javax.xml.parsers.SAXParserFactory in System Properties as org.apache.xerces.jaxp.SAXParserFactoryImpl
Thu Dec 14  13:35:45:969 06:tid:27379847:	Creating the creator :com.ai.db.rel2.DBRequestExecutor3
Thu Dec 14  13:35:45:969 06:tid:27379847:	warn: Could not find classname for request 'request.IProcedureBaseExtender'
Thu Dec 14  13:35:45:969 06:tid:27379847:	Trace:com.ai.db.rel2.DBRequestExecutor3:No default extender specified. Using a default
Thu Dec 14  13:35:45:979 06:tid:27379847:	Creating the creator :com.ai.db.rel2.TransactionManager
Thu Dec 14  13:35:45:979 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:New transaction context is going to be started
Thu Dec 14  13:35:45:979 06:tid:27379847:	Warn:com.ai.db.rel2.TransactionManager:startcontext called when the transaction manager is not open
Thu Dec 14  13:35:45:979 06:tid:27379847:	Warn:com.ai.db.rel2.TransactionManager:manager will be open now
Thu Dec 14  13:35:45:979 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionManager:Opening up a transaction manager for this thread.
Thu Dec 14  13:35:45:989 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionFacilityTL:No previous context. stack is empty
Thu Dec 14  13:35:45:989 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionManager:Requesting a connection from transaction manager
Thu Dec 14  13:35:45:989 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionManager:Obtaining a connection from context:com.ai.db.rel2.TransactionalContext@13582d
Thu Dec 14  13:35:45:989 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalContext:No previous connection for reportsDB
Thu Dec 14  13:35:45:989 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalContext:Requesting a new connection from data source
Thu Dec 14  13:35:45:989 06:tid:27379847:	Creating the creator :com.ai.db.ConnectionPoolConnectionManager1
Thu Dec 14  13:35:45:989 06:tid:27379847:	cp: Requesting a connection from data source reportsDB
Thu Dec 14  13:35:45:999 06:tid:27379847:	cp: Connection pool not found for data source :reportsDB
Thu Dec 14  13:35:46:009 06:tid:27379847:	Creating the creator :com.ai.scheduler.BasicScheduler
Thu Dec 14  13:35:46:019 06:tid:27379847:	sc: Scheduler started at :12/14/06 1:35 PM
Thu Dec 14  13:35:46:029 06:tid:27379847:	cp: Creating a new connection to reportsDB
Thu Dec 14  13:35:46:039 06:tid:27379847:	Registering jdbc driver: sun.jdbc.odbc.JdbcOdbcDriver
Thu Dec 14  13:35:46:129 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalConnection:Set auto commit flag to false. Original is:true
Thu Dec 14  13:35:46:129 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalContext:Saving the connection for future use
Thu Dec 14  13:35:46:129 06:tid:27379847:	Arguments in to the statement are : {ownerUserId=satya}
Thu Dec 14  13:35:46:129 06:tid:27379847:	Creating the creator :com.ai.common.OracleQuoteTranslator
Thu Dec 14  13:35:46:129 06:tid:27379847:	statement to execute : SELECT * FROM filed_items AS fi, reports AS r, folders AS f WHERE 1=1  And fi.item_id=r.report_id And fi.folder_id=f.folder_id and f.public='Y' and f.owner_user_id=null ORDER BY r.last_updated_on DESC
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:I am the transaction owner
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:No exception. Going to commit if not a delayed read
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:The returned object is a delayed read. Commit will happen when the delayed read object releases that connection
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:I am the txn owner. Need to release the txn
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:Returned object is a delayed read
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:Delayed read is active. Transfering context to the object. Not releasing resources
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TMBaseJavaProcedure:Closing context or remove context from the thread
Thu Dec 14  13:35:46:149 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionFacilityTL:stack is empty. setting the current context to null
Thu Dec 14  13:35:46:159 06:tid:27379847:	Walking through the meta data
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:filed_item_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : filed_item_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Returned index for column : 0
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column index: 0
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:folder_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : folder_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Returned index for column : 20
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column index: 20
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:item_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : item_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Returned index for column : 2
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column index: 2
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:item_type
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : item_type
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Returned index for column : 3
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column index: 3
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:report_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : report_id
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Returned index for column : 4
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column index: 4
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:report_short_name
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : report_short_name
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Returned index for column : 5
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column index: 5
Thu Dec 14  13:35:46:159 06:tid:27379847:	Column name:report_long_name
Thu Dec 14  13:35:46:159 06:tid:27379847:	vectorMetadata: Request for column : report_long_name
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 6
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 6
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:report_description
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : report_description
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 7
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 7
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:public
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : public
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 23
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 23
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:url
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : url
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 9
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 9
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:update_url
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : update_url
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 10
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 10
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:fieldValues
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : fieldValues
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 11
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 11
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:whereClause
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : whereClause
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 12
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column index: 12
Thu Dec 14  13:35:46:169 06:tid:27379847:	Column name:report_type_id
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Request for column : report_type_id
Thu Dec 14  13:35:46:169 06:tid:27379847:	vectorMetadata: Returned index for column : 13
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 13
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:report_content_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : report_content_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 14
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 14
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:owner_user_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : owner_user_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 24
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 24
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:created_by
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : created_by
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 16
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 16
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:created_on
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : created_on
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 17
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 17
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:last_updated_by
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : last_updated_by
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 18
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 18
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:last_updated_on
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : last_updated_on
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 19
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 19
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:folder_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : folder_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 20
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 20
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:folder_name
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : folder_name
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 21
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 21
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:parent_folder_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : parent_folder_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 22
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 22
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:public
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : public
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 23
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 23
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column name:owner_user_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Request for column : owner_user_id
Thu Dec 14  13:35:46:179 06:tid:27379847:	vectorMetadata: Returned index for column : 24
Thu Dec 14  13:35:46:179 06:tid:27379847:	Column index: 24
Thu Dec 14  13:35:46:179 06:tid:27379847:	End walking through the metadata
Thu Dec 14  13:35:46:179 06:tid:27379847:	Walking through the rows retrieved
Thu Dec 14  13:35:46:179 06:tid:27379847:	End Walking through the rows retrieved
Thu Dec 14  13:35:46:179 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalContext:Call from the delayed read. Releasing connections
Thu Dec 14  13:35:46:179 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalContext:Commit needed flag is false. No commit will happen
Thu Dec 14  13:35:46:179 06:tid:27379847:	Trace:com.ai.db.rel2.TransactionalConnection:Restoring auto commit flag to its original status:true
Thu Dec 14  13:35:46:179 06:tid:27379847:	cp: Returning connection for pool reportsDB