The Call Handler Configured for Dispatch Messaging, Weekend Hotline, Is Accessed Through

The following log lines were generated with the following micro traces turned ON.

CDE 10, 16

ConvSub 0, 1, 3, 5

Additional traces won’t add appreciably to the diagnosis of multiple message part issues

1. A message with attachments is played and attachments are explored.

Function MsgRoot_OnEntry is entered – MsgRoot_OnEntry prepares message to be played, including figuring out if the message has attachments

08/07/2008 10:18:48.524 |3115,PhoneSystem-1-001,4BDF65E83A8E423B88C9E9E1FD368E62,ConvSub,0,Entering [MsgRoot_OnEntry]|

The important property is this one: MessageHasAttachments. Here, we see it being set to true.

08/07/2008 10:18:48.531 |3115,PhoneSystem-1-001,4BDF65E83A8E423B88C9E9E1FD368E62,ConvSub,3,MsgRoot_OnEntry: ICsNamedProps::SetPropBool(MessageHasAttachments) to value: true returned 0x00000000 S_OK [Src/PlayMsg.cpp:657]|

Function MsgRoot_OnEntry finishes

08/07/2008 10:18:48.546 |3115,PhoneSystem-1-001,4BDF65E83A8E423B88C9E9E1FD368E62,ConvSub,0,Exiting [MsgRoot_OnEntry]|

Later in the logs, in reponse to the after message menu, the user presses 98, which has been set to play attachments in the custom keypad map

08/07/2008 10:20:27.795 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubMsgPlay.cde!MsgMenu] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|

08/07/2008 10:20:27.795 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Event is [TT98_EVENT] [Src/CsStateMachine.cpp:168]|

This gets Lathe MessageAttachments state, which runs the SubMutliPart conversation

08/07/2008 10:20:27.795 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubMsgPlay.cde!MessageAttachments] [Src/CsStateMachine.cpp:190]|

08/07/2008 10:20:27.795 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubMsgPlay.cde!MessageAttachments] [Src/CsStateMachine.cpp:139]|

08/07/2008 10:20:27.795 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,16,SubMultiPart : CCsCDEConvBase::Run : Entering conversation [SubMultiPart] [Src/CsCDEConvBase.cpp:123]|

SubMultiPart_RunStart does several things, but of importance here is counting the number of parts:

08/07/2008 10:20:28.398 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [SubMultiPart_RunStart]|

08/07/2008 10:20:28.399 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,SubMultiPart_RunStart: Found [5] parts in current message [Src/SubMultiPart.cpp:95]|

08/07/2008 10:20:28.399 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,SubMultiPart_RunStart: ICsNamedProps::SetPropLong(Multipart.Count) to value: 5 returned 0x00000000 S_OK [Src/SubMultiPart.cpp:96]|

08/07/2008 10:20:28.399 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [SubMultiPart_RunStart]|

PrepareCurrentPart does the heavy lifting to figure out what the first attachment, or message part, is, and prepares it for play. Highlighted are interesting things about the message part. This first part is the actual message body, incidentally

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubMultiPart.cde!PrepareCurrentPart] [Src/CsStateMachine.cpp:190]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubMultiPart.cde!PrepareCurrentPart] [Src/CsStateMachine.cpp:139]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10, : CCustomState::OnEntry : State name=[PrepareCurrentPart] onentry=[MMP_PrepareCurrentPart_OnEntry] library=[libConvSub.so] [Src/CustomState.cpp:162]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [MMP_PrepareCurrentPart_OnEntry]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::GetPropLong() found property Multipart.Count = 5. GetPropLong() returned 0x00000000 S_OK [Src/SubMultiPart.cpp:128]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::GetPropLong() found property Multipart.CurrentPart = 1. GetPropLong() returned 0x00000000 S_OK [Src/SubMultiPart.cpp:129]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: Calling CCsCmlServerMessage::GetAllSubParts()... [Src/SubMultiPart.cpp:137]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: CCsCmlServerMessage::GetAllSubParts() returned hr=0x00000000 S_OK, size=[5] [Src/SubMultiPart.cpp:146]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: Processing part [1] [Src/SubMultiPart.cpp:155]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: Part [1] is of type [3] [Src/SubMultiPart.cpp:163]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.BodyAvailable) to value: false returned 0x00000000 S_OK [Src/SubMultiPart.cpp:169]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartBodyText) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:170]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.NameAvailable) to value: false returned 0x00000000 S_OK [Src/SubMultiPart.cpp:173]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartName) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:174]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.AudioAvailable) to value: false returned 0x00000000 S_OK [Src/SubMultiPart.cpp:177]|

08/07/2008 10:20:31.548 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartAudio) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:178]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.FileDescriptionAvailable) to value: false returned 0x00000000 S_OK [Src/SubMultiPart.cpp:181]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.FileDescription) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:182]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.TypeAvailable) to value: true returned 0x00000000 S_OK [Src/SubMultiPart.cpp:185]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropLong(Multipart.PartType) to value: 3 returned 0x00000000 S_OK [Src/SubMultiPart.cpp:186]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.NameAvailable) to value: false returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:189]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartName) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:190]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: CCsCmlPart::GetContentFileName() returned [] [Src/SubMultiPart.cpp:200]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [MMP_PrepareTextPart]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [PackageTextPartForTTS]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [CheckMsgStreamForAllWhiteSpace]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [CheckMsgStreamForAllWhiteSpace]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,PackageTextPartForTTS: ICsNamedProps::SetPropLong(MediaType) to value: 1 returned 0x00000000 S_OK [Src/SubMultiPart.cpp:498]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,PackageTextPartForTTS: ICsNamedProps::SetPropVariant(Stream) to value: returned 0x00000000 S_OK [Src/SubMultiPart.cpp:503]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [PackageUserSettings]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,5,PackageUserSettings: Setting TTS language to American English [Src/TTSSetup.cpp:140]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,PackageUserSettings: ICsNamedProps::SetPropString(lang) to value: American English returned 0x00000000 S_OK [Src/TTSSetup.cpp:141]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [PackageUserSettings]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,PackageTextPartForTTS: ICsNamedProps::SetPropObject(Multipart.PartBodyText) returned 0x00000000 S_OK [Src/SubMultiPart.cpp:521]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [PackageTextPartForTTS]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareTextPart: ICsNamedProps::SetPropBool(Multipart.BodyAvailable) to value: true returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:350]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [MMP_PrepareTextPart]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,5,MMP_PrepareCurrentPart_OnEntry: ICsEventQueue::PushID(TRUE_EVENT) returned 0x00000000 S_OK [Src/SubMultiPart.cpp:256]|

08/07/2008 10:20:31.549 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [MMP_PrepareCurrentPart_OnEntry]|

08/07/2008 10:20:31.550 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubMultiPart.cde!PrepareCurrentPart] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|

Now part can be played, header, then body, then menu

08/07/2008 10:20:31.550 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Event is [TrueEvent] [Src/CsStateMachine.cpp:168]|

08/07/2008 10:20:31.550 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubMultiPart.cde!PlayCurrentPartHeader] [Src/CsStateMachine.cpp:190]|

08/07/2008 10:20:34.483 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubMultiPart.cde!PlayCurrentPartBody] [Src/CsStateMachine.cpp:190]|

08/07/2008 10:20:36.296 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Next state is [/opt/cisco/connection/share/tui/SubMultiPart.cde!PlayCurrentPartMenu] [Src/CsStateMachine.cpp:190]|

Transition responds to TT9Event, which, in a Skip-and-Scan conversation, means next…so on to the next part

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Event is [TT9Event] [Src/CsStateMachine.cpp:168]|

The next part is prepared for play by PrepareCurrentPart

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : Calling OnEntry for state [/opt/cisco/connection/share/tui/SubMultiPart.cde!PrepareCurrentPart] [Src/CsStateMachine.cpp:139]|

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10, : CCustomState::OnEntry : State name=[PrepareCurrentPart] onentry=[MMP_PrepareCurrentPart_OnEntry] library=[libConvSub.so] [Src/CustomState.cpp:162]|

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [MMP_PrepareCurrentPart_OnEntry]|

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::GetPropLong() found property Multipart.Count = 5. GetPropLong() returned 0x00000000 S_OK [Src/SubMultiPart.cpp:128]|

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::GetPropLong() found property Multipart.CurrentPart = 2. GetPropLong() returned 0x00000000 S_OK [Src/SubMultiPart.cpp:129]|

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: Calling CCsCmlServerMessage::GetAllSubParts()... [Src/SubMultiPart.cpp:137]|

08/07/2008 10:20:38.973 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: CCsCmlServerMessage::GetAllSubParts() returned hr=0x00000000 S_OK, size=[5] [Src/SubMultiPart.cpp:146]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: Processing part [2] [Src/SubMultiPart.cpp:155]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: Part [2] is of type [6] [Src/SubMultiPart.cpp:163]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.BodyAvailable) to value: false returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:169]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartBodyText) returned 0x00000000 S_OK [Src/SubMultiPart.cpp:170]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.NameAvailable) to value: false returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:173]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartName) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:174]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.AudioAvailable) to value: false returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:177]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartAudio) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:178]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.FileDescriptionAvailable) to value: false returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:181]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.FileDescription) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:182]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.TypeAvailable) to value: true returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:185]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropLong(Multipart.PartType) to value: 6 returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:186]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.NameAvailable) to value: false returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:189]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::RemoveProp(Multipart.PartName) returned 0x80043200 E_NP_PROP_NOT_FOUND [Src/SubMultiPart.cpp:190]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropString(Multipart.PartName) to value: coppola-ffs-livereply.doc returned 0x00000000 S_OK [Src/SubMultiPart.cpp:196]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareCurrentPart_OnEntry: ICsNamedProps::SetPropBool(Multipart.NameAvailable) to value: true returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:197]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareCurrentPart_OnEntry: CCsCmlPart::GetContentFileName() returned [coppola-ffs-livereply.doc] [Src/SubMultiPart.cpp:200]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [MMP_PrepareOtherPart]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareOtherPart: ICsNamedProps::GetPropString() found property Multipart.PartName = coppola-ffs-livereply.doc. GetPropString() returned 0x00000000 S_OK [Src/SubMultiPart.cpp:451]|

08/07/2008 10:20:38.974 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Entering [GetTextDescriptionOfAttachment]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,GetTextDescriptionOfAttachment: Returnging [a document] as description [Src/SubMultiPart.cpp:603]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [GetTextDescriptionOfAttachment]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareOtherPart: ICsNamedProps::SetPropString(Multipart.FileDescription) to value: a document returned 0x00000000 S_OK [Src/SubMultiPart.cpp:458]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,3,MMP_PrepareOtherPart: ICsNamedProps::SetPropBool(Multipart.FileDescriptionAvailable) to value: true returned 0x00043210 S_NP_PROP_REPLACED [Src/SubMultiPart.cpp:459]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,1,MMP_PrepareOtherPart: GetTextDescriptionOfAttachment(coppola-ffs-livereply.doc) returned 0x00000000 S_OK, [a document] [Src/SubMultiPart.cpp:462]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [MMP_PrepareOtherPart]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,5,MMP_PrepareCurrentPart_OnEntry: ICsEventQueue::PushID(TRUE_EVENT) returned 0x00000000 S_OK [Src/SubMultiPart.cpp:256]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,ConvSub,0,Exiting [MMP_PrepareCurrentPart_OnEntry]|

08/07/2008 10:20:38.978 |3113,PhoneSystem-1-001,E038235DA8944EC888567B12ACD236EB,CDE,10,CCsStateMachine::Execute : OnEntry for state [/opt/cisco/connection/share/tui/SubMultiPart.cde!PrepareCurrentPart] returned 0x00000000 S_OK [Src/CsStateMachine.cpp:143]|

And so on, through all the parts.