## These traces are specific to responding to a hand off for a transfer (should have already looked at the hand off request traces and the hand off response for a logon traces). ## The traces start with the B tone being received. 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,1,GatherDigits: GatherDigits() returned 0x00000000 S_OK, digits=[B], num=[1] [Src/PHGreeting.cpp:2210]| 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,GatherDigits: DTMF received [B] [Src/PHGreeting.cpp:2218]| 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,GatherDigits: Matched one-key [B] [Src/PHGreeting.cpp:2241]| 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,1,GatherDigits: put_IgnoreDigits() returned 0x00000000 S_OK [Src/PHGreeting.cpp:2273]| 07/31/2008 09:47:46.402 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,GatherDigits: Gathering additional digits... [Src/PHGreeting.cpp:2275]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,1,GatherDigits: GatherDigits() returned 0x00045b35 S_MIU_TERM_TIMEOUT, digits=[], num=[0] [Src/PHGreeting.cpp:2285]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,GatherDigits: Matched one-key [B] [Src/PHGreeting.cpp:2289]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,GatherDigits: Digits [B] [Src/PHGreeting.cpp:2297]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [GatherDigits]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,ProcessUserInput: GatherDigits() succeeded. Digits=[B], Menu Index =[13] [Src/PHGreeting.cpp:2515]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,ProcessUserInput: Processing return from GatherDigits() hr=0x00041905 S_1KEY [Src/PHGreeting.cpp:2533]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,ProcessUserInput: Hand off request, received [B] [Src/PHGreeting.cpp:2573]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [ProcessUserInput]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(Greeting) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3795]| 07/31/2008 09:47:47.904 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(VoiceName) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3796]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(CallHandlerAccessID) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3797]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(fVoiceNameAvailable) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3798]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnEntry: ICsNamedProps::RemoveProp(bTransferOverride) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3799]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,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:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,PlayGreeting_OnEntry: Either bUseErrorMsgRule is false or S_CALL_COMPLETE was returned [Src/PHGreeting.cpp:3819]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [PlayGreeting_OnEntry]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [PlayGreeting_OnExit]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(bUseErrorMsgRule) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:3846]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::GetPropBool() found property bCallHandlerData = true. GetPropBool() returned 0x00000000 S_OK [Src/PHGreeting.cpp:3850]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [CCsConvReporter]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [CCsConvReporter]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [ReportCallHandlerTraffic]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataDTMF = . GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:436]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataText = Opening Greeting. GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:439]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataAction = disconnect. GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:442]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ReportCallHandlerTraffic: ICsNamedProps::GetPropString() found property TrafficDataActionDTMF = . GetPropString() returned 0x00000000 S_OK [Src/ConvReporter.cpp:445]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [ReportCallHandlerTraffic]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataDTMF) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3857]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataText) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3858]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataAction) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3859]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(TrafficDataActionDTMF) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3860]| 07/31/2008 09:47:47.905 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PlayGreeting_OnExit: ICsNamedProps::RemoveProp(bCallHandlerData) returned 0x00000000 S_OK [Src/PHGreeting.cpp:3861]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [PlayGreeting_OnExit]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [PHGreeting_RunExit]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCallBusy) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:145]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(lAfterGreetingAction) returned 0x00000000 S_OK [Src/PHGreeting.cpp:146]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bIgnoreBusyGreeting) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:147]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bIgnoreInternalGreeting) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:148]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bIgnoreOffHoursGreeting) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:149]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCheckSubMboxOutsiderCaller) returned 0x00000000 S_OK [Src/PHGreeting.cpp:150]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCheckSubMboxNotifyOutsiderCaller) returned 0x00000000 S_OK [Src/PHGreeting.cpp:151]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(strDialingDomainSubEMAIL) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/PHGreeting.cpp:152]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,PHGreeting_RunExit: ICsNamedProps::RemoveProp(bCheckRecipientServer) returned 0x00000000 S_OK [Src/PHGreeting.cpp:153]| 07/31/2008 09:47:47.906 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [PHGreeting_RunExit]| ## Hand off process begins. 07/31/2008 09:47:47.911 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [RespondToHandOffRequest_OnEntry]| 07/31/2008 09:47:47.913 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:47.913 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:47.913 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,RespondToHandOffRequest_OnEntry: Hand off response, sent [D] [Src/HandOff.cpp:644]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,RespondToHandOffRequest_OnEntry: Packet received [21011*1010#] [Src/HandOff.cpp:703]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,RespondToHandOffRequest_OnEntry: ICsNamedProps::SetPropString(HandOff_Packet) to value: 21011*1010# returned 0x00000000 S_OK [Src/HandOff.cpp:716]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,RespondToHandOffRequest_OnEntry: ICsEventQueue::PushID(TRUE_EVENT) returned 0x00000000 S_OK [Src/HandOff.cpp:718]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [RespondToHandOffRequest_OnEntry]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [ProcessPacket_OnEntry]| ## For transfers the packet is broken apart as follows: ## HandOff_TransferCallerId holds the caller id of the call. ## HandOff_TransferCalledId holds the extension that was matched. ## HandOff_TransferOverride is not shown in these diags, but would be set to true if the caller had pressed [#2] to skip to the greeting. 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ProcessPacket_OnEntry: ICsNamedProps::GetPropString() found property HandOff_Packet = 21011*1010#. GetPropString() returned 0x00000000 S_OK [Src/HandOff.cpp:732]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ProcessPacket_OnEntry: ICsNamedProps::SetPropString(HandOff_TransferCallerId) to value: 1011 returned 0x00000000 S_OK [Src/HandOff.cpp:819]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,ProcessPacket_OnEntry: ICsNamedProps::SetPropString(HandOff_TransferCalledId) to value: 1010 returned 0x00000000 S_OK [Src/HandOff.cpp:832]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,5,ProcessPacket_OnEntry: ICsEventQueue::PushName(RouteToTransfer) returned 0x00000000 S_OK [Src/HandOff.cpp:838]| 07/31/2008 09:47:51.949 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [ProcessPacket_OnEntry]| ## RouteToTransfer_OnEntry takes all the values that where within the packet and places them in the correct properties so that the transfer conversation can be called and the caller can be transferred to the user. 07/31/2008 09:47:51.950 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [RouteToTransfer_OnEntry]| 07/31/2008 09:47:51.950 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [UpdateCallWithTransferSettings]| 07/31/2008 09:47:51.950 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,UpdateCallWithTransferSettings: ICsNamedProps::GetPropString() found property HandOff_TransferCallerId = 1011. GetPropString() returned 0x00000000 S_OK [Src/HandOff.cpp:919]| 07/31/2008 09:47:51.950 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [WriteToMonitor]| 07/31/2008 09:47:51.950 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [WriteToMonitor]| 07/31/2008 09:47:51.950 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,UpdateCallWithTransferSettings: ICsNamedProps::GetPropString() found property HandOff_TransferCalledId = 1010. GetPropString() returned 0x00000000 S_OK [Src/HandOff.cpp:935]| 07/31/2008 09:47:51.992 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,3,UpdateCallWithTransferSettings: ICsNamedProps::SetPropVariant(CurEnt) to value: returned 0x00043210 S_NP_PROP_REPLACED [Src/HandOff.cpp:962]| 07/31/2008 09:47:51.992 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [UpdateCallWithTransferSettings]| 07/31/2008 09:47:51.992 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Exiting [RouteToTransfer_OnEntry]| ## Now the call is taken to the transfer conversation. From here on its just like a typical transfer. 07/31/2008 09:47:51.992 |881,CCM-1-001,353DE8226E034CFC8CC9A3517648F42E,ConvSub,0,Entering [PHTransfer_RunInit]|