All Forums Teradata Studio
ak93 2 posts Joined 05/12
09 Mar 2013
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.TeradataInterdepen denciesException: Could not log on user
at com.teradata.datatools.dependencies.mdsimpl.TeradataIn terdependencies.(TeradataInterdependencies.java:134)
at com.teradata.datatools.dependencies.TeradataInterdepen dencies.getTeradataInterdependencies(TeradataInterdepe ndencies.java:33)
at com.teradata.datatools.dependencies.ui.ShowInterdepend encies.showInInterdepencencyBrowser(ShowInterdependenc ies.java:186)
at com.teradata.datatools.dependencies.ui.ShowInterdepend encies.run(ShowInterdependencies.java:134)
at org.eclipse.ui.internal.PluginAction.runWithEvent(Plug inAction.java:251)
at org.eclipse.jface.action.ActionContributionItem.handle WidgetSelection(ActionContributionItem.java:584)
at org.eclipse.jface.action.ActionContributionItem.access $2(ActionContributionItem.java:501)
at org.eclipse.jface.action.ActionContributionItem$5.hand leEvent(ActionContributionItem.java:411)
at org.eclipse.swt.widgets.EventTable.sendEvent(EventTabl e.java:84)
at org.eclipse.swt.widgets.Widget.sendEvent(Widget.java: 1053)
at org.eclipse.swt.widgets.Display.runDeferredEvents(Disp lay.java:4165)
at org.eclipse.swt.widgets.Display.readAndDispatch(Displa y.java:3754)
at org.eclipse.ui.internal.Workbench.runEventLoop(Workben ch.java:2701)
at org.eclipse.ui.internal.Workbench.runUI(Workbench.java :2665)
at org.eclipse.ui.internal.Workbench.access$4(Workbench.j ava:2499)
at org.eclipse.ui.internal.Workbench$7.run(Workbench.java :679)
at org.eclipse.core.databinding.observable.Realm.runWithD efault(Realm.java:332)
at org.eclipse.ui.internal.Workbench.createAndRunWorkbenc h(Workbench.java:668)
at org.eclipse.ui.PlatformUI.createAndRunWorkbench(Platfo rmUI.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.EclipseAppLa uncher.runApplication(EclipseAppLauncher.java:110)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLa uncher.start(EclipseAppLauncher.java:79)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(Ec lipseStarter.java:344)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(Ec lipseStarter.java:179)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknow n 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.TeradataMdsWsClient Exception: Could not get object keys for objectType DatabaseSystem
at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsC lient.getObjectKeys(TeradataMdsWsClient.java:297)
at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsC lient.getObjectKeys(TeradataMdsWsClient.java:271)
at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsC lient.(TeradataMdsWsClient.java:104)
at com.teradata.datatools.mdswsclient.TeradataMdsWsClient .getTeradataMdsWsClient(TeradataMdsWsClient.java:43)
at com.teradata.datatools.dependencies.mdsimpl.TeradataIn terdependencies.(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.newInsta nce(Unknown Source)
at java.lang.reflect.Constructor.newInstance(Unknown Source)
at com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createE xception(Unknown Source)
at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.i nvoke(Unknown Source)
at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.i nvoke(Unknown Source)
at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(Unkn own Source)
at $Proxy32.getClassObjectKeys(Unknown Source)
at com.teradata.datatools.mdswsclient.impl.TeradataMdsWsC lient.getObjectKeys(TeradataMdsWsClient.java:287)
... 35 more

 

ak93 2 posts Joined 05/12
09 Mar 2013

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>
 

fgrimmer 553 posts Joined 04/09
11 Mar 2013

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?

You must sign in to leave a comment.