#288 WebLogic doesn't enroll Conn requested before TX in the TX

[Miro Halas]

If a connection is requested before transaction
begins and this connection is used withing the
transaction, in WebLogic 9.1 it will not be enrolled
in the transaction and it may not be ever commited
(unless by accident if it is reused later)

The WebLogic 9.1 was configured as described in our
documentation. The test was performed using
instrumented jTDS 1.2 driver with MS SQL Server 2005
SP1.

The core-app.war was deployed. All test cases in
TransactionTest were commented out except
testCommitAfterUpdateDelete.

Here is the full analysis

This defect is detected by executing only the
TransactionTests - testCommitAfterUpdateDelete with
all the other test cases commented out.

Here is full log

.
.
JAVA Memory arguments: -Xms256m -Xmx512m -
XX:CompileThreshold=8000 -XX:PermSize=48m -
XX:MaxPermSize=128m
.
WLS Start Mode=Development
.
CLASSPATH=;C:\bea\patch_weblogic910
\profiles\default\sys_manifest_classpath\weblogic_patc
h.jar;C:\bea\JDK150~1\lib\tools.jar;C:\bea\WEBLOG~1
\server\lib\weblogic_sp.jar

;C:\bea\WEBLOG~1\server
\lib\weblogic.jar;C:\bea\WEBLOG~1
\server\lib\webservices.jar;;C:\bea\WEBLOG~1
\common\eval\pointbase\lib\pbclient51.jar;C:\bea\WEBLO
G~1\server\lib\xqrl.jar;;C:\bea\WEBL

OG~1\integration\lib\ut
il.jar;C:\bea\weblogic91\server\ext\jdbc\jtds.jar;
.
PATH=C:\bea\patch_weblogic910
\profiles\default\native;C:\bea\WEBLOG~1
\server\native\win\32;C:\bea\WEBLOG~1
\server\bin;C:\bea\JDK150~1\jre\bin;C:\bea\JDK150~1
\bin;C:\WI

NNT\system32;C:\WINNT;C
:\WINNT\System32\Wbem;C:\Lotus\Notes;c:\Program
Files\ibm\Client Access;c:\Program Files\ibm\Client
Access\shared;c:\Program Files\ibm\Client

Access\emulator;C:\Program Files\Common Files\Op
Session\Shared\;C:\Program Files\Common
Files\OpSession\Viewer Shared\;C:\Program Files\ATI
Technologies\ATI Control Panel;C:\Program
Files\Common Files\Roxio

Shared\DLLShared;C:\Program Fil
es\ATI Technologies\Fire GL 3D Studio Max;C:\Program
Files\HPQ\IAM\bin;C:\Program Files\cvsnt;C:\Program
Files\QuickTime\QTSystem\;C:\Program Files\Microsoft
SQL

Server\80\Tools\Binn\;C:\Pro
gram Files\Microsoft SQL Server\90
\DTS\Binn\;C:\Program Files\Microsoft SQL Server\90
\Tools\binn\;C:\Program Files\Microsoft SQL

Server\90\Tools\Binn\VSShell\Common7\IDE\;C:\Program
Files\Mi
crosoft Visual Studio 8\Common7
\IDE\PrivateAssemblies\;C:\JOnAS-4.6.6
\ant\bin;C:\JOnAS-4.6.6\bin\nt;C:\jdk1.5.0_06
\bin;;C:\bea\WEBLOG~1\server\native\win\32\oci920_8
.
***************************************************
* To start WebLogic Server, use a username and *
* password assigned to an admin-level user. For *
* server administration, use the WebLogic Server *
* console at http:\\hostname:port\console *
***************************************************
starting weblogic with Java version:
Listening for transport dt_socket at address: 8453
java version "1.5.0_04"
Java(TM) 2 Runtime Environment, Standard Edition
(build 1.5.0_04-b05)
Java HotSpot(TM) Client VM (build 1.5.0_04-b05, mixed
mode)
Starting WLS with line:
C:\bea\JDK150~1\bin\java -client -Xdebug -Xnoagent -
Xrunjdwp:transport=dt_socket,address=8453,server=y,sus
pend=n -Djava.compiler=NONE -Xms256m -Xmx512m

-XX:CompileThreshold=8000 -XX:PermSiz
e=48m -XX:MaxPermSize=128m -Xverify:none -ea -
da:com.bea... -da:javelin... -da:weblogic... -
ea:com.bea.wli... -ea:com.bea.broker... -
ea:com.bea.sbconsole...

-Dplatform.home=C:\bea\WEBLOG~
1 -Dwls.home=C:\bea\WEBLOG~1\server -
Dwli.home=C:\bea\WEBLOG~1\integration -
Dweblogic.management.discover=true -
Dweblogic.ProductionModeEnabled= -Dwlw.iterativeDev=

-Dwlw.testConsole= -Dwl
w.logErrorsToConsole= -
Dweblogic.ext.dirs=C:\bea\patch_weblogic910
\profiles\default\sysext_manifest_classpath -
Dweblogic.Name=AdminServer

-Djava.security.policy=C:\bea\WEBLOG~1\server\lib\we
blogic.policy weblogic.Server
Listening for transport dt_socket at address: 8453
<May 19, 2006 2:39:06 PM CDT> <Notice>
<WebLogicServer> <BEA-000395> <Following extensions
directory contents added to the end of the classpath:
C:\bea\user_projects\domains\oss\lib\ossproperties.jar
>
<May 19, 2006 2:39:07 PM CDT> <Info> <WebLogicServer>
<BEA-000377> <Starting WebLogic Server with Java
HotSpot(TM) Client VM Version 1.5.0_04-b05 from Sun
Microsystems

Inc.>
<May 19, 2006 2:39:09 PM CDT> <Info> <Management>
<BEA-141107> <Version: WebLogic Server 9.1 Mon Dec
12 19:26:33 EST 2005 689178 >
<May 19, 2006 2:39:18 PM CDT> <Info> <WebLogicServer>
<BEA-000215> <Loaded License : C:\bea\license.bea>
<May 19, 2006 2:39:18 PM CDT> <Notice>
<WebLogicServer> <BEA-000365> <Server state changed
to STARTING>
<May 19, 2006 2:39:18 PM CDT> <Info> <WorkManager>
<BEA-002900> <Initializing self-tuning thread pool>
<May 19, 2006 2:39:19 PM CDT> <Notice> <Log
Management> <BEA-170019> <The server log file
C:\bea\user_projects\domains\oss\servers\AdminServer\l
ogs\AdminServer.log is

opened. All server side
log events will be written to this file.>
<May 19, 2006 2:39:24 PM CDT> <Notice> <Security>
<BEA-090082> <Security initializing using security
realm myrealm.>
<May 19, 2006 2:39:33 PM CDT> <Notice>
<WebLogicServer> <BEA-000365> <Server state changed
to STANDBY>
<May 19, 2006 2:39:33 PM CDT> <Notice>
<WebLogicServer> <BEA-000365> <Server state changed
to STARTING>
jTDS: connect
url = jdbc:jtds:sqlserver://localhost:1433/OSS
info = {user=sa, password=p@ssw0rd}
==========================
jTDS: Constructing ConnectionJDBC2 string properties
jTDS: Login
serverName = localhost
database = OSS
user = sa
password = p@ssw0rd
domain =
appName = jTDS
progName = jTDS
wsid = CLXITISFXYXX
language =
macAddress = 000000000000
netPacketSize = 0
=================================
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@171157a
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@171157a
jTDS: Constructing ConnectionJDBC3
jTDS: ConnectionJDBC2 prepareStatement SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@171157a
jTDS: ConnectionJDBC2 prepareStatement int int SELECT
1 net.sourceforge.jtds.jdbc.ConnectionJDBC3@171157a
May 19, 2006 2:39:43 PM
org.opensubsystems.core.util.Log <clinit>
INFO: Using log configuration file

zip:C:/bea/user_projects/domains/oss/./servers/AdminSe
rver/tmp/_WL_user/core-app/x7nvg4/war/WEB-
INF/lib/core-conf.jar!/osslog.properties
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINER: ENTRY
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.Config getProperties
CONFIG: Name of configuration file is not specified
using property oss.config.file. Trying to load
default configuration file from system classpath.
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.Config getProperties
CONFIG: Using configuration file
jar:file:/C:/bea/user_projects/domains/oss/./lib/osspr
operties.jar!/oss.properties
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINEST: Speculative class instantiation for
org.opensubsystems.core.persist.db.DatabaseTransaction
Factory failed for class

org.opensubsystems.core.persist.db.DatabaseTransaction
Factory with
error java.lang.InstantiationException:
org.opensubsystems.core.persist.db.DatabaseTransaction
Factory
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.J2EEUtils
detectJ2EEServerType
FINEST: Trying to detect J2EE application server
using identifier
weblogic.utils.classloaders.ChangeAwareClassLoader
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.J2EEUtils
detectJ2EEServerType
FINE: Weblogic application server detected.
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINEST: Speculative class instantiation for
org.opensubsystems.core.util.TransactionFactory
failed for class
org.opensubsystems.core.util.TransactionFactory with
error

java.lang.Instantiatio
nException:
org.opensubsystems.core.util.TransactionFactory
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassUtils
createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.transaction.J2EETra
nsactionFactoryImpl
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseTransaction
FactoryImpl setInstance
FINE: Default database transaction factory is
org.opensubsystems.core.persist.db.transaction.J2EETra
nsactionFactoryImpl
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINEST: Speculative class instantiation for
org.opensubsystems.core.persist.db.DatabaseConnectionF
actory failed for class

org.opensubsystems.core.persist.db.DatabaseConnectionF
actory with er
ror java.lang.InstantiationException:
org.opensubsystems.core.persist.db.DatabaseConnectionF
actory
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassUtils
createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.connectionpool.J2EE
DatabaseConnectionFactoryImpl
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl setInstance
FINE: Default database connection factory is
org.opensubsystems.core.persist.db.connectionpool.J2EE
DatabaseConnectionFactoryImpl
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.driver =
net.sourceforge.jtds.jdbc.Driver
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.url =
jdbc:jtds:sqlserver://localhost:1433/OSS
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.user = basta
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.password = fidli
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.adminuser = sa
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.adminpassword =
p@ssw0rd
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.admindatasource = true
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseImpl
setInstance
FINE: Default database is
org.opensubsystems.core.persist.db.mssql.MSSQLDatabase
Impl
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebUtils <clinit>
CONFIG: Parameter oss.webserver.servebuffer.size
specifying size of buffer to use to serve files to
the Internet not found, using default value 40960
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINE: Initializing default database.
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.connectionpool.J2EE
DatabaseConnectionFactoryImpl createConnectionPool
FINEST: Looking up datasource OSSDS
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.connectionpool.J2EE
DatabaseConnectionFactoryImpl createConnectionPool
FINE: Found datasource OSSDS
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.connectionpool.Pool
edDatabaseConnectionFactoryImpl addDataSource
FINE: Connection pool OSSDS registered.
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.connectionpool.Pool
edDatabaseConnectionFactoryImpl start
FINE: Connection pool is running...
jTDS: connect
url = jdbc:jtds:sqlserver://localhost:1433/OSS
info = {user=basta, password=fidli}
==========================
jTDS: Constructing ConnectionJDBC2 string properties
jTDS: Login
serverName = localhost
database = OSS
user = basta
password = fidli
domain =
appName = jTDS
progName = jTDS
wsid = CLXITISFXYXX
language =
macAddress = 000000000000
netPacketSize = 0
=================================
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: Constructing ConnectionJDBC3
jTDS: ConnectionJDBC2 prepareStatement SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: ConnectionJDBC2 prepareStatement int int SELECT
1 net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.transaction.J2EETra
nsactionFactoryImpl lookupUserTransaction
FINEST: Looking up UserTransaction at location
UserTransaction
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.transaction.J2EETra
nsactionFactoryImpl lookupUserTransaction
FINEST: UserTransaction is not available at JNDI
location UserTransaction
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.transaction.J2EETra
nsactionFactoryImpl lookupUserTransaction
FINEST: Looking up UserTransaction at location
java:comp/UserTransaction
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.transaction.J2EETra
nsactionFactoryImpl lookupUserTransaction
FINE: UserTransaction found at
java:comp/UserTransaction
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl initializeConnection
FINEST: Setting autocommit to requested autocommit
false since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseTransaction
FactoryImpl commitTransaction
FINEST: commitTransaction on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_j
tds_jdbc_ConnectionJDBC3@0
jTDS: ConnectionJDBC2 commit
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: ConnectionJDBC3
clearSavepointsnet.sourceforge.jtds.jdbc.ConnectionJDB
C3@11c4ef6
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseTransaction
FactoryImpl commitTransaction
FINEST: commitTransaction sucessfull on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_j
tds_jdbc_ConnectionJDBC3@0
May 19, 2006 2:40:01 PM
org.opensubsystems.core.persist.db.DatabaseImpl start
FINER: Database is initialized.
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINE: Database default initialized.
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINER: RETURN
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebModuleListener
contextInitialized
FINER: ENTRY
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassUtils
createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.www.WebModuleDefinitionManager
succeeded for class

org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 2:40:01 PM
org.opensubsystems.core.util.ClassFactory
createInstance
FINEST: Class instantiation for
org.opensubsystems.core.www.WebModuleDefinitionManager
based on default setting instantiated

org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebModuleDefinitionManager
setInstance
FINE: Default module definition manager is
org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebModuleListener
contextInitialized
FINER: RETURN
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebSessionServlet <clinit>
CONFIG: Parameter oss.webserver.dispatcher.cached
specifying if request dispatcher to UI pages should
be cached not found, using default value false
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.webserver.login.required = false
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.webserver.login.url = 'null'
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.webserver.sessionvalidator.class = null
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.login.secure = false
May 19, 2006 2:40:01 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.application.secure = false
<May 19, 2006 2:40:01 PM CDT> <Notice> <Log
Management> <BEA-170027> <The server initialized the
domain log broadcaster successfully. Log messages
will now be

broadcasted to the domain log.>

<May 19, 2006 2:40:03 PM CDT> <Notice>
<WebLogicServer> <BEA-000365> <Server state changed
to ADMIN>
<May 19, 2006 2:40:03 PM CDT> <Notice>
<WebLogicServer> <BEA-000365> <Server state changed
to RESUMING>
<May 19, 2006 2:40:04 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default[1]" is now listening on
192.168.150.1:7001 for protocols iiop, t3, ldap,
http.>
<May 19, 2006 2:40:04 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default" is now listening on
10.44.40.34:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 2:40:04 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default[2]" is now listening on
192.168.93.1:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 2:40:04 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default[3]" is now listening on
127.0.0.1:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 2:40:04 PM CDT> <Notice>
<WebLogicServer> <BEA-000331> <Started WebLogic Admin
Server "AdminServer" for domain "oss" running in
Development Mode>
<May 19, 2006 2:40:04 PM CDT> <Notice>
<WebLogicServer> <BEA-000365> <Server state changed
to RUNNING>
<May 19, 2006 2:40:04 PM CDT> <Notice>
<WebLogicServer> <BEA-000360> <Server started in
RUNNING mode>
<May 19, 2006 2:40:06 PM CDT> <Warning> <Server> <BEA-
002611> <Hostname "CLXITISFXYXX", maps to multiple IP
addresses: 10.44.40.34, 192.168.150.1, 192.168.93.1>
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseSchemaImpl
<clinit>
CONFIG: Property oss.dbschema.prefix = BF_
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassUtils
createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.DatabaseSchemaManag
er
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.persist.db.DatabaseSchemaManag
er succeeded for class

org.opensubsystems.core.persist.db.DatabaseSchemaManag
er
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassFactory
createInstance
FINEST: Class instantiation for
org.opensubsystems.core.persist.db.DatabaseSchemaManag
er based on default setting instantiated

org.opensubsystems.core.persist.db.DatabaseSchemaManag
er
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseSchemaManag
er setManagerInstance
FINE: Default database schema manager is
org.opensubsystems.core.persist.db.DatabaseSchemaManag
er
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassUtils
createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.driver.mssql.MSSQLD
atabaseTestSchema
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.persist.db.driver.DatabaseTest
Schema succeeded for class

org.opensubsystems.core.persist.db.driver.mssql.MSSQLD
atabaseTestS
chema
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassFactory
createInstance
FINEST: Class instantiation for
org.opensubsystems.core.persist.db.driver.DatabaseTest
Schema based on default setting instantiated

org.opensubsystems.core.persist.db.driver.mssql.MSSQLD
ataba
seTestSchema
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema SCHEMA version 1 is now
managed by versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassUtils
createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.mssql.MSSQLVersione
dDatabaseSchema
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassFactory
instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema succeeded for class

org.opensubsystems.core.persist.db.mssql.MSSQLVersione
dDatabaseSchem
a
May 19, 2006 2:41:31 PM
org.opensubsystems.core.util.ClassFactory
createInstance
FINEST: Class instantiation for
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema based on default setting instantiated

org.opensubsystems.core.persist.db.mssql.MSSQLVersione
dDataba
seSchema
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST version 1 is now
managed by versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 2:41:31 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no
tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests
therefore no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore
no tests will be added from this suite.
May 19, 2006 2:41:31 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite
org.opensubsystems.core.CoreTests were successfully
added.

Here starts the test

May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl initializeConnection
FINEST: Setting autocommit to requested autocommit
false since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema loadExistingSchemas
FINE: Verifying existing database schemas
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema loadExistingSchemas
FINER: Database schema DBTEST version 1 is up to date.
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema loadExistingSchemas
FINER: Database schema SCHEMA version 1 is up to date.
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSc
hema loadExistingSchemas
FINE: Existing database schemas verified: 0 schemas
to add and 0 schemas to upgrade.
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseTransaction
FactoryImpl commitTransaction
FINEST: commitTransaction on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_j
tds_jdbc_ConnectionJDBC3@1
jTDS: ConnectionJDBC2 commit
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: ConnectionJDBC3
clearSavepointsnet.sourceforge.jtds.jdbc.ConnectionJDB
C3@11c4ef6
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseTransaction
FactoryImpl commitTransaction
FINEST: commitTransaction sucessfull on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_j
tds_jdbc_ConnectionJDBC3@1
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseImpl start
FINER: Database is initialized.
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl initializeConnection
FINEST: Setting autocommit to requested autocommit
false since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
jTDS: connect
url = jdbc:jtds:sqlserver://localhost:1433/OSS
info = {user=basta, password=fidli}
==========================
jTDS: Constructing ConnectionJDBC2 string properties
jTDS: Login
serverName = localhost
database = OSS
user = basta
password = fidli
domain =
appName = jTDS
progName = jTDS
wsid = CLXITISFXYXX
language =
macAddress = 000000000000
netPacketSize = 0
=================================
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: Constructing ConnectionJDBC3
jTDS: ConnectionJDBC2 prepareStatement SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 prepareStatement int int SELECT
1 net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc

Here we are requesting connection. Notice we are
requesting it before the transaction has started

May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl initializeConnection
FINEST: Setting autocommit to requested autocommit
false since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false

Here begins the first transaction, which deletes any
leftover data and enters clean data for the test

May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction begin
FINEST: UserTransaction.begin for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction begin
FINEST: UserTransaction.begin sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
jTDS: ConnectionJDBC2 prepareStatement delete from
TRANSACTION_TEST
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 prepareStatement int int delete
from TRANSACTION_TEST
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 prepareStatement insert into
TRANSACTION_TEST values (?, ?)
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 prepareStatement int int insert
into TRANSACTION_TEST values (?, ?)
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction commit
FINEST: UserTransaction.commit for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction commit
FINEST: UserTransaction.commit sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]

Here the code instructed WebLogic to commit the
transaction. Notice that the transaction was commited
in our code
but NOT in with jTDS connection. This is
unexpected!!! I wonder if it is due to the fact that
the connection
was requested before the transaction has started.

JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true

Here starts the seconds transaction, which updates
the data in the database

May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction begin
FINEST: UserTransaction.begin for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction begin
FINEST: UserTransaction.begin sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl initializeConnection
FINEST: Setting autocommit to false. Requested
autocommitfalse and transaction is in progress.
JTDS: Request to setAutoCommit to false
jTDS: ConnectionJDBC2 prepareStatement update
TRANSACTION_TEST set TEST_VALUE = ? where TEST_ID = ?
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 prepareStatement int int update
TRANSACTION_TEST set TEST_VALUE = ? where TEST_ID = ?
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction commit
FINEST: UserTransaction.commit for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
jTDS: ConnectionJDBC2 commit
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC3
clearSavepointsnet.sourceforge.jtds.jdbc.ConnectionJDB
C3@683bfc
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction commit

Here the code instructed the transaction to commit
and notice that WebLogic commited the transaction
also in the jTDS code.
I wonder if this is due to the fact that the
connection was requested within the transaction. The
issue is that this really
commited two transactions The previous one and this
one.

Here starts another transaction to delete the data.

FINEST: UserTransaction.commit sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction begin
FINEST: UserTransaction.begin for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction begin
FINEST: UserTransaction.begin sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
jTDS: ConnectionJDBC2 prepareStatement delete from
TRANSACTION_TEST
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
jTDS: ConnectionJDBC2 prepareStatement int int delete
from TRANSACTION_TEST
net.sourceforge.jtds.jdbc.ConnectionJDBC3@11c4ef6
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction commit
FINEST: UserTransaction.commit for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.transaction.Delegat
ingUserTransaction commit
FINEST: UserTransaction.commit sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]

Here we again commited the transaction but weblogic
didn't commit the previous transaction. SInce there
is an update pending this transaction will block. The
issue is that the connection used during previous
transaction was acquired before the transaction has
started and therefore WebLogic ignore it. This is
in my opinion a bug.

JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
May 19, 2006 2:41:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionF
actoryImpl initializeConnection
FINEST: Setting autocommit to requested autocommit
true since no transaction is in progress.
JTDS: Request to setAutoCommit to true
jTDS: ConnectionJDBC2 prepareStatement select
TEST_VALUE from TRANSACTION_TEST where TEST_ID = 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc
jTDS: ConnectionJDBC2 prepareStatement int int select
TEST_VALUE from TRANSACTION_TEST where TEST_ID = 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@683bfc

Here this block because the previous delete statement
wasn't commited

The stack trace when WebLogic locks is. Please notice
that the line numbers jtds classes are off from the
1.2 release due to me adding debug statements.
Also the lines in OpenSubsystems code is off due to
added comments in the code

Thread [[ACTIVE] ExecuteThread: '3' for
queue: 'weblogic.kernel.Default (self-tuning)']
(Suspended)
SocketInputStream.socketRead0(FileDescriptor,
byte[], int, int, int) line: not available [native
method]
SocketInputStream.read(byte[], int, int)
line: 129
DataInputStream.readFully(byte[], int, int)
line: 176
DataInputStream.readFully(byte[]) line: 152
SharedSocket.readPacket(byte[]) line: 826
SharedSocket.getNetPacket(int, byte[]) line:
707
ResponseStream.getPacket() line: 466
ResponseStream.read() line: 103
ResponseStream.peek() line: 88
TdsCore.wait(int) line: 3887
TdsCore.executeSQL(String, String, ParamInfo
[], boolean, int, int, int, boolean) line: 1042
JtdsPreparedStatement
(JtdsStatement).executeSQLQuery(String, String,
ParamInfo[], boolean) line: 406
JtdsPreparedStatement.executeQuery() line: 693
PreparedStatement_net_sourceforge_jtds_jdbc_Jt
dsPreparedStatement(PreparedStatement).executeQuery()
line: 100
TransactionTest$TransactionTestInternal.testCo
mmitAfterUpdateDelete() line: 770
NativeMethodAccessorImpl.invoke0(Method,
Object, Object[]) line: not available [native method]
NativeMethodAccessorImpl.invoke(Object, Object
[]) line: 39
DelegatingMethodAccessorImpl.invoke(Object,
Object[]) line: 25
Method.invoke(Object, Object...) line: 585
TransactionTest$TransactionTestInternal
(TestCase).runTest() line: 164
TransactionTest$TransactionTestInternal
(TestCase).runBare() line: 130
TestResult$1.protect() line: 106
TestResult.runProtected(Test, Protectable)
line: 124
TestResult.run(TestCase) line: 109
TransactionTest$TransactionTestInternal
(TestCase).run(TestResult) line: 120
TestSuite.runTest(Test, TestResult) line: 230
TestSuite.run(TestResult) line: 225
DatabaseTestSuite(TestSuite).runTest(Test,
TestResult) line: 230
DatabaseTestSuite.runTest(Test, TestResult)
line: 143
DatabaseTestSuite(TestSuite).run(TestResult)
line: 225
DatabaseTestSetup(TestDecorator).basicRun
(TestResult) line: 24
TestSetup$1.protect() line: 21
TestResult.runProtected(Test, Protectable)
line: 124
DatabaseTestSetup(TestSetup).run(TestResult)
line: 25
TestRunner$1.run() line: 72
TestRunner.run(String[]) line: 95
JUnitEEServlet.runTests(String, String[],
HttpServletRequest, boolean) line: 234
JUnitEEServlet.doGet(HttpServletRequest,
HttpServletResponse) line: 205
JUnitEEServlet(HttpServlet).service
(HttpServletRequest, HttpServletResponse) line: 743
JUnitEEServlet(HttpServlet).service
(ServletRequest, ServletResponse) line: 856
StubSecurityHelper$ServletServiceAction.run()
line: 225
StubSecurityHelper.invokeServlet
(ServletRequest, HttpServletRequest,
ServletRequestImpl, ServletResponse,
HttpServletResponse, Servlet) line: 127
ServletStubImpl.execute(ServletRequest,
ServletResponse, FilterChainImpl) line: 272
ServletStubImpl.execute(ServletRequest,
ServletResponse) line: 165
WebAppServletContext$ServletInvocationAction.r
un() line: 3153
AuthenticatedSubject.doAs(AbstractSubject,
PrivilegedAction) line: 321
SecurityManager.runAs(AuthenticatedSubject,
AuthenticatedSubject, PrivilegedAction) line: 121
WebAppServletContext.securedExecute
(HttpServletRequest, HttpServletResponse, boolean)
line: 1973
WebAppServletContext.execute
(ServletRequestImpl, ServletResponseImpl) line: 1880
ServletRequestImpl.run() line: 1310
ExecuteThread.execute(Runnable) line: 207
ExecuteThread.run() line: 179

[Miro Halas]

Full analysis with no line breaks for better readability

[Miro Halas]

Logged In: YES
user_id=166079

Workaround for this defect is to ONLY use connections
acquired within the transaction and not outside of
transaction.

[Miro Halas]

Logged In: YES
user_id=166079

Here is another log from another test proving this problem

This defect is detected by executing only the
TransactionTests -
testCommitAfterUpdateDeleteWithAutoCommit with all the
other test cases commented out.

Here is full log

.
.
JAVA Memory arguments: -Xms256m -Xmx512m -
XX:CompileThreshold=8000 -XX:PermSize=48m -
XX:MaxPermSize=128m
.
WLS Start Mode=Development
.
CLASSPATH=;C:\bea\patch_weblogic910
\profiles\default\sys_manifest_classpath\weblogic_patch.jar
;C:\bea\JDK150~1\lib\tools.jar;C:\bea\WEBLOG~1
\server\lib\weblogic_sp.jar;C:\bea\WEBLOG~1\server
\lib\weblogic.jar;C:\bea\WEBLOG~1
\server\lib\webservices.jar;;C:\bea\WEBLOG~1
\common\eval\pointbase\lib\pbclient51.jar;C:\bea\WEBLOG~1
\server\lib\xqrl.jar;;C:\bea\WEBLOG~1\integration\lib\ut
il.jar;C:\bea\weblogic91\server\ext\jdbc\jtds.jar;
.
PATH=C:\bea\patch_weblogic910
\profiles\default\native;C:\bea\WEBLOG~1
\server\native\win\32;C:\bea\WEBLOG~1
\server\bin;C:\bea\JDK150~1\jre\bin;C:\bea\JDK150~1
\bin;C:\WINNT\system32;C:\WINNT;C
:\WINNT\System32\Wbem;C:\Lotus\Notes;c:\Program
Files\ibm\Client Access;c:\Program Files\ibm\Client
Access\shared;c:\Program Files\ibm\Client
Access\emulator;C:\Program Files\Common Files\Op
Session\Shared\;C:\Program Files\Common
Files\OpSession\Viewer Shared\;C:\Program Files\ATI
Technologies\ATI Control Panel;C:\Program Files\Common
Files\Roxio Shared\DLLShared;C:\Program Fil
es\ATI Technologies\Fire GL 3D Studio Max;C:\Program
Files\HPQ\IAM\bin;C:\Program Files\cvsnt;C:\Program
Files\QuickTime\QTSystem\;C:\Program Files\Microsoft SQL
Server\80\Tools\Binn\;C:\Pro
gram Files\Microsoft SQL Server\90\DTS\Binn\;C:\Program
Files\Microsoft SQL Server\90\Tools\binn\;C:\Program
Files\Microsoft SQL Server\90\Tools\Binn\VSShell\Common7
\IDE\;C:\Program Files\Mi
crosoft Visual Studio 8\Common7
\IDE\PrivateAssemblies\;C:\JOnAS-4.6.6\ant\bin;C:\JOnAS-
4.6.6\bin\nt;C:\jdk1.5.0_06\bin;;C:\bea\WEBLOG~1
\server\native\win\32\oci920_8
.
***************************************************
* To start WebLogic Server, use a username and *
* password assigned to an admin-level user. For *
* server administration, use the WebLogic Server *
* console at http:\\hostname:port\console *
***************************************************
starting weblogic with Java version:
Listening for transport dt_socket at address: 8453
java version "1.5.0_04"
Java(TM) 2 Runtime Environment, Standard Edition (build
1.5.0_04-b05)
Java HotSpot(TM) Client VM (build 1.5.0_04-b05, mixed mode)
Starting WLS with line:
C:\bea\JDK150~1\bin\java -client -Xdebug -Xnoagent -
Xrunjdwp:transport=dt_socket,address=8453,server=y,suspend=
n -Djava.compiler=NONE -Xms256m -Xmx512m -
XX:CompileThreshold=8000 -XX:PermSiz
e=48m -XX:MaxPermSize=128m -Xverify:none -ea -
da:com.bea... -da:javelin... -da:weblogic... -
ea:com.bea.wli... -ea:com.bea.broker... -
ea:com.bea.sbconsole... -Dplatform.home=C:\bea\WEBLOG~
1 -Dwls.home=C:\bea\WEBLOG~1\server -
Dwli.home=C:\bea\WEBLOG~1\integration -
Dweblogic.management.discover=true -
Dweblogic.ProductionModeEnabled= -Dwlw.iterativeDev= -
Dwlw.testConsole= -Dwl
w.logErrorsToConsole= -
Dweblogic.ext.dirs=C:\bea\patch_weblogic910
\profiles\default\sysext_manifest_classpath -
Dweblogic.Name=AdminServer -
Djava.security.policy=C:\bea\WEBLOG~1\server\lib\we
blogic.policy weblogic.Server
Listening for transport dt_socket at address: 8453
<May 19, 2006 4:32:12 PM CDT> <Notice> <WebLogicServer>
<BEA-000395> <Following extensions directory contents
added to the end of the classpath:
C:\bea\user_projects\domains\oss\lib\ossproperties.jar>
<May 19, 2006 4:32:12 PM CDT> <Info> <WebLogicServer> <BEA-
000377> <Starting WebLogic Server with Java HotSpot(TM)
Client VM Version 1.5.0_04-b05 from Sun Microsystems Inc.>
<May 19, 2006 4:32:13 PM CDT> <Info> <Management> <BEA-
141107> <Version: WebLogic Server 9.1 Mon Dec 12 19:26:33
EST 2005 689178 >
<May 19, 2006 4:32:17 PM CDT> <Info> <WebLogicServer> <BEA-
000215> <Loaded License : C:\bea\license.bea>
<May 19, 2006 4:32:17 PM CDT> <Notice> <WebLogicServer>
<BEA-000365> <Server state changed to STARTING>
<May 19, 2006 4:32:17 PM CDT> <Info> <WorkManager> <BEA-
002900> <Initializing self-tuning thread pool>
<May 19, 2006 4:32:17 PM CDT> <Notice> <Log Management>
<BEA-170019> <The server log file
C:\bea\user_projects\domains\oss\servers\AdminServer\logs\A
dminServer.log is opened. All server side
log events will be written to this file.>
<May 19, 2006 4:32:19 PM CDT> <Notice> <Security> <BEA-
090082> <Security initializing using security realm
myrealm.>
<May 19, 2006 4:32:21 PM CDT> <Notice> <WebLogicServer>
<BEA-000365> <Server state changed to STANDBY>
<May 19, 2006 4:32:21 PM CDT> <Notice> <WebLogicServer>
<BEA-000365> <Server state changed to STARTING>
jTDS: connect
url = jdbc:jtds:sqlserver://localhost:1433/OSS
info = {user=sa, password=p@ssw0rd}
==========================
jTDS: Constructing ConnectionJDBC2 string properties
jTDS: Login
serverName = localhost
database = OSS
user = sa
password = p@ssw0rd
domain =
appName = jTDS
progName = jTDS
wsid = CLXITISFXYXX
language =
macAddress = 000000000000
netPacketSize = 0
=================================
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@133092e
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@133092e
jTDS: Constructing ConnectionJDBC3
jTDS: ConnectionJDBC2 prepareStatement SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@133092e
jTDS: ConnectionJDBC2 prepareStatement int int SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@133092e
May 19, 2006 4:32:22 PM org.opensubsystems.core.util.Log
<clinit>
INFO: Using log configuration file
zip:C:/bea/user_projects/domains/oss/./servers/AdminServer/
tmp/_WL_user/core-app/x7nvg4/war/WEB-INF/lib/core-
conf.jar!/osslog.properties
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINER: ENTRY
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.Config getProperties
CONFIG: Name of configuration file is not specified using
property oss.config.file. Trying to load default
configuration file from system classpath.
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.Config getProperties
CONFIG: Using configuration file
jar:file:/C:/bea/user_projects/domains/oss/./lib/osspropert
ies.jar!/oss.properties
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINEST: Speculative class instantiation for
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ry failed for class
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ry with
error java.lang.InstantiationException:
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ry
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.J2EEUtils detectJ2EEServerType
FINEST: Trying to detect J2EE application server using
identifier
weblogic.utils.classloaders.ChangeAwareClassLoader
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.J2EEUtils detectJ2EEServerType
FINE: Weblogic application server detected.
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINEST: Speculative class instantiation for
org.opensubsystems.core.util.TransactionFactory failed for
class org.opensubsystems.core.util.TransactionFactory with
error java.lang.Instantiatio
nException: org.opensubsystems.core.util.TransactionFactory
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassUtils createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.transaction.J2EETransact
ionFactoryImpl
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ryImpl setInstance
FINE: Default database transaction factory is
org.opensubsystems.core.persist.db.transaction.J2EETransact
ionFactoryImpl
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINEST: Speculative class instantiation for
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
y failed for class
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
y with er
ror java.lang.InstantiationException:
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
y
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassUtils createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.connectionpool.J2EEDatab
aseConnectionFactoryImpl
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl setInstance
FINE: Default database connection factory is
org.opensubsystems.core.persist.db.connectionpool.J2EEDatab
aseConnectionFactoryImpl
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.driver =
net.sourceforge.jtds.jdbc.Driver
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.url =
jdbc:jtds:sqlserver://localhost:1433/OSS
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.user = basta
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.password = fidli
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.adminuser = sa
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.adminpassword = p@ssw0rd
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl loadDefaultDatabaseProperties
CONFIG: Property oss.datasource.admindatasource = true
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseImpl setInstance
FINE: Default database is
org.opensubsystems.core.persist.db.mssql.MSSQLDatabaseImpl
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebUtils <clinit>
CONFIG: Parameter oss.webserver.servebuffer.size
specifying size of buffer to use to serve files to the
Internet not found, using default value 40960
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINE: Initializing default database.
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.connectionpool.J2EEDatab
aseConnectionFactoryImpl createConnectionPool
FINEST: Looking up datasource OSSDS
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.connectionpool.J2EEDatab
aseConnectionFactoryImpl createConnectionPool
FINE: Found datasource OSSDS
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.connectionpool.PooledDat
abaseConnectionFactoryImpl addDataSource
FINE: Connection pool OSSDS registered.
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.connectionpool.PooledDat
abaseConnectionFactoryImpl start
FINE: Connection pool is running...
jTDS: connect
url = jdbc:jtds:sqlserver://localhost:1433/OSS
info = {user=basta, password=fidli}
==========================
jTDS: Constructing ConnectionJDBC2 string properties
jTDS: Login
serverName = localhost
database = OSS
user = basta
password = fidli
domain =
appName = jTDS
progName = jTDS
wsid = CLXITISFXYXX
language =
macAddress = 000000000000
netPacketSize = 0
=================================
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: Constructing ConnectionJDBC3
jTDS: ConnectionJDBC2 prepareStatement SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC2 prepareStatement int int SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.transaction.J2EETransact
ionFactoryImpl lookupUserTransaction
FINEST: Looking up UserTransaction at location
UserTransaction
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.transaction.J2EETransact
ionFactoryImpl lookupUserTransaction
FINEST: UserTransaction is not available at JNDI location
UserTransaction
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.transaction.J2EETransact
ionFactoryImpl lookupUserTransaction
FINEST: Looking up UserTransaction at location
java:comp/UserTransaction
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.transaction.J2EETransact
ionFactoryImpl lookupUserTransaction
FINE: UserTransaction found at java:comp/UserTransaction
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to requested autocommit false
since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ryImpl commitTransaction
FINEST: commitTransaction on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_jtds_j
dbc_ConnectionJDBC3@0
jTDS: ConnectionJDBC2 commit
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC3
clearSavepointsnet.sourceforge.jtds.jdbc.ConnectionJDBC3@17
f13c5
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ryImpl commitTransaction
FINEST: commitTransaction sucessfull on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_jtds_j
dbc_ConnectionJDBC3@0
May 19, 2006 4:32:23 PM
org.opensubsystems.core.persist.db.DatabaseImpl start
FINER: Database is initialized.
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINE: Database default initialized.
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.DatabaseContextListener
contextInitialized
FINER: RETURN
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebModuleListener
contextInitialized
FINER: ENTRY
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassUtils createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.www.WebModuleDefinitionManager
succeeded for class
org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 4:32:23 PM
org.opensubsystems.core.util.ClassFactory createInstance
FINEST: Class instantiation for
org.opensubsystems.core.www.WebModuleDefinitionManager
based on default setting instantiated
org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebModuleDefinitionManager
setInstance
FINE: Default module definition manager is
org.opensubsystems.core.www.WebModuleDefinitionManager
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebModuleListener
contextInitialized
FINER: RETURN
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebSessionServlet <clinit>
CONFIG: Parameter oss.webserver.dispatcher.cached
specifying if request dispatcher to UI pages should be
cached not found, using default value false
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.webserver.login.required = false
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.webserver.login.url = 'null'
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.webserver.sessionvalidator.class = null
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.login.secure = false
May 19, 2006 4:32:23 PM
org.opensubsystems.core.www.WebSessionServlet init
CONFIG: oss.application.secure = false
<May 19, 2006 4:32:23 PM CDT> <Notice> <Log Management>
<BEA-170027> <The server initialized the domain log
broadcaster successfully. Log messages will now be
broadcasted to the domain log.>

<May 19, 2006 4:32:24 PM CDT> <Notice> <WebLogicServer>
<BEA-000365> <Server state changed to ADMIN>
<May 19, 2006 4:32:24 PM CDT> <Notice> <WebLogicServer>
<BEA-000365> <Server state changed to RESUMING>
<May 19, 2006 4:32:25 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default[1]" is now listening on
192.168.150.1:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 4:32:25 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default" is now listening on
10.44.40.34:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 4:32:25 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default[2]" is now listening on
192.168.93.1:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 4:32:25 PM CDT> <Notice> <Server> <BEA-
002613> <Channel "Default[3]" is now listening on
127.0.0.1:7001 for protocols iiop, t3, ldap, http.>
<May 19, 2006 4:32:25 PM CDT> <Notice> <WebLogicServer>
<BEA-000331> <Started WebLogic Admin Server "AdminServer"
for domain "oss" running in Development Mode>
<May 19, 2006 4:32:25 PM CDT> <Notice> <WebLogicServer>
<BEA-000365> <Server state changed to RUNNING>
<May 19, 2006 4:32:25 PM CDT> <Notice> <WebLogicServer>
<BEA-000360> <Server started in RUNNING mode>
<May 19, 2006 4:32:27 PM CDT> <Warning> <Server> <BEA-
002611> <Hostname "CLXITISFXYXX", maps to multiple IP
addresses: 10.44.40.34, 192.168.150.1, 192.168.93.1>

The server is ready

May 19, 2006 4:32:34 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:34 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:34 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:34 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:34 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseSchemaImpl
<clinit>
CONFIG: Property oss.dbschema.prefix = BF_
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassUtils createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.DatabaseSchemaManager
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.persist.db.DatabaseSchemaManager
succeeded for class
org.opensubsystems.core.persist.db.DatabaseSchemaManager
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassFactory createInstance
FINEST: Class instantiation for
org.opensubsystems.core.persist.db.DatabaseSchemaManager
based on default setting instantiated
org.opensubsystems.core.persist.db.DatabaseSchemaManager
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseSchemaManager
setManagerInstance
FINE: Default database schema manager is
org.opensubsystems.core.persist.db.DatabaseSchemaManager
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassUtils createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.driver.mssql.MSSQLDataba
seTestSchema
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.persist.db.driver.DatabaseTestSchem
a succeeded for class
org.opensubsystems.core.persist.db.driver.mssql.MSSQLDataba
seTestS
chema
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassFactory createInstance
FINEST: Class instantiation for
org.opensubsystems.core.persist.db.driver.DatabaseTestSchem
a based on default setting instantiated
org.opensubsystems.core.persist.db.driver.mssql.MSSQLDataba
seTestSchema
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema SCHEMA version 1 is now managed by
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassUtils createNewInstance
FINEST: Instantiated class
org.opensubsystems.core.persist.db.mssql.MSSQLVersionedData
baseSchema
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassFactory instantiateClass
FINER: Speculative class instantiation for
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
succeeded for class
org.opensubsystems.core.persist.db.mssql.MSSQLVersionedData
baseSchem
a
May 19, 2006 4:32:35 PM
org.opensubsystems.core.util.ClassFactory createInstance
FINEST: Class instantiation for
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
based on default setting instantiated
org.opensubsystems.core.persist.db.mssql.MSSQLVersionedData
ba
seSchema
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST version 1 is now managed by
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
add
FINEST: Database schema DBTEST is already managed by
versioned schema. It is not added second time.
May 19, 2006 4:32:35 PM
org.opensubsystems.core.persist.db.DatabaseImpl add
FINEST: Database schema DBTEST added to the database
versioned schema.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.portal.PortalTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.inventory.InventoryTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.search.SearchTests therefore no tests
will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.preferences.PreferencesTests therefore
no tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Cannot find class
org.opensubsystems.security.SecurityTests therefore no
tests will be added from this suite.
May 19, 2006 4:32:35 PM
org.opensubsystems.GenericDatabaseTests
addGenericTestsFromTestSuite
FINE: Test from suite org.opensubsystems.core.CoreTests
were successfully added.

Here the test starts

May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to requested autocommit false
since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
loadExistingSchemas
FINE: Verifying existing database schemas
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
loadExistingSchemas
FINER: Database schema DBTEST version 1 is up to date.
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
loadExistingSchemas
FINER: Database schema SCHEMA version 1 is up to date.
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.VersionedDatabaseSchema
loadExistingSchemas
FINE: Existing database schemas verified: 0 schemas to add
and 0 schemas to upgrade.
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ryImpl commitTransaction
FINEST: commitTransaction on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_jtds_j
dbc_ConnectionJDBC3@1
jTDS: ConnectionJDBC2 commit
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC3
clearSavepointsnet.sourceforge.jtds.jdbc.ConnectionJDBC3@17
f13c5
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.DatabaseTransactionFacto
ryImpl commitTransaction
FINEST: commitTransaction sucessfull on connection
weblogic.jdbc.wrapper.PoolConnection_net_sourceforge_jtds_j
dbc_ConnectionJDBC3@1
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.DatabaseImpl start
FINER: Database is initialized.
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to requested autocommit false
since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
jTDS: connect
url = jdbc:jtds:sqlserver://localhost:1433/OSS
info = {user=basta, password=fidli}
==========================
jTDS: Constructing ConnectionJDBC2 string properties
jTDS: Login
serverName = localhost
database = OSS
user = basta
password = fidli
domain =
appName = jTDS
progName = jTDS
wsid = CLXITISFXYXX
language =
macAddress = 000000000000
netPacketSize = 0
=================================
jTDS: ConnectionJDBC2 createStatement
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: ConnectionJDBC2 createStatement int int
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: Constructing ConnectionJDBC3
jTDS: ConnectionJDBC2 prepareStatement SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: ConnectionJDBC2 prepareStatement int int SELECT 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84

// Here we are getting the connection before the
transaction

May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to requested autocommit false
since no transaction is in progress.
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false

Here is the first transaction which deletes all the data

May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction begin
FINEST: UserTransaction.begin for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction begin
FINEST: UserTransaction.begin sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
jTDS: ConnectionJDBC2 prepareStatement delete from
TRANSACTION_TEST
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: ConnectionJDBC2 prepareStatement int int delete from
TRANSACTION_TEST
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction commit
FINEST: UserTransaction.commit for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]

// Here the transaction ends and notice that the jTDS
connection wasn't commited. This is due to the fact
// the WebLogic didn't enroll the connection in
transaction because it was obtained before the transaction
// has started

// This is not that important

May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction commit
FINEST: UserTransaction.commit sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
jTDS: ConnectionJDBC2 prepareStatement insert into
TRANSACTION_TEST values (?, ?)
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: ConnectionJDBC2 prepareStatement int int insert into
TRANSACTION_TEST values (?, ?)
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction begin
FINEST: UserTransaction.begin for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 4:32:44 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction begin
FINEST: UserTransaction.begin sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to false. Requested
autocommitfalse and transaction is in progress.
JTDS: Request to setAutoCommit to false
jTDS: ConnectionJDBC2 prepareStatement update
TRANSACTION_TEST set TEST_VALUE = ? where TEST_ID = ?
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: ConnectionJDBC2 prepareStatement int int update
TRANSACTION_TEST set TEST_VALUE = ? where TEST_ID = ?
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction commit
FINEST: UserTransaction.commit for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
jTDS: ConnectionJDBC2 commit
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
jTDS: ConnectionJDBC3
clearSavepointsnet.sourceforge.jtds.jdbc.ConnectionJDBC3@8e
7b84
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction commit
FINEST: UserTransaction.commit sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
jTDS: ConnectionJDBC2 prepareStatement int int select
TEST_VALUE from TRANSACTION_TEST where TEST_ID = 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
JTDS: Request to setAutoCommit to true
jTDS: ConnectionJDBC2 prepareStatement update
TRANSACTION_TEST set TEST_VALUE = ? where TEST_ID = ?
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC2 prepareStatement int int update
TRANSACTION_TEST set TEST_VALUE = ? where TEST_ID = ?
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
JTDS: Request to setAutoCommit to true
JTDS: Autocommit set to true
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to requested autocommit true
since no transaction is in progress.
JTDS: Request to setAutoCommit to true
jTDS: ConnectionJDBC2 prepareStatement int int select
TEST_VALUE from TRANSACTION_TEST where TEST_ID = 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@8e7b84
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.DatabaseConnectionFactor
yImpl initializeConnection
FINEST: Setting autocommit to requested autocommit false
since no transaction is in progress.

// Here starts the transaction where we should delete all
data.
// Notice that there is no prepare statement since it is
reusing cached prepared statement on connection
ConnectionJDBC3@8e7b84

JTDS: Request to setAutoCommit to false
JTDS: Autocommit set to false
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction begin
FINEST: UserTransaction.begin for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction begin
FINEST: UserTransaction.begin sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction commit
FINEST: UserTransaction.commit for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]
May 19, 2006 4:32:45 PM
org.opensubsystems.core.persist.db.transaction.DelegatingUs
erTransaction commit
FINEST: UserTransaction.commit sucessfull for ClientTM
[AdminServer+10.44.40.34:7001+oss+t3+]

// Here the transaction ends and notice that the jTDS
connection wasn't commited. This is due to the fact
// the WebLogic didn't enroll the connection in
transaction because it was obtained before the transaction
// has started

// Here we try to select the same data using different
connection

JTDS: Request to setAutoCommit to true
jTDS: ConnectionJDBC2 prepareStatement select TEST_VALUE
from TRANSACTION_TEST where TEST_ID = 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5
jTDS: ConnectionJDBC2 prepareStatement int int select
TEST_VALUE from TRANSACTION_TEST where TEST_ID = 1
net.sourceforge.jtds.jdbc.ConnectionJDBC3@17f13c5

// And here WebLogicc blocks since we are trying to read
data which were not commited since the
// connection which should have been commited wasn't
enroller in the transaction

[Miro Halas]

Full analysis with no line breaks for better readability