Teradata studio connection to mds : Could not log on user

Teradata Studio
N/A

Teradata studio connection to mds : Could not log on user

I can see the system loaded as that defined in mds when we use the Test user button. but upon checking for interdependence on object we get the following error

com.teradata.datatools.dependencies.TeradataInterdependenciesException: Could not log on user

at com.teradata.datatools.dependencies.mdsimpl.TeradataInterdependencies.(TeradataInterdependencies.java:134)

at com.teradata.datatools.dependencies.TeradataInterdependencies.getTeradataInterdependencies(TeradataInterdependencies.java:33)

at com.teradata.datatools.dependencies.ui.ShowInterdependencies.showInInterdepencencyBrowser(ShowInterdependencies.java:186)

at com.teradata.datatools.dependencies.ui.ShowInterdependencies.run(ShowInterdependencies.java:134)

at org.eclipse.ui.internal.PluginAction.runWithEvent(PluginAction.java:251)

at org.eclipse.jface.action.ActionContributionItem.handleWidgetSelection(ActionContributionItem.java:584)

at org.eclipse.jface.action.ActionContributionItem.access$2(ActionContributionItem.java:501)

at org.eclipse.jface.action.ActionContributionItem$5.handleEvent(ActionContributionItem.java:411)

at org.eclipse.swt.widgets.EventTable.sendEvent(EventTable.java:84)

at org.eclipse.swt.widgets.Widget.sendEvent(Widget.java:1053)

at org.eclipse.swt.widgets.Display.runDeferredEvents(Display.java:4165)

at org.eclipse.swt.widgets.Display.readAndDispatch(Display.java:3754)

at org.eclipse.ui.internal.Workbench.runEventLoop(Workbench.java:2701)

at org.eclipse.ui.internal.Workbench.runUI(Workbench.java:2665)

at org.eclipse.ui.internal.Workbench.access$4(Workbench.java:2499)

at org.eclipse.ui.internal.Workbench$7.run(Workbench.java:679)

at org.eclipse.core.databinding.observable.Realm.runWithDefault(Realm.java:332)

at org.eclipse.ui.internal.Workbench.createAndRunWorkbench(Workbench.java:668)

at org.eclipse.ui.PlatformUI.createAndRunWorkbench(PlatformUI.java:149)

at org.eclipse.ui.internal.ide.application.IDEApplication.start(IDEApplication.java:123)

at org.eclipse.equinox.internal.app.EclipseAppHandle.run(EclipseAppHandle.java:196)

at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:110)

at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:79)

at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:344)

at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:179)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

at java.lang.reflect.Method.invoke(Unknown Source)

at org.eclipse.equinox.launcher.Main.invokeFramework(Main.java:622)

at org.eclipse.equinox.launcher.Main.basicRun(Main.java:577)

at org.eclipse.equinox.launcher.Main.run(Main.java:1410)

Caused by: com.teradata.datatools.mdswsclient.TeradataMdsWsClientException: Could not get object keys for objectType DatabaseSystem

at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsClient.getObjectKeys(TeradataMdsWsClient.java:297)

at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsClient.getObjectKeys(TeradataMdsWsClient.java:271)

at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsClient.(TeradataMdsWsClient.java:104)

at com.teradata.datatools.mdswsclient.TeradataMdsWsClient.getTeradataMdsWsClient(TeradataMdsWsClient.java:43)

at com.teradata.datatools.dependencies.mdsimpl.TeradataInterdependencies.(TeradataInterdependencies.java:122)

... 31 more

Caused by: com.teradata.mdsws.access.MetaException_Exception: META_E_UNKNOWN_USER

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)

at java.lang.reflect.Constructor.newInstance(Unknown Source)

at com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createException(Unknown Source)

at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(Unknown Source)

at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(Unknown Source)

at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(Unknown Source)

at $Proxy32.getClassObjectKeys(Unknown Source)

at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsClient.getObjectKeys(TeradataMdsWsClient.java:287)

... 35 more




2 REPLIES
N/A

Re: Teradata studio connection to mds : Could not log on user

see log file

03/09/2013 14:09:25|PID-1808|THREAD-804|4|CMetaActionProcessor::QueryPollItems:  POLLING

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CMetaStorage::SInitialize(verify=1,useUTF16=0,MDS Version=14.00.00.003) ENTER

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CMetaTransaction: STxSetDefaultLocking - setting default locking to ACCESS, conn=0

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CDBAccess::DBSetSessionDefaultLocking(bAccess=1, conn=0)   ENTER

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CDBAccess::DBSetSessionDefaultLocking [Result=S_OK]

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CMetaTransaction::STxInitialize(dsn=<null>,user=<null>,RunQuiet=1,useUTF16=0)   ENTER

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CMetaTransaction::ConnectToDatabase(dsn=<null>,user=<null>)   ENTER

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CDBAccess::DBConnect(num=2,dsn=TD_MDS_DEVLP,user=mdsadmin,mode=1,defaultDB=util_mds,sessionCharSet=(null),runQuiet=1) ENTER

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CDBAccess::DBConnect: Connecting to database (DSN, user) (TD_MDS_DEVLP, mdsadmin)

03/09/2013 14:09:25|PID-1808|THREAD-804|5|CDBAccess::DBConnect: Connect string = (dsn=TD_MDS_DEVLP;uid=mdsadmin;SessionMode=Teradata;DateTimeFormat=IAA;DefaultDatabase=util_mds;UseIntegratedSecurity=N;Reconnect=Y;QUIETMODE=YES;pwd=*)

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::AllocStmtHandle: allocated statement handle = 0x02fa63f0, connection=0x02fd96b8

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::DBSetSessionDefaultLocking(bAccess=1, conn=0)   ENTER

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CDBAccess::GetTeradataVersion: version='14.00.0105  14.00.01.05'

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CategorizeODBCTeradataVersion returns 140000

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=75, SQL=SET SESSION CHARACTERISTICS AS TRANSACTION ISOLATION LEVEL READ UNCOMMITTED

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::DBSetSessionDefaultLocking [Result=S_OK]

03/09/2013 14:09:26|PID-1808|THREAD-804|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::DBConnect: Connect string = (dsn=TD_MDS_DEVLP;uid=mdsadmin;SessionMode=Teradata;DateTimeFormat=IAA;DefaultDatabase=util_mds;UseIntegratedSecurity=N;Reconnect=Y;QUIETMODE=YES;pwd=*)

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::AllocStmtHandle: allocated statement handle = 0x02fa60c8, connection=0x02fd99a0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=1

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CDBAccess::GetTeradataVersion: version='14.00.0105  14.00.01.05'

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::DBConnect - PreTeradata12 = NO

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::DBConnect [Result=S_OK]

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::ConnectToDatabase [Result=S_OK]

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaTransaction::STxInitialize [Result=S_OK]

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::GetSessionDatabaseName returns 'UTIL_MDS'

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SInitialize - repository database = 'UTIL_MDS'

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CDBAccess::GetODBCDriverVersion: version=14.00.00.02

03/09/2013 14:09:26|PID-1808|THREAD-804|5|ValidateODBCDriverVersion [Result=S_OK]

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CDBAccess::GetTeradataVersion: version='14.00.0105  14.00.01.05'

03/09/2013 14:09:26|PID-1808|THREAD-804|5|ReformatODBCTeradataVersion(14.00.0105  14.00.01.05) returns '14.00.0105  14.00.01.05', status=S_OK

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CategorizeODBCTeradataVersion returns 140000

03/09/2013 14:09:26|PID-1808|THREAD-804|5|ValidateODBCTeradataVersion [Result=S_OK]

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=82, SQL=LOCKING ROW FOR ACCESS SEL Version, MetamigrateProgress FROM metaroot WHERE loid=1

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CMetaStorage::SInitialize: MDS Repository Version = 14000000

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=122, SQL=LOCKING ROW FOR ACCESS SEL VersioningSupport, RetainBusinessInformation, RepositoryUsesFallback FROM metaroot WHERE loid=1

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CMetaStorage::SInitialize: VersioningSupport = DATA VERSIONING ACTIVE

03/09/2013 14:09:26|PID-1808|THREAD-804|4|CMetaStorage::SInitialize: RetainBusinessInformation = 1

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SInitialize: RepositoryUsesFallback = D: default, no fallback clause on CREATE TABLE

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SCopyMetaLockorderingsTable ENTER

03/09/2013 14:09:26|PID-1808|THREAD-804|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = OFF for conn=0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=77, SQL=LOCKING metalockingorder FOR ACCESS SEL loid, lockorder FROM metalockingorder

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 134 rows in result set, 134 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaTransaction::GetResultRows: # rows retrieved=134

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaTransaction::AttemptCommitRollback: Committing transaction 0 on logical connection 0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CDBAccess::DBCommit: SQLEndTran(Commit) successful on connection=0

03/09/2013 14:09:26|PID-1808|THREAD-804|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCopyMetaLockorderingsTable [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=12, SQL=HELP SESSION

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::GetSessionCharacterSet returns 'ASCII'

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SInitialize [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SSetOwnerID - set owner=500, IsSuperUser=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=48, SQL=SEL ActionPollMinutes FROM metaroot WHERE loid=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=40, SQL=SEL AuditFlag FROM metaroot WHERE loid=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SGetClassInstances ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::AddLocksSGetClassInstances ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SGetLoidFromLoidOrGoid(0,type=Class)   ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=138, SQL=LOCKING metaid FOR ACCESS SEL loid, VersionNumber FROM metaid WHERE goid='C3AD1311472C11D19E850000C071AADA'XBF order by VersionNumber DESC

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaTransaction::GetResultRows: # rows retrieved=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SGetLoidFromLoidOrGoid returns S_OK, loid=1001

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=308, SQL=LOCKING ROW FOR ACCESS SEL loid,serial,SuperClasses,VersioningSupport,DescriptionRequired,c.goid,name,UniqueNamesFlag,DerivedClass,BaseClassId,OwnerID,SecurityProfileID,AbstractClass,Properties, PredecessorVersionLoid,BaseVersionLoid,PublishState,VersionNumber, IsFrozen  FROM metaclassdesc c WHERE loid=1001

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaClassCache::GetClassCacheItem(1001) returns S_OK

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::AddLocksSGetClassInstances [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::FetchSetCollectionObjects(startNum=0,returnCount=0,checkForDupes=0)   ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x02fa63f0, Conn=0, len=69, SQL=SEL a.* FROM metaclass_1001 a  WHERE a.PublishState=2 ORDER BY a.name

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 0 rows fetched, fetch UTF16=0, format=2

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaTransaction::SSetupResults: # rows in spool file=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::FetchSetCollectionObjects returns S_OK, numEntries=1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::FreeParameters enter

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::SGetClassInstances [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|4|CMetaActionProcessor::Poll: Next Action is 0.

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::~CMetaStorage  ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::Finish ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::Finish [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CMetaStorage::~CMetaStorage  EXIT

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::~CDBAccess ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::DBDisconnect ENTER

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::DBDisconnect: Calling SQLDisconnect for Conn 0

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::DBDisconnect [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::DBDisconnect: Calling SQLDisconnect for Conn 1

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::DBDisconnect [Result=S_OK]

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::DBDisconnect   EXIT

03/09/2013 14:09:27|PID-1808|THREAD-804|5|CDBAccess::~CDBAccess  EXIT

03/09/2013 14:09:27|PID-1808|THREAD-804|4|CMetaActionProcessor::Sleeping for 1800 seconds

03/09/2013 14:11:32|PID-612|THREAD-6128|5|metaadm.dll Initializing!

03/09/2013 14:11:32|PID-612|THREAD-6128|5|Metaadm: looking for resource library metaadm_ENU

03/09/2013 14:11:32|PID-612|THREAD-6128|5|Primary and Sublang failed, Loading English resource DLL!

03/09/2013 14:11:32|PID-612|THREAD-6128|5|metaconf.dll Initializing!

03/09/2013 14:11:32|PID-612|THREAD-6128|5|Metaconf: looking for resource library metaconf_ENU

03/09/2013 14:11:32|PID-612|THREAD-6128|5|Try def sublanguage: looking for resource library metaconf_ENU

03/09/2013 14:11:32|PID-612|THREAD-6128|5|No resource DLLs found, Using EXE!

03/09/2013 14:11:32|PID-612|THREAD-6128|5|Metamgr: looking for resource library metamgr_ENU

03/09/2013 14:11:32|PID-612|THREAD-6128|5|No resource DLLs found, use EXE!

03/09/2013 14:11:35|PID-612|THREAD-6128|5|GetDatabaseRegistryInfo    ENTER

03/09/2013 14:11:35|PID-612|THREAD-6128|5|GetDatabaseRegistryInfo returns status=S_OK, dsn=TD_MDS_DEVLP, user=mdsadmin, dbname=util_mds

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|[metajni] CMetaJNIRepository::SignOn()  calling CMetaRepository::SignOn

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaRepository::SignOn(user=)   ENTER

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaRepository::AuthenticateUser(user=)   ENTER

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CSkeletonChannel::AuthenticateUser() Entering API.

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = OFF for conn=0

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaStorage::AddLocksSAuthenticate ENTER

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=33, SQL=LOCK TABLE metarelation_206 READ;

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=25, SQL=LOCK TABLE metauser READ;

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaStorage::AddLocksSAuthenticate [Result=S_OK]

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CMetaSecurity::AuthenticateUser()  ENTER

03/09/2013 14:13:20|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=65, SQL=SEL UserPassword,loid,UserExtensions FROM metauser WHERE name='';

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::SetupResultsTable  returns status=S_OK, 0 rows in result set, 0 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CMetaTransactionEntry::TxEntryError - setting transaction error for connection ODBC_MAIN, TxSerial=0

03/09/2013 14:13:21|PID-4140|THREAD-4912|2|80007003 CMetaTransaction::GetResultRow: Expected exactly one row returned by SELECT but actually received 0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CMetaTransactionEntry::TxEntryError - setting transaction error for connection ODBC_MAIN, TxSerial=0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CMetaTransaction::AttemptCommitRollback: Rolling back transaction 0 on logical connection 0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::Rollback: SQLEndTran(Rollback) called for connection=0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::Rollback [Result=S_OK]

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CMetaStorage::SCommit [Result=META_E_TX_ROLLED_BACK]

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=69, SQL=LOCKING ROW FOR ACCESS SEL ActivityLogFlag FROM metaroot WHERE loid=1

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=374, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,456355147,'','Repository SignOn','AuthenticateUser','',456355147,1,'',0,'',0,1,'',0,'','META_E_UNKNOWN_USER','N')

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|CSkeletonChannel::AuthenticateUser [Result=META_E_UNKNOWN_USER]

03/09/2013 14:13:21|PID-4140|THREAD-4912|4|CMetaRepository::AuthenticateUser (User=) NOT ALLOWED

03/09/2013 14:13:21|PID-4140|THREAD-4912|2|CMetaRepository::AuthenticateUser [Result=META_E_UNKNOWN_USER]

03/09/2013 14:13:21|PID-4140|THREAD-4912|2|CMetaRepository::SignOn [Result=META_E_UNKNOWN_USER]

03/09/2013 14:13:21|PID-4140|THREAD-4912|2|[metajni] CMetaJNIRepository::SignOn()  FAILED [0x8000703b]

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <>

03/09/2013 14:13:21|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <>

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIRepository::SignOn()  calling CMetaRepository::SignOn

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaRepository::SignOn(user=metasu)   ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaRepository::AuthenticateUser(user=metasu)   ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CSkeletonChannel::AuthenticateUser() Entering API.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::AddLocksSAuthenticate ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7bf680, Conn=0, len=33, SQL=LOCK TABLE metarelation_206 READ;

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7bf680, Conn=0, len=25, SQL=LOCK TABLE metauser READ;

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::AddLocksSAuthenticate [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaSecurity::AuthenticateUser(metasu)  ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7bf680, Conn=0, len=71, SQL=SEL UserPassword,loid,UserExtensions FROM metauser WHERE name='metasu';

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7bf680, Conn=0, len=58, SQL=SEL originid FROM metarelation_206 WHERE destinationid=500

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::SetupResultsTable  returns status=S_OK, 2 rows in result set, 2 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaTransaction::GetResultRows: # rows retrieved=2

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7d08d8, Conn=1, len=144, SQL=INS metalogons(EntryID,EntryDate,EntryTime,SystemName,MDSUserName,MDSUserLoid) VALUES('A67B45131871441ABEABA42C6ED1A185'XB,,, '', 'metasu', 500)

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaTransaction::AttemptCommitRollback: Committing transaction 0 on logical connection 0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::DBCommit: SQLEndTran(Commit) successful on connection=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7d08d8, Conn=1, len=69, SQL=LOCKING ROW FOR ACCESS SEL ActivityLogFlag FROM metaroot WHERE loid=1

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7d08d8, Conn=1, len=357, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOn','AuthenticateUser','metasu',500,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CSkeletonChannel::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaRepository::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaRepository::SignOn [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIRepository::SignOn()  SUCCEEDED.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|[metajni] CMetaJNIClassInfo::GetObjectsKeys()  calling CMetaObject::GetClassObjectsByProperty

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaObject::GetClassObjectsByProperty entered

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CSkeletonChannel::GetClassObjectsByProperty() Entering API.

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SGetClassInstances ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=1

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::AddLocksSGetClassInstances ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SGetLoidFromLoidOrGoid(1001,type=Class)   ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SGetLoidFromLoidOrGoid returns S_OK, loid=1001

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaClassCache::GetClassCacheItem returns existing class info for class=1001

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaClassCache::GetClassCacheItem(1001) returns S_OK

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::AddLocksSGetClassInstances [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|metastorage::ExpandPropertyFilter(#entries=3)

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|metastorage::ExpandPropertyFilter(#entries=3)

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::BuildActivityLogWhereClause: ("ISFROZEN"=0 OR ("LOID"<10000 AND "LOID">0))

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::FetchSetCollectionObjects(startNum=0,returnCount=0,checkForDupes=0)   ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7bf680, Conn=0, len=196, SQL=SEL a.loid,a.Name,a.VersionNumber,a.PublishState,a.OwnerID,a.SecurityProfileId FROM metaclass_1001 a  WHERE a.PublishState=2 AND (a."ISFROZEN"=0 OR (a."LOID"<10000 AND a."LOID">0)) ORDER BY a.name

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 0 rows fetched, fetch UTF16=0, format=2

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaTransaction::SSetupResults: # rows in spool file=1

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::FetchSetCollectionObjects returns S_OK, numEntries=1

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::FreeParameters enter

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CMetaStorage::SGetClassInstances [Result=S_OK]

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CSkeletonChannel::GetClassObjectsByProperty returns S_OK, object count=1

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7d08d8, Conn=1, len=431, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Read Collection','CMetaObject::GetClassObjectsByProperty','',0,0,'DatabaseSystem',1001,'',0,1,'',0,'("ISFROZEN"=0 OR ("LOID"<10000 AND "LOID">0))','OK','N')

03/09/2013 14:14:03|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CMetaObject::GetClassObjectsByProperty [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] CMetaJNIClassInfo::GetObjectsKeys()  SUCCEEDED.

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] SetJListSize<>()  jlist capacity set to 1

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <NULL>

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] CMetaJNIRepository::SignOff()  calling CMetaRepository::SignOff

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CMetaRepository::SignOff(user=metasu)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CSkeletonChannel::SignOff() Entering API.

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=0

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7d08d8, Conn=1, len=94, SQL=LOCKING metalogons FOR WRITE DEL metalogons WHERE EntryID='A67B45131871441ABEABA42C6ED1A185'XB

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7d08d8, Conn=1, len=341, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOff','SignOff','',0,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CSkeletonChannel::SignOff [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|CMetaRepository::SignOff [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] CMetaJNIRepository::SignOff()  SUCCEEDED.

03/09/2013 14:14:04|PID-4140|THREAD-3476|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIRepository::SignOn()  calling CMetaRepository::SignOn

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaRepository::SignOn(user=metasu)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaRepository::AuthenticateUser(user=metasu)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CSkeletonChannel::AuthenticateUser() Entering API.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::AddLocksSAuthenticate ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7b9708, Conn=0, len=33, SQL=LOCK TABLE metarelation_206 READ;

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7b9708, Conn=0, len=25, SQL=LOCK TABLE metauser READ;

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::AddLocksSAuthenticate [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaSecurity::AuthenticateUser(metasu)  ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7b9708, Conn=0, len=71, SQL=SEL UserPassword,loid,UserExtensions FROM metauser WHERE name='metasu';

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7b9708, Conn=0, len=58, SQL=SEL originid FROM metarelation_206 WHERE destinationid=500

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::SetupResultsTable  returns status=S_OK, 2 rows in result set, 2 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaTransaction::GetResultRows: # rows retrieved=2

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7efee8, Conn=1, len=144, SQL=INS metalogons(EntryID,EntryDate,EntryTime,SystemName,MDSUserName,MDSUserLoid) VALUES('DA5B05AAD5434B6AA4B2A1841E77F45F'XB,,, '', 'metasu', 500)

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaTransaction::AttemptCommitRollback: Committing transaction 0 on logical connection 0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::DBCommit: SQLEndTran(Commit) successful on connection=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7efee8, Conn=1, len=69, SQL=LOCKING ROW FOR ACCESS SEL ActivityLogFlag FROM metaroot WHERE loid=1

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7efee8, Conn=1, len=357, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOn','AuthenticateUser','metasu',500,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CSkeletonChannel::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaRepository::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaRepository::SignOn [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIRepository::SignOn()  SUCCEEDED.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|[metajni] CMetaJNIClassInfo::GetObjectsKeys()  calling CMetaObject::GetClassObjectsByProperty

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaObject::GetClassObjectsByProperty entered

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CSkeletonChannel::GetClassObjectsByProperty() Entering API.

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SGetClassInstances ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=1

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::AddLocksSGetClassInstances ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SGetLoidFromLoidOrGoid(1001,type=Class)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SGetLoidFromLoidOrGoid returns S_OK, loid=1001

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaClassCache::GetClassCacheItem returns existing class info for class=1001

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaClassCache::GetClassCacheItem(1001) returns S_OK

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::AddLocksSGetClassInstances [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|metastorage::ExpandPropertyFilter(#entries=3)

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|metastorage::ExpandPropertyFilter(#entries=3)

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::BuildActivityLogWhereClause: ("ISFROZEN"=0 OR ("LOID"<10000 AND "LOID">0))

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::FetchSetCollectionObjects(startNum=0,returnCount=0,checkForDupes=0)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7b9708, Conn=0, len=196, SQL=SEL a.loid,a.Name,a.VersionNumber,a.PublishState,a.OwnerID,a.SecurityProfileId FROM metaclass_1001 a  WHERE a.PublishState=2 AND (a."ISFROZEN"=0 OR (a."LOID"<10000 AND a."LOID">0)) ORDER BY a.name

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 0 rows fetched, fetch UTF16=0, format=2

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaTransaction::SSetupResults: # rows in spool file=1

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::FetchSetCollectionObjects returns S_OK, numEntries=1

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::FreeParameters enter

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CMetaStorage::SGetClassInstances [Result=S_OK]

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CSkeletonChannel::GetClassObjectsByProperty returns S_OK, object count=1

03/09/2013 14:14:04|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7efee8, Conn=1, len=431, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Read Collection','CMetaObject::GetClassObjectsByProperty','',0,0,'DatabaseSystem',1001,'',0,1,'',0,'("ISFROZEN"=0 OR ("LOID"<10000 AND "LOID">0))','OK','N')

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CMetaObject::GetClassObjectsByProperty [Result=S_OK]

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] CMetaJNIClassInfo::GetObjectsKeys()  SUCCEEDED.

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] SetJListSize<>()  jlist capacity set to 1

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <NULL>

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] CMetaJNIRepository::SignOff()  calling CMetaRepository::SignOff

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CMetaRepository::SignOff(user=metasu)   ENTER

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CSkeletonChannel::SignOff() Entering API.

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=0

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7efee8, Conn=1, len=94, SQL=LOCKING metalogons FOR WRITE DEL metalogons WHERE EntryID='DA5B05AAD5434B6AA4B2A1841E77F45F'XB

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7efee8, Conn=1, len=341, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOff','SignOff','',0,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CSkeletonChannel::SignOff [Result=S_OK]

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|CMetaRepository::SignOff [Result=S_OK]

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] CMetaJNIRepository::SignOff()  SUCCEEDED.

03/09/2013 14:14:05|PID-4140|THREAD-3820|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIRepository::SignOn()  calling CMetaRepository::SignOn

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaRepository::SignOn(user=metasu)   ENTER

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaRepository::AuthenticateUser(user=metasu)   ENTER

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CSkeletonChannel::AuthenticateUser() Entering API.

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = OFF for conn=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaStorage::AddLocksSAuthenticate ENTER

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78ef18, Conn=0, len=33, SQL=LOCK TABLE metarelation_206 READ;

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78ef18, Conn=0, len=25, SQL=LOCK TABLE metauser READ;

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaStorage::AddLocksSAuthenticate [Result=S_OK]

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaSecurity::AuthenticateUser(metasu)  ENTER

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78ef18, Conn=0, len=71, SQL=SEL UserPassword,loid,UserExtensions FROM metauser WHERE name='metasu';

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78ef18, Conn=0, len=58, SQL=SEL originid FROM metarelation_206 WHERE destinationid=500

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::SetupResultsTable  returns status=S_OK, 2 rows in result set, 2 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaTransaction::GetResultRows: # rows retrieved=2

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78f520, Conn=1, len=144, SQL=INS metalogons(EntryID,EntryDate,EntryTime,SystemName,MDSUserName,MDSUserLoid) VALUES('54D21551B7614379BE1ECD56DC7D6C52'XB,,, '', 'metasu', 500)

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaTransaction::AttemptCommitRollback: Committing transaction 0 on logical connection 0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::DBCommit: SQLEndTran(Commit) successful on connection=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78f520, Conn=1, len=69, SQL=LOCKING ROW FOR ACCESS SEL ActivityLogFlag FROM metaroot WHERE loid=1

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78f520, Conn=1, len=357, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOn','AuthenticateUser','metasu',500,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CSkeletonChannel::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaRepository::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaRepository::SignOn [Result=S_OK]

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIRepository::SignOn()  SUCCEEDED.

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|[metajni] CMetaJNIClassInfo::GetObjectsKeys()  calling CMetaObject::GetClassObjectsByProperty

03/09/2013 14:14:15|PID-4140|THREAD-3960|5|CMetaObject::GetClassObjectsByProperty entered

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CSkeletonChannel::GetClassObjectsByProperty() Entering API.

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SGetClassInstances ENTER

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=1

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::AddLocksSGetClassInstances ENTER

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SGetLoidFromLoidOrGoid(1001,type=Class)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SGetLoidFromLoidOrGoid returns S_OK, loid=1001

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaClassCache::GetClassCacheItem returns existing class info for class=1001

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaClassCache::GetClassCacheItem(1001) returns S_OK

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::AddLocksSGetClassInstances [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|metastorage::ExpandPropertyFilter(#entries=3)

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|metastorage::ExpandPropertyFilter(#entries=3)

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::BuildActivityLogWhereClause: ("ISFROZEN"=0 OR ("LOID"<10000 AND "LOID">0))

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::FetchSetCollectionObjects(startNum=0,returnCount=0,checkForDupes=0)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78ef18, Conn=0, len=196, SQL=SEL a.loid,a.Name,a.VersionNumber,a.PublishState,a.OwnerID,a.SecurityProfileId FROM metaclass_1001 a  WHERE a.PublishState=2 AND (a."ISFROZEN"=0 OR (a."LOID"<10000 AND a."LOID">0)) ORDER BY a.name

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 0 rows fetched, fetch UTF16=0, format=2

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaTransaction::SSetupResults: # rows in spool file=1

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::FetchSetCollectionObjects returns S_OK, numEntries=1

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::FreeParameters enter

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SGetClassInstances [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CSkeletonChannel::GetClassObjectsByProperty returns S_OK, object count=1

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78f520, Conn=1, len=431, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Read Collection','CMetaObject::GetClassObjectsByProperty','',0,0,'DatabaseSystem',1001,'',0,1,'',0,'("ISFROZEN"=0 OR ("LOID"<10000 AND "LOID">0))','OK','N')

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaObject::GetClassObjectsByProperty [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] CMetaJNIClassInfo::GetObjectsKeys()  SUCCEEDED.

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] SetJListSize<>()  jlist capacity set to 1

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <NULL>

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] CMetaJNIRepository::SignOff()  calling CMetaRepository::SignOff

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaRepository::SignOff(user=metasu)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CSkeletonChannel::SignOff() Entering API.

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=0

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78f520, Conn=1, len=94, SQL=LOCKING metalogons FOR WRITE DEL metalogons WHERE EntryID='54D21551B7614379BE1ECD56DC7D6C52'XB

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a78f520, Conn=1, len=341, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOff','SignOff','',0,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CSkeletonChannel::SignOff [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|CMetaRepository::SignOff [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] CMetaJNIRepository::SignOff()  SUCCEEDED.

03/09/2013 14:14:16|PID-4140|THREAD-3960|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|[metajni] CMetaJNIRepository::SignOn()  calling CMetaRepository::SignOn

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaRepository::SignOn(user=metasu)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaRepository::AuthenticateUser(user=metasu)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CSkeletonChannel::AuthenticateUser() Entering API.

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaStorage::AddLocksSAuthenticate ENTER

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=33, SQL=LOCK TABLE metarelation_206 READ;

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=25, SQL=LOCK TABLE metauser READ;

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaStorage::AddLocksSAuthenticate [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaSecurity::AuthenticateUser(metasu)  ENTER

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=71, SQL=SEL UserPassword,loid,UserExtensions FROM metauser WHERE name='metasu';

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=58, SQL=SEL originid FROM metarelation_206 WHERE destinationid=500

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::SetupResultsTable  returns status=S_OK, 2 rows in result set, 2 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaTransaction::GetResultRows: # rows retrieved=2

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=144, SQL=INS metalogons(EntryID,EntryDate,EntryTime,SystemName,MDSUserName,MDSUserLoid) VALUES('8F9F375319354574AC08C786A17FB4F5'XB,,, '', 'metasu', 500)

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaTransaction::AttemptCommitRollback: Committing transaction 0 on logical connection 0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::DBCommit: SQLEndTran(Commit) successful on connection=0

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=357, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOn','AuthenticateUser','metasu',500,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:16|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CSkeletonChannel::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaRepository::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaRepository::SignOn [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIRepository::SignOn()  SUCCEEDED.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] tdmds::SetPropertyFilter()  Getting filter number 0

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] tdmds::SetPropertyFilter()  logical op value = META_AND

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] tdmds::SetPropertyFilter()  MetaFilterItem.op value = 0

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Property ID = -13.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Property Name = .

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Prop object type <java.lang.Short>.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Short Value = 2

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <java.lang.Short>

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] tdmds::JObjKeys2Vector()  SortKeys list is <null>

03/09/2013 14:14:17|PID-4140|THREAD-4912|4|[metajni] tdmds::SetRangeVaules()  Range parameter is <null>

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIObjectCollections::JOidList2OidVect()  joidList size = 0

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::SetTCHARFromJstr()  entered.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::SetTCHARFromJstr()  jstring is null; returning.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIClassInfo::GetObjectsImpl()  calling CMetaObject::GetClassObjectRange

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaObject::GetClassObjectRange entered

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CSkeletonChannel::GetClassObjectRange() Entering API.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SGetClassObjectRange ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SGetClassInstances ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=1

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::AddLocksSGetClassInstances ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SGetLoidFromLoidOrGoid(1001,type=Class)   ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SGetLoidFromLoidOrGoid returns S_OK, loid=1001

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaClassCache::GetClassCacheItem returns existing class info for class=1001

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaClassCache::GetClassCacheItem(1001) returns S_OK

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::AddLocksSGetClassInstances [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|metastorage::ExpandPropertyFilter(#entries=1)

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|metastorage::ExpandPropertyFilter(#entries=1)

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::BuildActivityLogWhereClause: ("PUBLISHSTATE"=2)

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::FetchSetCollectionObjects(startNum=0,returnCount=0,checkForDupes=0)   ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b130, Conn=0, len=94, SQL=SEL a.* FROM metaclass_1001 a  WHERE a.PublishState=2 AND (a."PUBLISHSTATE"=2) ORDER BY a.name

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 0 rows fetched, fetch UTF16=0, format=2

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaTransaction::SSetupResults: # rows in spool file=1

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::FetchSetCollectionObjects returns S_OK, numEntries=1

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::FreeParameters enter

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SGetClassInstances [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SGetClassObjectRange [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CSkeletonChannel::GetClassObjectRange returns S_OK, object count=1

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaObject::GetClassObjectRange TotalCount=1

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=398, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Read Collection','CMetaObject::GetClassObjectRange','',0,0,'DatabaseSystem',1001,'',0,0,'',0,'("PUBLISHSTATE"=2)','OK','N')

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaObject::GetClassObjectRange [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIClassInfo::GetObjectsImpl()  SUCCEEDED.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] PopulateList<>()  t

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] SetJListSize<>()  jlist capacity set to 1

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <NULL>

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIRepository::SignOff()  calling CMetaRepository::SignOff

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaRepository::SignOff(user=metasu)   ENTER

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CSkeletonChannel::SignOff() Entering API.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=0

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=94, SQL=LOCKING metalogons FOR WRITE DEL metalogons WHERE EntryID='8F9F375319354574AC08C786A17FB4F5'XB

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a79b738, Conn=1, len=341, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOff','SignOff','',0,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CSkeletonChannel::SignOff [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|CMetaRepository::SignOff [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIRepository::SignOff()  SUCCEEDED.

03/09/2013 14:14:17|PID-4140|THREAD-4912|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|[metajni] CMetaJNIRepository::SignOn()  calling CMetaRepository::SignOn

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaRepository::SignOn(user=metasu)   ENTER

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaRepository::AuthenticateUser(user=metasu)   ENTER

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CSkeletonChannel::AuthenticateUser() Entering API.

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = OFF for conn=0

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=0 chkKeepAlive=0

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaTransaction::STxBegin: Opening new transaction on logical connection 0

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaTransaction::STxBegin - txDepth = 1

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaStorage::AddLocksSAuthenticate ENTER

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a794798, Conn=0, len=33, SQL=LOCK TABLE metarelation_206 READ;

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a794798, Conn=0, len=25, SQL=LOCK TABLE metauser READ;

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaStorage::AddLocksSAuthenticate [Result=S_OK]

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CMetaSecurity::AuthenticateUser(metasu)  ENTER

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a794798, Conn=0, len=71, SQL=SEL UserPassword,loid,UserExtensions FROM metauser WHERE name='metasu';

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a794798, Conn=0, len=58, SQL=SEL originid FROM metarelation_206 WHERE destinationid=500

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:17|PID-4140|THREAD-3720|5|CDBAccess::SetupResultsTable  returns status=S_OK, 2 rows in result set, 2 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaTransaction::GetResultRows: # rows retrieved=2

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7aa520, Conn=1, len=144, SQL=INS metalogons(EntryID,EntryDate,EntryTime,SystemName,MDSUserName,MDSUserLoid) VALUES('B3F02684ED0743A981E96066744CD57D'XB,,, '', 'metasu', 500)

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCheckDatabase MainTXMode=OFF txDepth=1 chkKeepAlive=0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaTransaction::AttemptCommitRollback: Committing transaction 0 on logical connection 0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::DBCommit: SQLEndTran(Commit) successful on connection=0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7aa520, Conn=1, len=69, SQL=LOCKING ROW FOR ACCESS SEL ActivityLogFlag FROM metaroot WHERE loid=1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 1 rows fetched, fetch UTF16=0, format=0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7aa520, Conn=1, len=357, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOn','AuthenticateUser','metasu',500,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CSkeletonChannel::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaRepository::AuthenticateUser [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaRepository::SignOn [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIRepository::SignOn()  SUCCEEDED.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] tdmds::SetPropertyFilter()  Getting filter number 0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] tdmds::SetPropertyFilter()  logical op value = META_AND

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] tdmds::SetPropertyFilter()  MetaFilterItem.op value = 0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Property ID = -13.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Property Name = .

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Prop object type <java.lang.Short>.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIPropertyItem::SetPropertyAttributes()  Short Value = 2

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <java.lang.Short>

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] tdmds::JObjKeys2Vector()  SortKeys list is <null>

03/09/2013 14:14:18|PID-4140|THREAD-3720|4|[metajni] tdmds::SetRangeVaules()  Range parameter is <null>

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIObjectCollections::JOidList2OidVect()  joidList size = 0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::SetTCHARFromJstr()  entered.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::SetTCHARFromJstr()  jstring is null; returning.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIClassInfo::GetObjectsImpl()  calling CMetaObject::GetClassObjectRange

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaObject::GetClassObjectRange entered

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CSkeletonChannel::GetClassObjectRange() Entering API.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SGetClassObjectRange ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SGetClassInstances ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|DBSetTxMode: set SQL_ATTR_AUTOCOMMIT = ON for conn=0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::AddLocksSGetClassInstances ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SGetLoidFromLoidOrGoid(1001,type=Class)   ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SGetLoidFromLoidOrGoid returns S_OK, loid=1001

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaClassCache::GetClassCacheItem returns existing class info for class=1001

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaClassCache::GetClassCacheItem(1001) returns S_OK

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::AddLocksSGetClassInstances [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|metastorage::ExpandPropertyFilter(#entries=1)

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|metastorage::ExpandPropertyFilter(#entries=1)

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::BuildActivityLogWhereClause: ("PUBLISHSTATE"=2)

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::FetchSetCollectionObjects(startNum=0,returnCount=0,checkForDupes=0)   ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a794798, Conn=0, len=94, SQL=SEL a.* FROM metaclass_1001 a  WHERE a.PublishState=2 AND (a."PUBLISHSTATE"=2) ORDER BY a.name

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::SetupResultsTable  returns status=S_OK, 1 rows in result set, 0 rows fetched, fetch UTF16=0, format=2

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaTransaction::SSetupResults: # rows in spool file=1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::FetchSetCollectionObjects returns S_OK, numEntries=1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::FreeParameters enter

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCommit(error=0)   ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCommit - no existing transaction

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCommit [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SGetClassInstances [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SGetClassObjectRange [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CSkeletonChannel::GetClassObjectRange returns S_OK, object count=1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaObject::GetClassObjectRange TotalCount=1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7aa520, Conn=1, len=398, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Read Collection','CMetaObject::GetClassObjectRange','',0,0,'DatabaseSystem',1001,'',0,0,'',0,'("PUBLISHSTATE"=2)','OK','N')

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaObject::GetClassObjectRange [Result=S_OK]

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIClassInfo::GetObjectsImpl()  SUCCEEDED.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] PopulateList<>()  t

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] SetJListSize<>()  jlist capacity set to 1

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <NULL>

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::ctor()  entered.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|[metajni] CMetaJNIRepository::SignOff()  calling CMetaRepository::SignOff

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaRepository::SignOff(user=metasu)   ENTER

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CSkeletonChannel::SignOff() Entering API.

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CMetaStorage::SCheckDatabase MainTXMode=ON txDepth=0 chkKeepAlive=0

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7aa520, Conn=1, len=94, SQL=LOCKING metalogons FOR WRITE DEL metalogons WHERE EntryID='B3F02684ED0743A981E96066744CD57D'XB

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CSkeletonChannel::ActivityLogIsActive() Entering API

03/09/2013 14:14:18|PID-4140|THREAD-3720|5|CDBAccess::RunSql: Before SQLExecDirect Stmt=0x1a7aa520, Conn=1, len=341, SQL=INSERT metaactivitylog(EntryDate,EntryTime,CallerLoid,CallerName,AccessType,APICalled,ObjectName,ObjectLoid,ObjectVersion,ClassName,ClassLoid,ModelName,ModelLoid,ObjectCount,RelationshipName,RelationshipLoid,SearchCondition,APIResult,AccessViolation) VALUES (,,500,'metasu','Repository SignOff','SignOff','',0,1,'',0,'',0,1,'',0,'','OK','N')

03/09/2013 14:14:19|PID-4140|THREAD-3720|5|CDBAccess::RunSql: After SQLExecDirect

03/09/2013 14:14:19|PID-4140|THREAD-3720|5|CSkeletonChannel::SignOff [Result=S_OK]

03/09/2013 14:14:19|PID-4140|THREAD-3720|5|CMetaRepository::SignOff [Result=S_OK]

03/09/2013 14:14:19|PID-4140|THREAD-3720|5|[metajni] CMetaJNIRepository::SignOff()  SUCCEEDED.

03/09/2013 14:14:19|PID-4140|THREAD-3720|5|[metajni] CMetaJNIString::~CMetaJNIString()  for <metasu>

Teradata Employee

Re: Teradata studio connection to mds : Could not log on user

From looking at the log, it looks like the first attempt to logon fails wihout a user name or password, but then it succeeds after that. So trying to find out what was done by the user at that point. What is the sequence of events you have done within Studio? What objects are you trying to show in the Interdependency Browser? Are you invoking from the 'Show Interdependecies' menu option from the Data Source Explorer or from the toolbar in the Teradata View?  Also, what Web Server have you deployed the MDS Web Service in?