## Annotation is found after the hash marks, and will always reference the line of the log directly following it.

## The traces being shown here are the Conversation macro traces.


## This is where the authentication process starts, the caller has just pressed [*] at the Opening Greeting to sign in.

07/08/2008 09:18:56.782 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubSignIn : CCsCDEConvBase::Run : Entering conversation [SubSignIn] [Src/CsCDEConvBase.cpp:123]|

## In this example, the caller attempting to sign in is calling in on extension 1011, which doesnt match the extension of any user on the server.
## In the next 7 lines, the caller id is checked to see if it matches a user on the server, if it did then the language on the call would be changed to the user's preference.
## This only comes into play when someone dials UC on their phone that matches their extension, so that they hear the enter password prompt in their language.

07/08/2008 09:18:56.782 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,SubSignIn_RunInit: ICsNamedProps::GetPropString() found property SignInCallerID = 1011.  GetPropString() returned 0x00000000 S_OK [Src/SubSignIn.cpp:66]|
07/08/2008 09:18:56.782 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,SubSignIn_RunInit: Tried to get Caller ID returned from named props, hr=0x00000000 S_OK, ID= [1011] [Src/SubSignIn.cpp:67]|
07/08/2008 09:18:56.786 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,No Subscriber Records found by Dtmf Access Id 1011 0x80046500 (Src/CsCallSession.cpp 404)|
07/08/2008 09:18:56.786 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GetSubscriberByDtmfIdOrPhoneWithAutoLogon: CCsCallSession::GetSubscriberByDtmfId() returned NULL [Src/AttemptSignIn.cpp:59]|
07/08/2008 09:18:56.791 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,No Subscriber Records found by Phone Number 1011 0x80046500 (Src/CsCallSession.cpp 446)|
07/08/2008 09:18:56.791 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GetSubscriberByDtmfIdOrPhoneWithAutoLogon: CCsCallSession::GetSubscriberByPhoneNumberWithAutoLogon() returned NULL [Src/AttemptSignIn.cpp:65]|
07/08/2008 09:18:56.791 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,SubSignIn_RunInit: GetSubscriberByDtmfIdOrPhoneWithAutoLogon( 1011 ) returned hr= 0x80004005 E_FAIL, subscriber=(nil) [Src/SubSignIn.cpp:81]|
07/08/2008 09:18:56.791 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSU : class=SubSignIn; language=ENU [Src/CsPhraseServerMgr.cpp:1157]|
07/08/2008 09:18:56.791 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhraseServerMgr::GetTuiLanguageString : The value of 'System.SystemSetup.TUILanguages.Loaded' is ENU [Src/CsPhraseServerMgr.cpp:2132]|
07/08/2008 09:18:56.792 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSUByName : name=SubSignIn_ENU.ps [Src/CsPhraseServerMgr.cpp:869]|
07/08/2008 09:18:56.792 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSUByProgID : SU; CsPhrases.CSingleUse MU; CsPhrases.CMultiUse!SubSignIn_ENU.ps [Src/CsPhraseServerMgr.cpp:503]|
07/08/2008 09:18:56.792 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMU : class=SubSignIn; language=ENU [Src/CsPhraseServerMgr.cpp:948]|
07/08/2008 09:18:56.796 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMUByName : class=SubSignIn; language=ENU [Src/CsPhraseServerMgr.cpp:847]|
07/08/2008 09:18:56.796 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMUByProgID : class=SubSignIn; language=ENU; scriptname=SubSignIn_ENU.ps [Src/CsPhraseServerMgr.cpp:685]|
07/08/2008 09:18:56.796 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMUByProgIDLow : class=SubSignIn; language=ENU; prompt path=/opt/cisco/connection/share/prompts/ENU/PCM/ [Src/CsPhraseServerMgr.cpp:599]|
07/08/2008 09:18:56.812 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,3,(MultiUse.cpp) Script: SubSignIn_ENU.ps (CCsPhraseServerMultiUse::InitPhraseServersMultiUse) -- /opt/cisco/connection/share/tui/ENU/SubSignIn_ENU.ps|
07/08/2008 09:18:56.814 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMUByProgIDLow : Adding object with ProgID CsPhrases.CMultiUse!SubSignIn_ENU.ps to the PhraseServersMU variant list [Src/CsPhraseServerMgr.cpp:664]|
07/08/2008 09:18:56.814 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMU : Searching for object with ProgID CommonENU.CMultiUse in the PhraseServersMU variant list [Src/CsPhraseServerMgr.cpp:1002]|
07/08/2008 09:18:56.814 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMU : class=Common; language=ENU [Src/CsPhraseServerMgr.cpp:948]|
07/08/2008 09:18:56.818 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMUByName : class=Common; language=ENU [Src/CsPhraseServerMgr.cpp:847]|
07/08/2008 09:18:56.818 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerMUByProgID : class=Common; language=ENU; scriptname=Common_ENU.ps [Src/CsPhraseServerMgr.cpp:685]|
07/08/2008 09:18:56.826 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSU : class=Common; language=ENU [Src/CsPhraseServerMgr.cpp:1157]|
07/08/2008 09:18:56.826 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhraseServerMgr::GetTuiLanguageString : The value of 'System.SystemSetup.TUILanguages.Loaded' is ENU [Src/CsPhraseServerMgr.cpp:2132]|
07/08/2008 09:18:56.826 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Starting state machine with root state [/opt/cisco/connection/share/tui/SubSignIn.cde!AnswerPhone] [Src/CsStateMachine.cpp:127]|
07/08/2008 09:18:56.826 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!AnswerPhone] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:18:56.827 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSAnswer::OnEntry : ICsMiuCall::Answer returned 0x00045B33 S_MIU_CONNECTED [Src/CsSAnswer.cpp:92]|
07/08/2008 09:18:56.827 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!AnswerPhone] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:18:56.827 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [TrueEvent] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:18:56.827 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignIn.cde!AuthenticateUser] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:18:56.827 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!AuthenticateUser] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:18:56.827 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubAuthenticate : CCsCDEConvBase::Run : Entering conversation [SubAuthenticate] [Src/CsCDEConvBase.cpp:123]|

## Authenticate.UseSubscriberSettings determines if PIN collection will use the accessibility settings (speed, volume, retries, timeouts) of the user, this setting can be found in the Advanced->Conversations page and is called "Apply User Accessibility Settings for Voice Mail Password Entry Conversation".

07/08/2008 09:18:56.839 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GetAuthenticateSecurityFlags: ICsNamedProps::SetPropBool(Authenticate.UseSubscriberSettings) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:173]|

## AuthenticateEx.IsNotification will be set to true if this is a notification dial-out.  In this case it is not, and the property is absent.
## If it was a notification, we would switch the language on the call to the user's language.

07/08/2008 09:18:56.839 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,SubAuthenticate_RunInit: ICsNamedProps::GetPropBool() could not find property: AuthenticateEx.IsNotification.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:240]|
07/08/2008 09:18:56.839 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSU : class=SubSignIn; language=ENU [Src/CsPhraseServerMgr.cpp:1157]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhraseServerMgr::GetTuiLanguageString : The value of 'System.SystemSetup.TUILanguages.Loaded' is ENU [Src/CsPhraseServerMgr.cpp:2132]|

## The TryCounter state checks to see how many times authentication has been attempted, if authentication has failed 3 times in a row then the caller is sent back...in case like this the caller would be going back to the Opening Greeting.

07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Starting state machine with root state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!TryCounter] [Src/CsStateMachine.cpp:127]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!TryCounter] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!TryCounter] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [NULL] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubAuthenticate.cde!GatherID] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!GatherID] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnEntry : State name=[GatherID] onentry=[GatherID_OnEntry] library=[libConvSub.so] [Src/CustomState.cpp:162]|

## The process of gathering the ID from the caller begins here.

07/08/2008 09:18:56.840 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GatherID_OnEntry: Gathering ID from the caller [Src/SubAuthenticate.cpp:1191]|

## Again, we check if this is a notification dial-out.  If it is then the dial string used for the notification is compared against all the user's extensions for a possible match.  If there is a match, then we have an ID.

07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckNotificationToKnownDevice: ICsNamedProps::GetPropBool() could not find property: AuthenticateEx.IsNotification.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:66]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GatherID_OnEntry: ICsNamedProps::GetPropBool() could not find property: bPlaySilencePromptID.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:1217]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GatherID_OnEntry: ICsPhrase::put_PhraseID(GetID) returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1231]|

## Get the caller id and see if it matches an extension of a user.  If it does, then the caller id is used, otherwise the caller is prompted for their ID.
## In this case the caller id does not match any extension, so the caller is prompted to enter their ID.

07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GatherID_OnEntry: Checking if ID is already in NamedProps [Src/SubAuthenticate.cpp:1253]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GetIDFromNamedProps: ICsNamedProps::GetPropBool() could not find property: AuthenticatePersistent.AuthenticatedAtLeastOne.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:367]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GetIDFromNamedProps: ICsNamedProps::GetPropString() found property SignInCallerID = 1011.  GetPropString() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:373]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GetIDFromNamedProps: ID from NamedProps is [1011] [Src/SubAuthenticate.cpp:375]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GatherID_OnEntry: ICsNamedProps::GetPropBool() could not find property: Authenticate.IgnoreKnownDevice.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:1262]|
07/08/2008 09:18:56.841 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GatherID_OnEntry: Found ID in named props, strID = 1011 [Src/SubAuthenticate.cpp:1267]|
07/08/2008 09:18:56.845 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,No Subscriber Records found by Dtmf Access Id 1011 0x80046500 (Src/CsCallSession.cpp 404)|
07/08/2008 09:18:56.845 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GetSubscriberByDtmfIdOrPhoneWithAutoLogon: CCsCallSession::GetSubscriberByDtmfId() returned NULL [Src/AttemptSignIn.cpp:59]|
07/08/2008 09:18:56.849 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,No Subscriber Records found by Phone Number 1011 0x80046500 (Src/CsCallSession.cpp 446)|
07/08/2008 09:18:56.849 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GetSubscriberByDtmfIdOrPhoneWithAutoLogon: CCsCallSession::GetSubscriberByPhoneNumberWithAutoLogon() returned NULL [Src/AttemptSignIn.cpp:65]|
07/08/2008 09:18:56.849 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GatherID_OnEntry: Subscriber not found:  strID = 1011 [Src/SubAuthenticate.cpp:1372]|
07/08/2008 09:18:56.849 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GatherID_OnEntry: ICsNamedProps::SetPropBool(Authenticate.IDNotFound) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1379]|
07/08/2008 09:18:56.849 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,3,(SingleUse.cpp) Script: SubSignIn_ENU.ps (Make) -- GetID|
07/08/2008 09:18:56.850 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,3,(SingleUse.cpp) Script: SubSignIn_ENU.ps (AddPrompt) -- PleaseEnterID|
07/08/2008 09:18:56.850 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,1,CCsPhrase::Play() :     [09:18:56 AM 07/08/2008] PhraseID - GetID [Src/CsPhrase.cpp:318]|
07/08/2008 09:18:56.850 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,6,CCsPhrase::ConvertStrFileName() :         Prompt Name - /opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU001.wav [Src/CsPhrase.cpp:1412]|

## The caller enters [1010#].

07/08/2008 09:18:56.850 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::Play Start Position in streams-0, fContinueRendering-0|
07/08/2008 09:18:56.854 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,GetLength success, file-/opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU001.wav length-5247 ms|
07/08/2008 09:18:56.854 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Vol=50 Speed=100 StartPos=0ms|
07/08/2008 09:18:56.854 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,File Name to play-/opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU001.wav|
07/08/2008 09:18:56.854 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Mixer Message send: GY_MSG_PLAY_FILE_START-64 len=389 msgID=64 confID=0 chnID=199 seqNo=64 startTimeInFile=0 maxPlayTime=0 isRepeat=0 extraParam=0 language="" contentType="" speed=0=GY_SPEED_NORMAL enableGC=0 targetGCLevel=0 validateFileHdrFmt=1 fileType[0]=0=GY_AUDIO_WAVE_FORMAT_FILE_TYPE nFiles=1|
07/08/2008 09:18:56.865 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_PLAY_FILE_RES-320 len=389 msgID=320 confID=0 chnID=199 seqNo=64|
07/08/2008 09:18:56.865 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_PLAY_FILE_RES-320|
07/08/2008 09:18:56.865 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play-Waiting for Playback to complete|
07/08/2008 09:18:56.925 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_PLAY_FILE_EVT_RES-324 len=32 msgID=324 confID=65535 chnID=199 seqNo=64 sessionID=8 event=7=GY_PR_CUR_PLAY_FILE p.fileIndex=0 p.elapseTime=0 p.voiceDuration=0|
07/08/2008 09:18:57.481 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:18:57.481 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (1)|
07/08/2008 09:18:57.481 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Digit Interpreted-(MEDIA_DIGIT_TERMINATE)|
07/08/2008 09:18:57.481 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:18:57.481 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play-Stop the Playback|
07/08/2008 09:18:57.481 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::StopPlayFile |
07/08/2008 09:18:57.481 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Mixer Message send: GY_MSG_STOP_PLAY_FILE-65 len=20 msgID=65 confID=0 chnID=199 seqNo=65 bytes_played_in_ms=0 startPosition=0|
07/08/2008 09:18:57.501 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_STOP_PLAY_FILE_RES-321 len=20 msgID=321 confID=0 chnID=199 seqNo=65 bytes_played_in_ms=610 startPosition=0|
07/08/2008 09:18:57.501 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_STOP_PLAY_FILE_RES-321|
07/08/2008 09:18:57.501 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::StopPlayFile=0x00000000=S_OK|
07/08/2008 09:18:57.502 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::ClosePlayFile |
07/08/2008 09:18:57.502 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Mixer Message send: GY_MSG_CLOSE_PLAY_FILE-66 len=20 msgID=66 confID=0 chnID=199 seqNo=66 bytes_played_in_ms=0 startPosition=0|
07/08/2008 09:18:57.514 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_CLOSE_PLAY_FILE_RES-322 len=20 msgID=322 confID=0 chnID=199 seqNo=66 bytes_played_in_ms=610 startPosition=0|
07/08/2008 09:18:57.514 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_CLOSE_PLAY_FILE_RES-322|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::ClosePlayFile=0x00000000=S_OK|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play waiting for final play event|
07/08/2008 09:18:57.515 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_PLAY_FILE_EVT_RES-324 len=32 msgID=324 confID=65535 chnID=199 seqNo=66 sessionID=8 event=11=GY_PR_DONE p.fileIndex=0 p.elapseTime=0 p.voiceDuration=0|
07/08/2008 09:18:57.515 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_PLAY_FILE_EVT_RES-324|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play-Wait for Final Play Event completed successfully|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::Play=0x00045B31=S_MIU_TERM_DIGIT|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::Gather LeadingTimeout=5000 InterDigitTimeout=3000 NumberDigits=1 Options=1|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Returing Digits <1>|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::Gather=0x00000000=S_OK|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::Gather LeadingTimeout=3000 InterDigitTimeout=3000 NumberDigits=39 Options=1|
07/08/2008 09:18:57.515 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:18:57.768 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:18:57.768 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (0)|
07/08/2008 09:18:57.768 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:18:57.769 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:18:57.769 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:18:58.076 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:18:58.076 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (1)|
07/08/2008 09:18:58.076 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:18:58.076 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:18:58.076 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:18:58.388 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:18:58.388 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (0)|
07/08/2008 09:18:58.388 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:18:58.388 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:18:58.388 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:18:58.678 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:18:58.678 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (#)|
07/08/2008 09:18:58.678 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:18:58.678 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:18:58.678 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Returing Digits <010#>|
07/08/2008 09:18:58.678 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::Gather=0x00045B31=S_MIU_TERM_DIGIT|
07/08/2008 09:18:58.678 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhrase::CheckForTermination : ICsMiuCall::GatherDigits() returned: 0x00045b31 (S_MIU_TERM_DIGIT) [Src/CsPhrase.cpp:1726]|
07/08/2008 09:18:58.678 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,6,CCsPhrase::Play() :     End Phrase [Src/CsPhrase.cpp:556]|
07/08/2008 09:18:58.679 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,7,CCsPhrase::Play() : DTMF [1010#] [Src/CsPhrase.cpp:606]|
07/08/2008 09:18:58.679 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GetIDFromUser: ID from the caller is [1010] [Src/SubAuthenticate.cpp:344]|

## Check to see if need to use the caller's accessibility settings when gathering their PIN.
## If accessibility settings must be used, a TRUE_EVENT is generated.  If not, a FALSE_EVENT is generated.
## In this case, its a FALSE_EVENT, since the setting in question is not enabled, the default situation.

07/08/2008 09:18:58.711 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GatherID_OnEntry: ICsNamedProps::GetPropBool() found property Authenticate.UseSubscriberSettings = false.  GetPropBool() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1471]|
07/08/2008 09:18:58.712 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,GatherID_OnEntry: ICsEventQueue::PushID(FALSE_EVENT) returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1479]|

## Stash the ID entered into the named prop SignInID so that we can use it later on.

07/08/2008 09:18:58.712 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GatherID_OnEntry: ICsNamedProps::SetPropString(SignInID) to value: 1010 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1483]|

## No device specific settings were found for this extension, since its the primary extension of the user, so the rest of the call wiill be using the base settings of the user.

07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Did not find device settings for extension, using base subscriber values. 1010 0x80046522 (Src/CsCallSession.cpp 3183)|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!GatherID] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [FalseEvent] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubAuthenticate.cde!LoadSubscriberMinimalData] [Src/CsStateMachine.cpp:190]|

## The bare mimimum settings needed to collect the PIN from the caller are loaded now.

07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!LoadSubscriberMinimalData] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnEntry : State name=[LoadSubscriberMinimalData] onentry=[LoadSubscriberAuthenticationData_OnEntry] library=[libConvSub.so] [Src/CustomState.cpp:162]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberAuthenticationData_OnEntry: ICsNamedProps::SetPropString(MailUserCallerID) to value: 1011 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1062]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberAuthenticationData_OnEntry: ICsNamedProps::SetPropString(MailUserAlias) to value: usera returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1079]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberAuthenticationData_OnEntry: ICsNamedProps::SetPropString(MailUserDisplayName) to value: User A returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1080]|
07/08/2008 09:18:58.721 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberAuthenticationData_OnEntry: ICsNamedProps::SetPropString(MailUserExtension) to value: 1010 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1083]|
07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberAuthenticationData_OnEntry: ICsNamedProps::SetPropLong(MinPasswordLength) to value: 6 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1095]|
07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Current device settings not set in the session 0x0 (Src/CsCallSession.cpp 3136)|
07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberAuthenticationData_OnEntry: ICsNamedProps::GetPropBool() could not find property: bSubLogInFromPhone.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:1119]|

## Here is where you would see the skip password setting happening if it was enabled.  You would see an event being pushed called "SkipPassword".
## In order for skipping the password to happen, the user must have the skip password option enabled and they must be calling in from a known device (that is their caller id maps to a user's extension).  Above you can see we check the prop bSubLogInFromPhone, which will be set to true if the caller calls in from a known device.  In this case its not true, since the property is absent.

07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!LoadSubscriberMinimalData] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [NULL] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubAuthenticate.cde!GatherPIN] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:18:59.266 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!GatherPIN] [Src/CsStateMachine.cpp:139]|

## Now the conversation is being run that will gather the password from the caller.

07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubAuthenticatePW : CCsCDEConvBase::Run : Entering conversation [SubAuthenticatePW] [Src/CsCDEConvBase.cpp:123]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,SubAuthenticatePW_RunInit: ICsNamedProps::GetPropBool() found property Authenticate.UseSubscriberSettings = false.  GetPropBool() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:207]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSU : class=SubSignIn; language=ENU [Src/CsPhraseServerMgr.cpp:1157]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhraseServerMgr::GetTuiLanguageString : The value of 'System.SystemSetup.TUILanguages.Loaded' is ENU [Src/CsPhraseServerMgr.cpp:2132]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Starting state machine with root state [/opt/cisco/connection/share/tui/SubAuthenticatePW.cde!ValidatePwd] [Src/CsStateMachine.cpp:127]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticatePW.cde!ValidatePwd] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnEntry : State name=[ValidatePwd] onentry=[] library=[libConvSub.so] [Src/CustomState.cpp:162]|
07/08/2008 09:18:59.267 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ValidatePwd_OnEntry: ICsNamedProps::GetPropBool() found property Authenticate.IDNotFound = true.  GetPropBool() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1882]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ValidatePwd_OnEntry: ICsNamedProps::GetPropString() found property MailUserCallerID = 1011.  GetPropString() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1897]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,ValidatePwd_OnEntry: Gathering Password from the caller [Src/SubAuthenticate.cpp:1900]|

## If NULL passwords were allowed (a blank password), then bNullValid would be set to true.  Allowing blank passwords is controlled by the credential policy's minimum password length.

07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,ValidatePwd_OnEntry: bNullValid = false [Src/SubAuthenticate.cpp:1923]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Current device settings not set in the session 0x0 (Src/CsCallSession.cpp 3136)|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ValidatePwd_OnEntry: ICsNamedProps::GetPropBool() could not find property: bPlaySilencePromptPwd.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:2020]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,ValidatePwd_OnEntry: ICsPhrse::put_PhraseID(GetSecCode) returned: 0x00000000 S_OK [Src/SubAuthenticate.cpp:2034]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,ValidatePwd_OnEntry: Asking the caller for a password [Src/SubAuthenticate.cpp:2038]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ValidatePwd_OnEntry: ICsNamedProps::GetPropString() could not find property: NotifyPWD.  GetPropString() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:2044]|

## At this point we start checking to see if the caller can say their PIN (voice PIN).
## If the caller's ID matches to a VUI user, and they are calling from a known extension, and voice PIN is enabled (advanced conversation setting), and the language being used is a VUI supported language (currently only ENU), then the voice PIN option is offered. 

07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ValidatePwd_OnEntry: ICsNamedProps::GetPropBool() could not find property: bSubLogInFromPhone.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:2063]|

## Here is where the prompt is actually played to enter a password (and in this case the caller enters their correct password).
## The Authenticate.UseSubscriberSettings is checked to see if the prompting of the password should follow the user's accessibility settings (in this case the number of times to repeat the prompt).

07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GetPwdFromUser: ICsNamedProps::GetPropBool() found property Authenticate.UseSubscriberSettings = false.  GetPropBool() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1535]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GetPwdFromUser: ICsNamedProps::GetPropBool() could not find property: Authenticate.KnownDeviceAllowsSubscriberSettings.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:1538]|
07/08/2008 09:18:59.268 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSPlayPhrase::OnEntry : SetPropLong(Speech_NoMatch_Retries) to value [0] returned 0x00000000 S_OK [Src/CsSPlayPhrase.cpp:171]|
07/08/2008 09:18:59.269 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSPlayPhrase::SetSpeedAndVolume : Defaults: speed [100] volume [50] [Src/CsSPlayPhrase.cpp:1243]|
07/08/2008 09:18:59.269 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSPlayPhrase::SetSpeedAndVolume : Prompt  : speed [100] volume [50] [Src/CsSPlayPhrase.cpp:1249]|
07/08/2008 09:18:59.269 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSPlayPhrase::SetSpeedAndVolume : Prompt  : speed [100] volume [50] [Src/CsSPlayPhrase.cpp:1267]|
07/08/2008 09:18:59.269 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSPlayPhrase::SetCurrentSpeed : Setting speed [100] [Src/CsSPlayPhrase.cpp:1353]|
07/08/2008 09:18:59.269 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSPlayPhrase::SetCurrentVolume : Setting volume [50] [Src/CsSPlayPhrase.cpp:1380]|
07/08/2008 09:18:59.296 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16, : CCsSPlayPhrase::OnEntry : Couldn't find PhraseStartPosition in named props, assuming 0 [Src/CsSPlayPhrase.cpp:240]|
07/08/2008 09:18:59.296 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSPlayPhrase::OnEntry : SetPropLong(NoInput_Retries) to value [0] [Src/CsSPlayPhrase.cpp:303]|
07/08/2008 09:18:59.296 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSPlayPhrase::OnEntry : GetPropLong(PlayPhrase_Help) value = [false] [Src/CsSPlayPhrase.cpp:308]|
07/08/2008 09:18:59.296 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,3,(SingleUse.cpp) Script: SubSignIn_ENU.ps (Make) -- GetSecCode|
07/08/2008 09:18:59.296 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,3,(SingleUse.cpp) Script: SubSignIn_ENU.ps (AddPrompt) -- GetSecCode|
07/08/2008 09:18:59.296 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::GetMediaLength |
07/08/2008 09:18:59.297 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,GetLength success, file-/opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU002.wav length-2260 ms|
07/08/2008 09:18:59.297 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::GetMediaLength=0x00000000=S_OK|
07/08/2008 09:18:59.297 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,1,CCsPhrase::Play() :     [09:18:59 AM 07/08/2008] PhraseID - GetSecCode [Src/CsPhrase.cpp:318]|
07/08/2008 09:18:59.297 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,6,CCsPhrase::ConvertStrFileName() :         Prompt Name - /opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU002.wav [Src/CsPhrase.cpp:1412]|
07/08/2008 09:18:59.297 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::Play Start Position in streams-0, fContinueRendering-0|
07/08/2008 09:18:59.298 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,GetLength success, file-/opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU002.wav length-2260 ms|
07/08/2008 09:18:59.298 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Vol=50 Speed=100 StartPos=0ms|
07/08/2008 09:18:59.298 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,File Name to play-/opt/cisco/connection/share/prompts/ENU/PCM/SubSignIn/AvSubSignInENU002.wav|
07/08/2008 09:18:59.298 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Mixer Message send: GY_MSG_PLAY_FILE_START-64 len=389 msgID=64 confID=0 chnID=199 seqNo=67 startTimeInFile=0 maxPlayTime=0 isRepeat=0 extraParam=0 language="" contentType="" speed=0=GY_SPEED_NORMAL enableGC=0 targetGCLevel=0 validateFileHdrFmt=1 fileType[0]=0=GY_AUDIO_WAVE_FORMAT_FILE_TYPE nFiles=1|
07/08/2008 09:18:59.307 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_PLAY_FILE_RES-320 len=389 msgID=320 confID=0 chnID=199 seqNo=67|
07/08/2008 09:18:59.307 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_PLAY_FILE_RES-320|
07/08/2008 09:18:59.307 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play-Waiting for Playback to complete|
07/08/2008 09:18:59.367 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_PLAY_FILE_EVT_RES-324 len=32 msgID=324 confID=65535 chnID=199 seqNo=67 sessionID=8 event=7=GY_PR_CUR_PLAY_FILE p.fileIndex=0 p.elapseTime=0 p.voiceDuration=0|
07/08/2008 09:18:59.915 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:18:59.915 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:18:59.915 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Digit Interpreted-(MEDIA_DIGIT_TERMINATE)|
07/08/2008 09:18:59.915 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:18:59.916 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play-Stop the Playback|
07/08/2008 09:18:59.916 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::StopPlayFile |
07/08/2008 09:18:59.916 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Mixer Message send: GY_MSG_STOP_PLAY_FILE-65 len=20 msgID=65 confID=0 chnID=199 seqNo=68 bytes_played_in_ms=0 startPosition=0|
07/08/2008 09:18:59.919 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_STOP_PLAY_FILE_RES-321 len=20 msgID=321 confID=0 chnID=199 seqNo=68 bytes_played_in_ms=600 startPosition=0|
07/08/2008 09:18:59.919 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_STOP_PLAY_FILE_RES-321|
07/08/2008 09:18:59.919 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::StopPlayFile=0x00000000=S_OK|
07/08/2008 09:18:59.919 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::ClosePlayFile |
07/08/2008 09:18:59.919 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Mixer Message send: GY_MSG_CLOSE_PLAY_FILE-66 len=20 msgID=66 confID=0 chnID=199 seqNo=69 bytes_played_in_ms=0 startPosition=0|
07/08/2008 09:18:59.931 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_CLOSE_PLAY_FILE_RES-322 len=20 msgID=322 confID=0 chnID=199 seqNo=69 bytes_played_in_ms=600 startPosition=0|
07/08/2008 09:18:59.931 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_CLOSE_PLAY_FILE_RES-322|
07/08/2008 09:18:59.931 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::ClosePlayFile=0x00000000=S_OK|
07/08/2008 09:18:59.931 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play waiting for final play event|
07/08/2008 09:18:59.955 |2345,,,MiuIO,12,Mixer Message received: GY_MSG_PLAY_FILE_EVT_RES-324 len=32 msgID=324 confID=65535 chnID=199 seqNo=69 sessionID=8 event=11=GY_PR_DONE p.fileIndex=0 p.elapseTime=0 p.voiceDuration=0|
07/08/2008 09:18:59.955 |2345,,,MiuIO,12,CMiuMedia::MiuMediaCallback: Processing Mixer message GY_MSG_PLAY_FILE_EVT_RES-324|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Play-Wait for Final Play Event completed successfully|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::Play=0x00045B31=S_MIU_TERM_DIGIT|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::Gather LeadingTimeout=5000 InterDigitTimeout=3000 NumberDigits=1 Options=1|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Returing Digits <MASKED>|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::Gather=0x00000000=S_OK|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::Gather LeadingTimeout=3000 InterDigitTimeout=3000 NumberDigits=23 Options=1|
07/08/2008 09:18:59.955 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:19:00.117 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:19:00.117 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:19:00.117 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:19:00.117 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:19:00.117 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:19:00.327 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:19:00.327 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:19:00.327 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:19:00.327 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:19:00.327 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:19:00.490 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:19:00.490 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:19:00.490 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:19:00.490 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:19:00.490 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:19:00.704 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:19:00.704 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:19:00.704 |2343,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:19:00.704 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:19:00.704 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:19:00.897 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:19:00.897 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:19:00.897 |2515,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:19:00.897 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:19:00.898 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Waiting for Digits|
07/08/2008 09:19:01.145 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Enter CMiuMedia::MediaEventHandler |
07/08/2008 09:19:01.145 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::MediaEventHandler-Digit (X)|
07/08/2008 09:19:01.145 |2514,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::MediaEventHandler=0x00000000=S_OK|
07/08/2008 09:19:01.145 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Wait for Digits completed successfully|
07/08/2008 09:19:01.145 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,CMiuMedia::Gather-Returing Digits <MASKED>|
07/08/2008 09:19:01.145 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,MiuIO,12,Exit CMiuMedia::Gather=0x00045B31=S_MIU_TERM_DIGIT|
07/08/2008 09:19:01.145 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhrase::CheckForTermination : ICsMiuCall::GatherDigits() returned: 0x00045b31 (S_MIU_TERM_DIGIT) [Src/CsPhrase.cpp:1726]|
07/08/2008 09:19:01.145 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,6,CCsPhrase::Play() :     End Phrase [Src/CsPhrase.cpp:556]|
07/08/2008 09:19:01.145 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,7,CCsPhrase::Play() : DTMF [***********] [Src/CsPhrase.cpp:606]|
07/08/2008 09:19:01.146 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,GetPwdFromUser: ICsNamedProps::GetPropString() found property DTMFBuffer = *************.  GetPropString() returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1587]|

## The DTMFs that were entered are checked to see if they are the password or not...if they are correct here is where you would see locked accounts or expired passwords come into play.

07/08/2008 09:19:01.146 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,ValidatePwd_OnEntry: Received touchtones in response to password request, attempting to authenticate a user [Src/SubAuthenticate.cpp:2165]|

## Log to reports that a successful sign in occured.

07/08/2008 09:19:01.153 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ReportSubscriberLogin: ICsNamedProps::GetPropString() found property MailUserDisplayName = User A.  GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:110]|
07/08/2008 09:19:01.153 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ReportSubscriberLogin: ICsNamedProps::GetPropString() found property MailUserExtension = 1010.  GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:114]|
07/08/2008 09:19:01.207 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,OpenStore: ICsNamedProps::SetPropLong(CurrentOpenMailStore) to value: 0 returned 0x00000000 S_OK [Src/CsConvMailbox.cpp:206]|
07/08/2008 09:19:01.221 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropString(Reporter.SubscriberObjectID) to value: d86e448d-6ca7-40b0-9c28-af221f7549b9 returned 0x00000000 S_OK [Src/ConvReporter.cpp:560]|
07/08/2008 09:19:01.221 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropDate(Reporter.LoginCount.DateTime) to value: 04:19:01 PM 07/08/2008 returned 0x00000000 S_OK [Src/ConvReporter.cpp:561]|
07/08/2008 09:19:01.221 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropLong(Reporter.LoginCount.New) to value: 0 returned 0x00000000 S_OK [Src/ConvReporter.cpp:562]|
07/08/2008 09:19:01.222 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropLong(Reporter.LoginCount.Saved) to value: 0 returned 0x00000000 S_OK [Src/ConvReporter.cpp:563]|
07/08/2008 09:19:01.222 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropDate(Reporter.LastCount.DateTime) to value: 04:19:01 PM 07/08/2008 returned 0x00000000 S_OK [Src/ConvReporter.cpp:564]|
07/08/2008 09:19:01.222 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropLong(Reporter.LastCount.New) to value: 0 returned 0x00000000 S_OK [Src/ConvReporter.cpp:565]|
07/08/2008 09:19:01.222 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CaptureSubscriberMessageCounts: ICsNamedProps::SetPropLong(Reporter.LastCount.Saved) to value: 0 returned 0x00000000 S_OK [Src/ConvReporter.cpp:566]|

## AuthenticatePersistent.AuthenticatedAtLeastOne keeps track of the fact that the caller has been successfully authenticated already.

07/08/2008 09:19:01.222 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(AuthenticatePersistent.AuthenticatedAtLeastOne) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:401]|

## For every user there is a field in the DB that keeps track of when they last successfully authenticated.  

07/08/2008 09:19:01.222 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,2,LoadSubscriberBasicData: CCsCallSubscriber::Commit() - TIME_LAST_CALL set to 2008-07-08 16:19:01 [Src/SubAuthenticate.cpp:426]|

## Now that the user is signed in switch to that user's language.

07/08/2008 09:19:01.271 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,SetSubsciberLanguageOnContext: Subscriber set to use system default language [Src/SubAuthenticate.cpp:1783]|
07/08/2008 09:19:01.271 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,SetSubsciberLanguageOnContext: System default language is [ENU] [Src/SubAuthenticate.cpp:1791]|
07/08/2008 09:19:01.271 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,SetSubsciberLanguageOnContext: Subscriber language = ENU [Src/SubAuthenticate.cpp:1821]|

## Also switch to that user's search scopes.

07/08/2008 09:19:01.274 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,4,Changed the current search by extension search space from 'fmstest4 Search Space' ({61f75701-de0b-48ee-a00d-125e5f0dec28}) to 'fmstest4 Search Space' ({61f75701-de0b-48ee-a00d-125e5f0dec28}) (Src/CsCallSession.cpp241)|
07/08/2008 09:19:01.277 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,4,Changed the current search by name search space from 'fmstest4 Search Space' ({61f75701-de0b-48ee-a00d-125e5f0dec28}) to 'fmstest4 Search Space' ({61f75701-de0b-48ee-a00d-125e5f0dec28}) (Src/CsCallSession.cpp312)|
07/08/2008 09:19:01.277 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::RemoveProp(ADDRSRCHPERSIST.SearchModeToggled) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:445]|

## Check to see if the user is permitted to use personal call transfer rules (PCTRs).

07/08/2008 09:19:01.282 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,CheckCallRoutingRulesEnabledForSubscriber: Rules are disabled. Setting bRoutingRulesEnabled off [Src/SubAuthenticate.cpp:1684]|
07/08/2008 09:19:01.282 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckCallRoutingRulesEnabledForSubscriber: ICsNamedProps::SetPropBool(bRoutingRulesEnabled) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:1687]|

## The initializing of several named props happens at this point.  These props are used in other conversations that the user can get at during their call (like sending a message or changing settings).
## Stepping through each named prop being set here is beyond the scope of these annotated logs.  Their is a document that maps the device specific settings from the named prop being initialized back to the SA setting in question.  Otherwise, the majority of these props you can figure what setting they represent just from their name.

07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(Flex_bOfferMsgTypeStackMenu) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:477]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bNewMailUser) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:480]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,LoadSubscriberBasicData: Clock mode set = 12 hour (am/pm) [Src/SubAuthenticate.cpp:487]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(DtTimeFormat) to value: 1 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:488]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(MailUserExitAction) to value: 2 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:497]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropString(MailUserExitConversation) to value: PHGreeting returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:506]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,LoadSubscriberBasicData: UID = [{1faee113-beeb-4b89-b6eb-34ab12a56b98}] - Exit Object ID  [Src/SubAuthenticate.cpp:514]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropVariant(MailUserExitObjectId) to value:  returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:519]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(SubSignIn_bStreamlinedSend) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:533]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bUseSkipAndScan) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:534]|
07/08/2008 09:19:01.299 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(EncryptPrivateMessages) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:538]|
07/08/2008 09:19:01.308 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CCL,12,[ccl/CsCclSession.cpp:114]: User usera has no calendaring or meeting related external services configured.|
07/08/2008 09:19:01.308 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Failed to acquire CclSession 0x80046C03 (Src/CsCallSubscriber.cpp 2109)|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CCL,12,[ccl/CsCclSession.cpp:114]: User usera has no calendaring or meeting related external services configured.|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Failed to acquire CclSession 0x80046C03 (Src/CsCallSubscriber.cpp 2109)|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bHasMeetingIntegration) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:541]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropString(MailUserCallID) to value: 4BF5B4F26B24498383C576056A4DD366 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:569]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CosMaxPrivateDlists) to value: 25 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:572]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CosMaxGreetingLength) to value: 90 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:573]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(CosCanRecordName) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:574]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CosMaxNameLength) to value: 30 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:575]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CosMaxMsgLength) to value: 300 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:576]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(CosListInDirectoryStatus) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:577]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(CosCanSendToPublicDL) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:578]|
07/08/2008 09:19:01.312 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CosMaxMembersPVL) to value: 99 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:579]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(RequireSecureMsgs) to value: 4 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:580]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(CosMoveToDeleteFolder) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:581]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(UseDelItmFldr) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:586]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(EnableEnhancedSecurity) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:587]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(CosUseLiveReply) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:588]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(UseLiveReplyUnidentifiedCallers) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:589]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(MsgLocatorEnabled) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:593]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(SayANI) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:596]|
07/08/2008 09:19:01.313 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(SaySenderExt) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:599]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bConfirmB4PurgeDeletedMsg) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:617]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(GTMEnabled) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:618]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bReplayMsgSummary) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:619]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(HasVoiceName) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:622]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CallerTimeZoneID) to value: 4 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:626]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,LoadSubscriberBasicData: Setting caller time zone ID to [4] [Src/SubAuthenticate.cpp:627]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(UseBriefPrompts) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:630]|
07/08/2008 09:19:01.349 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Current device settings not set in the session 0x0 (Src/CsCallSession.cpp 3136)|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(AddressMode) to value: 0 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:644]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CommandDigitTimeout) to value: 1500 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:654]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bConfirmB4DeleteNewOrSavedMsg) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:664]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bContinuousAddNameMode) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:674]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropString(SubscriberDTMFConversation) to value: SubMenu returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:680]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,LoadSubscriberBasicData: Subscriber conversation is 'SubMenu' [Src/SubAuthenticate.cpp:702]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropString(SubscriberConversation) to value: SubMenu returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:704]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(FirstDigitTimeout) to value: 5000 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:715]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(InterDigitDelay) to value: 3000 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:725]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bJumpToNewMessages) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:735]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(MessageSpeed) to value: 100 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:745]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CurrentMessageSpeed) to value: 100 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:746]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(MessageVolume) to value: 50 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:756]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CurrentMessageSpeed) to value: 100 returned 0x00043210 S_NP_PROP_REPLACED [Src/SubAuthenticate.cpp:757]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bDualNameConfirmation) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:767]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(PromptSpeed) to value: 100 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:777]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CurrentPromptSpeed) to value: 100 returned 0x00043210 S_NP_PROP_REPLACED [Src/SubAuthenticate.cpp:778]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(PromptVolume) to value: 50 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:788]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(CurrentPromptVolume) to value: 50 returned 0x00043210 S_NP_PROP_REPLACED [Src/SubAuthenticate.cpp:789]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(Retries) to value: 1 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:799]|
07/08/2008 09:19:01.350 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bMsgPlaybackHangUpMarkRead) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:809]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(UponHangupMarkRead) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:810]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bSendMsgOnHang) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:820]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(Addressing.SendMessageOnHangup) to value: true returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:821]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(JumpAheadTime) to value: 5000 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:832]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(JumpBackTime) to value: 5000 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:842]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(SubscriberConfirmationConfidenceThreshold) to value: 60 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:852]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(SpeechIncompleteTimeout) to value: 750 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:862]|
07/08/2008 09:19:01.351 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropLong(SpeechSensitivity) to value: 50 returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:872]|
07/08/2008 09:19:01.354 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(SubscriberHasImapServiceDefined) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:911]|
07/08/2008 09:19:01.375 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(bHasAlternateContactNumbers) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:939]|
07/08/2008 09:19:01.379 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,LoadSubscriberBasicData: ICsNamedProps::SetPropBool(IsFaxEnabled) to value: false returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:943]|
07/08/2008 09:19:01.379 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,ValidatePwd_OnEntry: ICsEventQueue::PushID(TRUE_EVENT) returned 0x00000000 S_OK [Src/SubAuthenticate.cpp:2216]|
07/08/2008 09:19:01.379 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticatePW.cde!ValidatePwd] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.379 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [TrueEvent] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.379 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubAuthenticatePW.cde!ReturnAuthenticated] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.379 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnExit : State name=[ValidatePwd] onexit=[ValidatePwd_OnExit] library=[libConvSub.so] [Src/CustomState.cpp:263]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,ValidatePwd_OnExit: ICsNamedProps::RemoveProp(bSubLogInFromPhone) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubAuthenticate.cpp:2302]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticatePW.cde!ReturnAuthenticated] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticatePW.cde!ReturnAuthenticated] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [Authenticated] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [NULL] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Exiting state machine. [Src/CsStateMachine.cpp:227]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubAuthenticatePW : CCsCDEConvBase::Run : Exiting conversation [SubAuthenticatePW] [Src/CsCDEConvBase.cpp:389]|
07/08/2008 09:19:01.380 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!GatherPIN] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [Authenticated] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubAuthenticate.cde!ReturnAuthenticated] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!ReturnAuthenticated] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubAuthenticate.cde!ReturnAuthenticated] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [Authenticated] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [NULL] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Exiting state machine. [Src/CsStateMachine.cpp:227]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubAuthenticate : CCsCDEConvBase::Run : Exiting conversation [SubAuthenticate] [Src/CsCDEConvBase.cpp:389]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!AuthenticateUser] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [Authenticated] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignIn.cde!RunSignInUtil] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!RunSignInUtil] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubSignInUtil : CCsCDEConvBase::Run : Entering conversation [SubSignInUtil] [Src/CsCDEConvBase.cpp:123]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CreatePhraseServerSU : class=SubSignIn; language=ENU [Src/CsPhraseServerMgr.cpp:1157]|
07/08/2008 09:19:01.381 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,PhraseServer,8,CCsPhraseServerMgr::GetTuiLanguageString : The value of 'System.SystemSetup.TUILanguages.Loaded' is ENU [Src/CsPhraseServerMgr.cpp:2132]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Starting state machine with root state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckAccountLocked] [Src/CsStateMachine.cpp:127]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckAccountLocked] [Src/CsStateMachine.cpp:139]|

## Check to see if the account is locked.  In this case the named prop that tells us this is not around, which just means that the account is not locked.

07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSCheckPropBool::OnEntry : ICsNamedProps::GetPropBool(SubSignIn_AccountLocked) failed, hr=0x80043200 E_NP_PROP_NOT_FOUND [Src/CsSCheckPropBool.cpp:87]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSCheckPropBool::OnEntry : Property SubSignIn_AccountLocked being evaluated as FALSE [Src/CsSCheckPropBool.cpp:90]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckAccountLocked] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [FalseEvent] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckExpiredAndNew] [Src/CsStateMachine.cpp:190]|

## This state checks to see whether the password has expired on the account or if the user is set to go through the first time enrollment conversation.

07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckExpiredAndNew] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckExpiredAndNew] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [NULL] [Src/CsStateMachine.cpp:168]|

## Checks to see if the caller should be warned that their PIN is about ready to expire.

07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForExpirationWarning] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForExpirationWarning] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnEntry : State name=[CheckForExpirationWarning] onentry=[CheckForExpirationWarning_OnEntry] library=[libConvSub.so] [Src/CustomState.cpp:162]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,CheckForExpirationWarning_OnEntry: Expiration warning not active. [Src/SubAuthenticate.cpp:2586]|
07/08/2008 09:19:01.382 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForExpirationWarning] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [NULL] [Src/CsStateMachine.cpp:168]|

## Checks to see if its signing the caller into an administrator conversation.  An administrator conversation is considered something like Broadcast Message Administrator, Greetings Administrator, or Uesr System Transfers.
## If the caller is signing in to an administrator conversation, then the checks below for an enabled alternate greeting and such are not done.

07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForAdminConversation] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForAdminConversation] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSCheckPropBool::OnEntry : ICsNamedProps::GetPropBool(SubSignIn_AdminConversation) failed, hr=0x80043200 E_NP_PROP_NOT_FOUND [Src/CsSCheckPropBool.cpp:87]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,11, : CCsSCheckPropBool::OnEntry : Property SubSignIn_AdminConversation being evaluated as FALSE [Src/CsSCheckPropBool.cpp:90]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForAdminConversation] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [FalseEvent] [Src/CsStateMachine.cpp:168]|

## Check to see if the user should be notified that their alternate greeting is enabled.  If they should be, then a menu is offered that allows the user to disable their alternate greeting.
## This is controlled by "After Logon Play -> Alternate Greeting Notification" in the Phone Menu page of the user in the SA.

07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckAlternateGreeting] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckAlternateGreeting] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnEntry : State name=[CheckAlternateGreeting] onentry=[] library=[libConvSub.so] [Src/CustomState.cpp:162]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,Failed to retrieve Subscriber's Spoken Name Stream 0x80046401 (Src/CsCallSubscriber.cpp 2706)|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckAlternateGreeting_OnEntry: ICsNamedProps::SetPropString(strExtension) to value: 1010 returned 0x00000000 S_OK [Src/SubSignIn.cpp:462]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckAlternateGreeting_OnEntry: ICsNamedProps::SetPropBool(bHaveVoiceName) to value: false returned 0x00000000 S_OK [Src/SubSignIn.cpp:467]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,CheckAlternateGreeting_OnEntry: Say warning for alternate greeting: false [Src/SubSignIn.cpp:470]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,CheckAlternateGreeting_OnEntry: ICsEventQueue::PushID(FALSE_EVENT) returned 0x00000000 S_OK [Src/SubSignIn.cpp:474]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckAlternateGreeting] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [FalseEvent] [Src/CsStateMachine.cpp:168]|

## Check to see if the user logged in over another user's greeting.  If they did, then the opportunity is presented to leave a message for the user whose greeting they signed in from.

07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForLeaveMsg] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForLeaveMsg] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.383 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10, : CCustomState::OnEntry : State name=[CheckForLeaveMsg] onentry=[] library=[libConvSub.so] [Src/CustomState.cpp:162]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckForLeaveMsg_OnEntry: ICsNamedProps::GetPropVariant() found property PreviousCurEnt = .  GetPropVariant() returned 0x00000000 [Src/SubSignIn.cpp:249]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,CheckForLeaveMsg_OnEntry: UID = [{1faee113-beeb-4b89-b6eb-34ab12a56b98}] - SZ_AVCONVPHCONSTANTS_PREVIOUSCURENT [Src/SubSignIn.cpp:257]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckForLeaveMsg_OnEntry: ICsNamedProps::GetPropString() found property SignInID = 1010.  GetPropString() returned 0x00000000 S_OK [Src/SubSignIn.cpp:265]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,3,CheckForLeaveMsg_OnEntry: ICsNamedProps::GetPropBool() could not find property: ConvSubResumeMsg.  GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubSignIn.cpp:298]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,ConvSub,5,CheckForLeaveMsg_OnEntry: ICsEventQueue::PushID(FALSE_EVENT) returned 0x00000000 S_OK [Src/SubSignIn.cpp:301]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignInUtil.cde!CheckForLeaveMsg] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [FalseEvent] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [NULL] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Exiting state machine. [Src/CsStateMachine.cpp:227]|

## This is the end of the authentication (sign in) process.  The caller is now taken to the subscriber menu conversation.

07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubSignInUtil : CCsCDEConvBase::Run : Exiting conversation [SubSignInUtil] [Src/CsCDEConvBase.cpp:389]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!RunSignInUtil] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [FalseEvent] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignIn.cde!RunSignInUtil_default] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!RunSignInUtil_default] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!RunSignInUtil_default] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Event is [NULL] [Src/CsStateMachine.cpp:168]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubSignIn.cde!RunNextConv] [Src/CsStateMachine.cpp:190]|
07/08/2008 09:19:01.384 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubSignIn.cde!RunNextConv] [Src/CsStateMachine.cpp:139]|
07/08/2008 09:19:01.388 |2359,CCM-1-001,4BF5B4F26B24498383C576056A4DD366,CDE,16,SubMenu : CCsCDEConvBase::Run : Entering conversation [SubMenu] [Src/CsCDEConvBase.cpp:123]|