--- a/kerneltest/e32test/usbho/t_otgdi/src/testcase0677.cpp Wed Aug 18 11:08:29 2010 +0300
+++ b/kerneltest/e32test/usbho/t_otgdi/src/testcase0677.cpp Thu Sep 02 21:54:16 2010 +0300
@@ -1,4 +1,4 @@
-// Copyright (c) 2007-2009 Nokia Corporation and/or its subsidiary(-ies).
+// Copyright (c) 2007-2010 Nokia Corporation and/or its subsidiary(-ies).
// All rights reserved.
// This component and the accompanying materials are made available
// under the terms of the License "Eclipse Public License v1.0"
@@ -24,6 +24,10 @@
#include "testcaseroot.h"
#include "testcasewd.h"
#include "testcase0677.h"
+#include "OstTraceDefinitions.h"
+#ifdef OST_TRACE_COMPILER_IN_USE
+#include "testcase0677Traces.h"
+#endif
#define _REPEATS (oOpenIterations*3)
@@ -36,7 +40,10 @@
CTestCase0677* CTestCase0677::NewL(TBool aHost)
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_NEWL);
+ }
CTestCase0677* self = new (ELeave) CTestCase0677(aHost);
CleanupStack::PushL(self);
self->ConstructL();
@@ -48,7 +55,10 @@
CTestCase0677::CTestCase0677(TBool aHost)
: CTestCaseRoot(KTestCaseId, aHost)
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_CTESTCASE0677);
+ }
}
@@ -58,7 +68,10 @@
*/
void CTestCase0677::ConstructL()
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_CONSTRUCTL);
+ }
iWDTimer = CTestCaseWatchdog::NewL();
iRepeats = OPEN_REPEATS;
@@ -68,7 +81,10 @@
CTestCase0677::~CTestCase0677()
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_DCTESTCASE0677);
+ }
Cancel();
delete iWDTimer;
@@ -78,7 +94,10 @@
void CTestCase0677::ExecuteTestCaseL()
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_EXECUTETESTCASEL);
+ }
iCaseStep = EPreconditions;
iRepeats = KOperationRetriesMax; // VBus event rise retries
@@ -91,7 +110,10 @@
void CTestCase0677::DoCancel()
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_DOCANCEL);
+ }
// cancel our timer
iTimer.Cancel();
@@ -100,7 +122,10 @@
void CTestCase0677::CancelKB(CTestCaseRoot *pThis)
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_CANCELKB);
+ }
CTestCase0677 * p = REINTERPRET_CAST(CTestCase0677 *,pThis);
// cancel any pending call, and then complete our active obj with a cancel value
p->iConsole->ReadCancel();
@@ -110,7 +135,10 @@
void CTestCase0677::CancelNotify(CTestCaseRoot *pThis)
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_CANCELNOTIFY);
+ }
CTestCase0677 * p = REINTERPRET_CAST(CTestCase0677 *,pThis);
// cancel any pending call, and then complete our active obj with a timeout value
switch (p->iCancelWhat)
@@ -136,15 +164,23 @@
void CTestCase0677::DescribePreconditions()
{
test.Printf(_L("Using OET, connect oscilloscope chan.A to VBus\n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_DESCRIBEPRECONDITIONS, "Using OET, connect oscilloscope chan.A to VBus\n");
test.Printf(_L("Connect oscilloscope chan.B to D+\n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_DESCRIBEPRECONDITIONS_DUP01, "Connect oscilloscope chan.B to D+\n");
test.Printf(_L("Trigger once, 200mV, 100ms \n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_DESCRIBEPRECONDITIONS_DUP02, "Trigger once, 200mV, 100ms \n");
test.Printf(_L("Prepare to observe VBus, D+ pulse.\n\n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_DESCRIBEPRECONDITIONS_DUP03, "Prepare to observe VBus, D+ pulse.\n\n");
}
void CTestCase0677::ContinueAfter(TTimeIntervalMicroSeconds32 aMicroSecs, TCaseSteps aStep)
{
LOG_VERBOSE2(_L("Wait %dms before drop VBus"), (TInt)(aMicroSecs.Int()/1000));
+ if(gVerboseOutput)
+ {
+ OstTrace1(TRACE_VERBOSE, CTESTCASE0677_CONTINUEAFTER, "Wait %dms before drop VBus", (TInt)(aMicroSecs.Int()/1000));;
+ }
iTimer.After(iStatus, aMicroSecs);
iCaseStep = aStep;
SetActive();
@@ -154,7 +190,10 @@
// handle event completion
void CTestCase0677::RunStepL()
{
- LOG_FUNC
+ if(gVerboseOutput)
+ {
+ OstTraceFunctionEntry0(CTESTCASE0677_RUNSTEPL);
+ }
// Obtain the completion code for this CActive obj.
TInt completionCode(iStatus.Int());
TBuf<MAX_DSTRLEN> aDescription;
@@ -173,7 +212,9 @@
}
// prompt to insert connector
test.Printf(KAttachOETAsBDevice);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP01, KAttachOETAsBDevice);
test.Printf(KPressAnyKeyToContinue);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP02, KPressAnyKeyToContinue);
RequestCharacter();
break;
@@ -199,7 +240,9 @@
if (otgIdPinPresent())
{
test.Printf(KRemoveAConnectorPrompt);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP03, KRemoveAConnectorPrompt);
test.Printf(KPressAnyKeyToContinue);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP04, KPressAnyKeyToContinue);
RequestCharacter();
iCaseStep = EDetectBPlug;
@@ -216,6 +259,7 @@
iWDTimer->Cancel();
test.Printf(KMsgWaitingForSRPInitiated);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP05, KMsgWaitingForSRPInitiated);
otgQueueOtgEventRequest( iOTGEvent, iStatus );
// turn on VBus (B-SRP)
@@ -240,6 +284,7 @@
}
OtgEventString(iOTGEvent, aDescription);
test.Printf(_L("Received event %d '%S' status(%d)\n"), iOTGEvent, &aDescription, completionCode);
+ OstTraceExt3(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP06, "Received event %d '%S' status(%d)\n", iOTGEvent, aDescription, completionCode);
if (RUsbOtgDriver::EEventSrpInitiated == iOTGEvent)
{
// calc interval
@@ -248,16 +293,22 @@
aNowTime.HomeTime();
aIntvlMicro = aNowTime.MicroSecondsFrom(iTimeSRPStart);
LOG_VERBOSE2(_L("SRP active after %d ms\n"), (TInt)(aIntvlMicro.Int64()/1000));
+ if(gVerboseOutput)
+ {
+ OstTrace1(TRACE_VERBOSE, CTESTCASE0677_RUNSTEPL_DUP07, "SRP active after %d ms\n", (TInt)(aIntvlMicro.Int64()/1000));;
+ }
iCancelWhat = ECancelMessageNotify;
otgQueueOtgMessageRequest( iOTGMessage, iStatus );
iCaseStep = EWaitForSRPTimeout;
test.Printf(KMsgWaitingForSRPTimeout);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP08, KMsgWaitingForSRPTimeout);
SetActive();
}
else
{
iCaseStep = EWaitForSRPInitiated;
test.Printf(KMsgWaitingForSRPInitiated);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP09, KMsgWaitingForSRPInitiated);
iStatus = KRequestPending;
otgQueueOtgEventRequest( iOTGEvent, iStatus );
SetActive();
@@ -272,6 +323,7 @@
}
OtgMessageString(iOTGMessage, aDescription);
test.Printf(_L("Received message %d '%S' status(%d)\n"), iOTGMessage, &aDescription, completionCode);
+ OstTraceExt3(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP10, "Received message %d '%S' status(%d)\n", iOTGMessage, aDescription, completionCode);
if (RUsbOtgDriver::EMessageSrpTimeout == iOTGMessage)
{
iWDTimer->Cancel(); // Only cancel WD Timer here, when timed portion of test is over
@@ -281,6 +333,10 @@
aNowTime.HomeTime();
aIntvlMicro = aNowTime.MicroSecondsFrom(iTimeSRPStart);
LOG_VERBOSE2(_L("SRP times out after %d ms\n"), (TInt)(aIntvlMicro.Int64()/1000));
+ if(gVerboseOutput)
+ {
+ OstTrace1(TRACE_VERBOSE, CTESTCASE0677_RUNSTEPL_DUP11, "SRP times out after %d ms\n", (TInt)(aIntvlMicro.Int64()/1000));;
+ }
// the correct value is 32 seconds, not 4.9 seconds as per the spec.
iCaseStep = EIssueSRPObservedPrompt;
@@ -290,6 +346,7 @@
{
iCaseStep = EWaitForSRPTimeout;
test.Printf(KMsgWaitingForSRPTimeout);
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP12, KMsgWaitingForSRPTimeout);
iStatus = KRequestPending;
otgQueueOtgMessageRequest( iOTGMessage, iStatus );
SetActive();
@@ -299,7 +356,9 @@
case EIssueSRPObservedPrompt:
{
test.Printf(_L("\nPress Y to verify that SRP was observed\n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP13, "\nPress Y to verify that SRP was observed\n");
test.Printf(_L("or any other key to fail test.\n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP14, "or any other key to fail test.\n");
RequestCharacter();
iCaseStep = ECheckSRPObservedUserInput;
break;
@@ -332,6 +391,7 @@
default:
test.Printf(_L("<Error> unknown test step\n"));
+ OstTrace0(TRACE_NORMAL, CTESTCASE0677_RUNSTEPL_DUP15, "<Error> unknown test step\n");
Cancel();
return (TestFailed(KErrCorrupt, _L("<Error> unknown test step")));
}