## These traces are specific to responding to a hand off for a logon (should have already looked at the hand off request traces). ## The logs being at the Opening Greeting receiving a B tone. So what has happened is the originating server has called this home server, and is sending a B tone to request a hand off. ## The B tone can be received at any call handler greeting and it will be handled correctly. 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,1,GatherDigits: GatherDigits() returned 0x00000000 S_OK, digits=[B], num=[1] [Src/PHGreeting.cpp:2210]| 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,GatherDigits: DTMF received [B] [Src/PHGreeting.cpp:2218]| 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,GatherDigits: Matched one-key [B] [Src/PHGreeting.cpp:2241]| 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,1,GatherDigits: put_IgnoreDigits() returned 0x00000000 S_OK [Src/PHGreeting.cpp:2273]| 07/31/2008 09:47:17.369 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,GatherDigits: Gathering additional digits... [Src/PHGreeting.cpp:2275]| 07/31/2008 09:47:18.871 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,1,GatherDigits: GatherDigits() returned 0x00045b35 S_MIU_TERM_TIMEOUT, digits=[], num=[0] [Src/PHGreeting.cpp:2285]| 07/31/2008 09:47:18.871 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,GatherDigits: Matched one-key [B] [Src/PHGreeting.cpp:2289]| 07/31/2008 09:47:18.871 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,GatherDigits: Digits [B] [Src/PHGreeting.cpp:2297]| 07/31/2008 09:47:18.871 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [GatherDigits]| 07/31/2008 09:47:18.871 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,ProcessUserInput: GatherDigits() succeeded. Digits=[B], Menu Index =[13] [Src/PHGreeting.cpp:2515]| 07/31/2008 09:47:18.871 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,ProcessUserInput: Processing return from GatherDigits() hr=0x00041905 S_1KEY [Src/PHGreeting.cpp:2533]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [WriteToMonitor]| ## Here the B tone is translated as a hand off request. If the check box to respond to hand off's was not checked, this diag would not show up. That is the check for a hand off request DTMF would never be made. 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,ProcessUserInput: Hand off request, received [B] [Src/PHGreeting.cpp:2573]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [ProcessUserInput]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(Greeting) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3795]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(VoiceName) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3796]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(CallHandlerAccessID) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3797]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(fVoiceNameAvailable) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3798]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(bTransferOverride) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3799]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::GetPropBool() could not find property: bUseErrorMsgRule. GetPropBool() returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3802]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,PlayGreeting_OnEntry: Either bUseErrorMsgRule is false or S_CALL_COMPLETE was returned [Src/PHGreeting.cpp:3819]| 07/31/2008 09:47:18.896 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [PlayGreeting_OnEntry]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [PlayGreeting_OnExit]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(bUseErrorMsgRule) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3846]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::GetPropBool() found property bCallHandlerData = true. GetPropBool() returned 0x00000000 S_OK [Src/PHGreeting.cpp:3850]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [CCsConvReporter]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [CCsConvReporter]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [ReportCallHandlerTraffic]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataDTMF = . GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:436]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataText = Opening Greeting. GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:439]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataAction = disconnect. GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:442]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataActionDTMF = . GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:445]| 07/31/2008 09:47:18.897 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [ReportCallHandlerTraffic]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataDTMF) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3857]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataText) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3858]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataAction) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3859]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataActionDTMF) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3860]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(bCallHandlerData) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3861]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [PlayGreeting_OnExit]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [PHGreeting_RunExit]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCallBusy) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:145]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(lAfterGreetingAction) returned 0x00000000 S_OK [Src/PHGreeting.cpp:146]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bIgnoreBusyGreeting) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:147]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bIgnoreInternalGreeting) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:148]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bIgnoreOffHoursGreeting) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:149]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCheckSubMboxOutsiderCaller) returned 0x00000000 S_OK [Src/PHGreeting.cpp:150]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCheckSubMboxNotifyOutsiderCaller) returned 0x00000000 S_OK [Src/PHGreeting.cpp:151]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(strDialingDomainSubEMAIL) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:152]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCheckRecipientServer) returned 0x00000000 S_OK [Src/PHGreeting.cpp:153]| 07/31/2008 09:47:18.898 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [PHGreeting_RunExit]| ## Here is where the call gets taken to begin the hand off process. Everything before this is just cleanup from PHGreeting. 07/31/2008 09:47:18.908 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [RespondToHandOffRequest_OnEntry]| 07/31/2008 09:47:18.922 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:18.922 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [WriteToMonitor]| ## The response DTMF tone(s) are sent, in this case it is the default D tone. 07/31/2008 09:47:18.922 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,RespondToHandOffRequest_OnEntry: Hand off response, sent [D] [Src/HandOff.cpp:644]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [WriteToMonitor]| ## The packet comes over. 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,RespondToHandOffRequest_OnEntry: Packet received [11013#] [Src/HandOff.cpp:703]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,RespondToHandOffRequest_OnEntry: ICsNamedProps::SetPropString(HandOff_Packet) to value: 11013# returned 0x00000000 S_OK [Src/HandOff.cpp:716]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,RespondToHandOffRequest_OnEntry: ICsEventQueue::PushID(TRUE_EVENT) returned 0x00000000 S_OK [Src/HandOff.cpp:718]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [RespondToHandOffRequest_OnEntry]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [ProcessPacket_OnEntry]| ## ProcessPacket_OnEntry is where the packet is analyzed and the values within it are stored. ## HandOff_LogonId holds the dtmf access id of the user attempting to log on (could be the primary extension or an alternate extension). ## HandOff_KnownDevice will be set to true if the logon was done over a known device (a phone matching the user's extension). 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ProcessPacket_OnEntry: ICsNamedProps::GetPropString() found property HandOff_Packet = 11013#. GetPropString() returned 0x00000000 S_OK [Src/HandOff.cpp:732]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ProcessPacket_OnEntry: ICsNamedProps::SetPropString(HandOff_LogonId) to value: 1013 returned 0x00000000 S_OK [Src/HandOff.cpp:769]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,ProcessPacket_OnEntry: ICsNamedProps::SetPropBool(HandOff_KnownDevice) to value: true returned 0x00000000 S_OK [Src/HandOff.cpp:776]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,5,ProcessPacket_OnEntry: ICsEventQueue::PushName(RouteToLogon) returned 0x00000000 S_OK [Src/HandOff.cpp:783]| 07/31/2008 09:47:21.456 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [ProcessPacket_OnEntry]| ## RouteToLogon_OnEntry takes all the values that where within the packet and places them in the correct properties so that the sign in conversation can be called and the user can sign in. 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [RouteToLogon_OnEntry]| 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,RouteToLogon_OnEntry: ICsNamedProps::GetPropString() found property HandOff_LogonId = 1013. GetPropString() returned 0x00000000 S_OK [Src/HandOff.cpp:866]| 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,RouteToLogon_OnEntry: ICsNamedProps::SetPropString(SignInCallerID) to value: 1013 returned 0x00043210 S_NP_PROP_REPLACED [Src/HandOff.cpp:868]| 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,RouteToLogon_OnEntry: ICsNamedProps::GetPropBool() found property HandOff_KnownDevice = true. GetPropBool() returned 0x00000000 S_OK [Src/HandOff.cpp:872]| 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,3,RouteToLogon_OnEntry: ICsNamedProps::SetPropBool(Authenticate.HandOffKnownDevice) to value: true returned 0x00000000 S_OK [Src/HandOff.cpp:873]| 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Exiting [RouteToLogon_OnEntry]| ## Now the call is taken to the sign in conversation. From here on its just like a typical authentication. 07/31/2008 09:47:21.457 |880,CCM-1-001,9D2BE2EF50F247F3A91BE68B93D6ABD6,ConvSub,0,Entering [SubSignIn_RunInit]|