Here is a new one...
I have an IP Office 500 v7.26 with embedded voicemail. It is in Standard mode running digital sets. Basic setup with analog lines and ATM cards.
The receptionist claimed when she checked her voicemail in the morning the entire system would shut down. Any of your guys out there know how you get a little more dramatic story and mostly these are coincidences. But this one is for real. I observed it happen. She waited until I arrived and tried to access her voicemail from the hard-set mail button on her phone and like clockwork- system shutdown and started rebooting.
I had monitor on and set as many related filters up and caught the report leading up to the shutdown. ( Below)
To date I have replaced the SD card reloaded configs. Checked voicemail on all other extensions and I don't see anything different. Nothing sticks out.
Can anyone take a look and see in the monitor report and see what this possibly could be? (final failure at the very bottom)
The next step in to upgrade to 7.26(36) which I need to do anyway but if anyone has seen this I would love to know what this gremlin's name is.
JerrBerr
[sub][tt]9479065mS CMCallEvt: 0.2854.0 -1 BaseEP: NEW CMEndpoint f5464d88 TOTAL NOW=3 CALL_LIST=1
89479066mS CMCallEvt: 0.2854.0 -1 Receptionist.-1: NEW CMExtnEndpoint f5464d88, Name=Receptionist, Extn=201, Phys Extn=201
89479066mS CMCallEvt: CREATE CALL:577 (f54835c0)
89479066mS CMCallEvt: 0.2855.0 -1 BaseEP: NEW CMEndpoint f54a5010 TOTAL NOW=4 CALL_LIST=1
89479068mS CMExtnEvt: Receptionist: CMExtnHandler::SetCurrent( id: 0->2854 )
89479068mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Receptionist
89479071mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
89479071mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Receptionist
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: Setting Hard Timer 4000
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: LOOKUP CALL ROUTE: type=102 called_party=?Receptionist sub=oink calling=201 dir=out complete=0 ses=0
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: ADD TARGET (N): number=?Receptionist type=102 depth=1 nobar=1 setorig=1 ses=0
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: ADD VM TARGET
89479073mS CMTARGET: **** 0.2854.0 577 Receptionist.0: MakeVoicemailTarget pbx=<null> local=1 type=3
89479073mS CMCallEvt: 0.2856.0 -1 BaseEP: NEW CMEndpoint f546651c TOTAL NOW=5 CALL_LIST=2
89479073mS CMTARGET: 0.2856.0 577 RAS.0: ADD PRIMARY
89479073mS CMTARGET: 0.2854.0 577 Receptionist.0: ADD VM TARGET: SUCCEEDED
89479073mS CMTARGET: 0.2854.0 577 Receptionist.0: CancelTimer CMTCDelayedProcessing
89479074mS CMTARGET: 0.2854.0 577 Receptionist.0: INITIAL TARGETING SUCCEEDED
89479074mS CMTARGET: 0.2854.0 577 Receptionist.0: GetNoAnswerTimer:15
89479074mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
89479075mS CMCallEvt: 0.2855.0 577 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
89479076mS CMCallEvt: 0.2856.0 577 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
89479076mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->2856 )
89479076mS CMMap: a=21.42 b=1.254 T
89479080mS CD: CALL: 0.2854.0 BState=Idle Cut=0 Music=0.0 Aend="Receptionist(201)" (0.1) Bend="" [RAS] (0.0) CalledNum=?Receptionist () CallingNum=201 (WITHHELD) Internal=1 Time=14 AState=Dialling
89479080mS CD2: CALL:S 0.2854.0,0.2855.0,7,0,0,0,0,0,Receptionist(201),,RAS,0.1,0.0,100.102,,1.101,201,oink,,102,,100,,0,0,0,0,0.0,,,,,,,1,14,0,0,0,0,enu,,0,,0,0,0,0,,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89479082mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Receptionist] Type=Default (100) Reason=CMDRdirect
UUI type=Local [...] [0x00 0x03 0x1e ]
89479082mS CMCallPkt: v=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
89479083mS CMCallEvt: 0.2856.0 577 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
89479083mS CMCallEvt: 0.2855.0 577 TargetingEP: RequestEnd 0.2856.0 577 RAS.0
89479083mS CMTARGET: 0.2854.0 577 Receptionist.0: CancelTimer CMTCNoAnswerTimeout
89479083mS CMCallEvt: 0.2855.0 -1 BaseEP: DELETE CMEndpoint f54a5010 TOTAL NOW=4 CALL_LIST=2
89479084mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
89479084mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSDialling->CMCSOGConnReq
89479084mS CMExtnEvt: v=1 State, new=Connected old=Dialling,0,0,Receptionist
89479085mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
89479085mS CMTARGET: 0.2854.0 577 Receptionist.0: ~CMTargetHandler f549f25c ep f5464d88
89479087mS CMCallEvt: 0.2856.0 577 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
89479094mS CMMap: PCG::MapBChan pcp[108]b1r0 cp_b f550c864 other_cp_b 0 type CGTypeSimple
89479095mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f59cbe3c other_cp_b f550c864 type CGTypeSimple
89479095mS CMMap: a=0.1 b=21.42 M1
89479131mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Receptionist] Type=Default (100) Reason=CMDRdirect
UUI type=Local [...] [0x00 0x03 0x1e ]
89479131mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Summary] Type=Default (100) Reason=CMDRdirect Calling[3,0,30] Type=Default Plan=Default
Display [Receptionist]
89479132mS CD: CALL: 0.2854.0 BState=Connected Cut=3 Music=0.0 Aend="Receptionist(201)" (0.1) Bend="(Voicemail)" [VoiceMail] (21.42) CalledNum=?Receptionist () CallingNum=201 (WITHHELD) Internal=1 Time=66 AState=Connected
89479132mS CD2: CALL:S 0.2854.0,0.2855.0,2,2,1,0,1,0,Receptionist(201),RAS,,0.1,21.42,100.102,?Receptionist,1.101,201,oink,,102,?Receptionist,100,,0,16,0,0,0.0,,,,,,,1,66,45,0,0,0,enu,,0,,0,0,0,0,,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89480940mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[MsgPlaying] Type=Default (100) Reason=CMDRdirect Called SubAddress[2015/1/28 17:11] Calling[9156296257] Type=Default Plan=Default Calling SubAddress[24]
Display [FRED LOYA INS >Main Cover exts]
Tag type=Text flags=0x1 [SECT39534.] [0x53 0x45 0x43 0x54 0x33 0x39 0x35 0x33 0x34 0x00 ]
89480941mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[201] Type=Default (100) Reason=CMDRdirect
Display [MailBoxReply]
Tag type=Binary flags=0x1 [VMM$SECT39534$O$2015/01/28 17:11$9156296257$24$0$0$201$] [0x56 0x4d 0x4d 0x24 0x53 0x45 0x43 0x54 0x33 0x39 0x35 0x33 0x34 0x24 0x4f 0x24 0x32 0x30 0x31 0x35 0x2f 0x30 0x31 0x2f 0x32 0x38 0x20 0x31 0x37 ]
89480941mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Receptionist] Type=Default (100) Reason=CMDRdirect
UUI type=Local [...] [0x01 0x02 0x1e ]
89481805mS LIC: Processing token (serial number=1361507211)
89481805mS LIC: ProcessToken (Serial number = 1361507211)
89483241mS RES: Thu 29/1/2015 09:43:01 FreeMem=66873704(2) CMMsg=6 (9) Buff=5200 895 1000 7462 5 Links=5152
89483241mS RES2: IP 500 V2 7.0(27) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=2 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
89486806mS LIC: Processing token (serial number=1361507211)
89486806mS LIC: ProcessToken (Serial number = 1361507211)
89488741mS RES: Thu 29/1/2015 09:43:07 FreeMem=66873700(2) CMMsg=6 (9) Buff=5200 945 998 7462 5 Links=5206
89488741mS RES2: IP 500 V2 7.0(27) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=50 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=2 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
89489381mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Summary] Type=Default (100) Reason=CMDRdirect Calling[2,1,30] Type=Default Plan=Default
Display [Receptionist]
89489381mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[MsgPlaying] Type=Default (100) Reason=CMDRdirect Called SubAddress[2015/1/28 17:11] Calling[9156296257] Type=Default Plan=Default Calling SubAddress[24]
Display [FRED LOYA INS >Main Cover exts]
Tag type=Text flags=0x1 [SECT39534.] [0x53 0x45 0x43 0x54 0x33 0x39 0x35 0x33 0x34 0x00 ]
89491410mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSConnected->CMCSCompleted
89491412mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Connected,0,0,Receptionist
89491413mS CMLOGGING: CALL:2015/01/2909:42,00:00:12,000,201,O,?Receptionist,?Receptionist,Receptionist,,,1,,""n/a,0
89491413mS CD: CALL: 0.2854.0 BState=Connected Cut=2 Music=0.0 Aend="Receptionist(201)" (0.1) Bend="(Voicemail)" [VoiceMail] (21.42) CalledNum=?Receptionist () CallingNum=201 (WITHHELD) Internal=1 Time=12347 AState=Idle
89491414mS CD2: CALL:S 0.2854.0,0.2855.0,0,2,0,0,1,0,Receptionist(201),RAS,,0.1,21.42,100.102,?Receptionist,1.101,201,oink,,102,?Receptionist,100,,0,16,0,0,0.0,,,,,,,1,12347,0,0,12326,0,enu,,0,,0,0,0,0,,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89491414mS CD: CALL: 0.2854.0 Deleted
89491414mS CD2: CALL 0.2854.0,0.2855.0,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89491414mS CMExtnEvt: Receptionist: CALL LOST (CMCauseNormal)
89491414mS CMExtnEvt: Receptionist: Extn(201) Calling Party Number(201) Type(CMNTypeInternal)
89491414mS CMExtnEvt: Receptionist: CMExtnHandler::SetCurrent( id: 2854->0 )
89491415mS CMCallEvt: 0.2854.0 -1 Receptionist.-1: StateChange: END=X CMCSCompleted->CMCSDelete
89491416mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
89491416mS CMCallEvt: 0.2856.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
89491416mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
89491417mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f59cbe3c other_cp_b f550c864
89491418mS CMMap: a=0.1 b=21.42 M0
89491418mS CMMap: PCG::UnmapBChan pcp[108]b1r0 cp_b f550c864 other_cp_b 0
89491419mS PRN: Begin Stack Trace
89491419mS PRN: pc=f060cf3c
89491419mS PRN: lr=f08a9688
89491419mS PRN: findfunc 00000000 f08a9688 f089e5f0 f02987b4 f0298760 00000000 00000000 00000000 00000000 00000000
89491419mS PRN: End Stack Trace
89491419mS PRN: ScheduleHistory NOT enabled: Task = DCPLDisplayTask File = ../platform/platform.cpp Line = 1113
89491419mS PRN: .FATAL TLB Data address=00000010 d=5 pc=f060cf3c 00000000 f08a9688 f089e5f0 f02987b4 f0298760 IP 500 V2 7.0(27)[/tt][sub][sup][/sup][/sub][/sub]
I have an IP Office 500 v7.26 with embedded voicemail. It is in Standard mode running digital sets. Basic setup with analog lines and ATM cards.
The receptionist claimed when she checked her voicemail in the morning the entire system would shut down. Any of your guys out there know how you get a little more dramatic story and mostly these are coincidences. But this one is for real. I observed it happen. She waited until I arrived and tried to access her voicemail from the hard-set mail button on her phone and like clockwork- system shutdown and started rebooting.
I had monitor on and set as many related filters up and caught the report leading up to the shutdown. ( Below)
To date I have replaced the SD card reloaded configs. Checked voicemail on all other extensions and I don't see anything different. Nothing sticks out.
Can anyone take a look and see in the monitor report and see what this possibly could be? (final failure at the very bottom)
The next step in to upgrade to 7.26(36) which I need to do anyway but if anyone has seen this I would love to know what this gremlin's name is.
JerrBerr
[sub][tt]9479065mS CMCallEvt: 0.2854.0 -1 BaseEP: NEW CMEndpoint f5464d88 TOTAL NOW=3 CALL_LIST=1
89479066mS CMCallEvt: 0.2854.0 -1 Receptionist.-1: NEW CMExtnEndpoint f5464d88, Name=Receptionist, Extn=201, Phys Extn=201
89479066mS CMCallEvt: CREATE CALL:577 (f54835c0)
89479066mS CMCallEvt: 0.2855.0 -1 BaseEP: NEW CMEndpoint f54a5010 TOTAL NOW=4 CALL_LIST=1
89479068mS CMExtnEvt: Receptionist: CMExtnHandler::SetCurrent( id: 0->2854 )
89479068mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Receptionist
89479071mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
89479071mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Receptionist
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: Setting Hard Timer 4000
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: LOOKUP CALL ROUTE: type=102 called_party=?Receptionist sub=oink calling=201 dir=out complete=0 ses=0
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: ADD TARGET (N): number=?Receptionist type=102 depth=1 nobar=1 setorig=1 ses=0
89479072mS CMTARGET: 0.2854.0 577 Receptionist.0: ADD VM TARGET
89479073mS CMTARGET: **** 0.2854.0 577 Receptionist.0: MakeVoicemailTarget pbx=<null> local=1 type=3
89479073mS CMCallEvt: 0.2856.0 -1 BaseEP: NEW CMEndpoint f546651c TOTAL NOW=5 CALL_LIST=2
89479073mS CMTARGET: 0.2856.0 577 RAS.0: ADD PRIMARY
89479073mS CMTARGET: 0.2854.0 577 Receptionist.0: ADD VM TARGET: SUCCEEDED
89479073mS CMTARGET: 0.2854.0 577 Receptionist.0: CancelTimer CMTCDelayedProcessing
89479074mS CMTARGET: 0.2854.0 577 Receptionist.0: INITIAL TARGETING SUCCEEDED
89479074mS CMTARGET: 0.2854.0 577 Receptionist.0: GetNoAnswerTimer:15
89479074mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
89479075mS CMCallEvt: 0.2855.0 577 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
89479076mS CMCallEvt: 0.2856.0 577 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
89479076mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->2856 )
89479076mS CMMap: a=21.42 b=1.254 T
89479080mS CD: CALL: 0.2854.0 BState=Idle Cut=0 Music=0.0 Aend="Receptionist(201)" (0.1) Bend="" [RAS] (0.0) CalledNum=?Receptionist () CallingNum=201 (WITHHELD) Internal=1 Time=14 AState=Dialling
89479080mS CD2: CALL:S 0.2854.0,0.2855.0,7,0,0,0,0,0,Receptionist(201),,RAS,0.1,0.0,100.102,,1.101,201,oink,,102,,100,,0,0,0,0,0.0,,,,,,,1,14,0,0,0,0,enu,,0,,0,0,0,0,,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89479082mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Receptionist] Type=Default (100) Reason=CMDRdirect
UUI type=Local [...] [0x00 0x03 0x1e ]
89479082mS CMCallPkt: v=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
89479083mS CMCallEvt: 0.2856.0 577 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
89479083mS CMCallEvt: 0.2855.0 577 TargetingEP: RequestEnd 0.2856.0 577 RAS.0
89479083mS CMTARGET: 0.2854.0 577 Receptionist.0: CancelTimer CMTCNoAnswerTimeout
89479083mS CMCallEvt: 0.2855.0 -1 BaseEP: DELETE CMEndpoint f54a5010 TOTAL NOW=4 CALL_LIST=2
89479084mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
89479084mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSDialling->CMCSOGConnReq
89479084mS CMExtnEvt: v=1 State, new=Connected old=Dialling,0,0,Receptionist
89479085mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
89479085mS CMTARGET: 0.2854.0 577 Receptionist.0: ~CMTargetHandler f549f25c ep f5464d88
89479087mS CMCallEvt: 0.2856.0 577 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
89479094mS CMMap: PCG::MapBChan pcp[108]b1r0 cp_b f550c864 other_cp_b 0 type CGTypeSimple
89479095mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f59cbe3c other_cp_b f550c864 type CGTypeSimple
89479095mS CMMap: a=0.1 b=21.42 M1
89479131mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Receptionist] Type=Default (100) Reason=CMDRdirect
UUI type=Local [...] [0x00 0x03 0x1e ]
89479131mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Summary] Type=Default (100) Reason=CMDRdirect Calling[3,0,30] Type=Default Plan=Default
Display [Receptionist]
89479132mS CD: CALL: 0.2854.0 BState=Connected Cut=3 Music=0.0 Aend="Receptionist(201)" (0.1) Bend="(Voicemail)" [VoiceMail] (21.42) CalledNum=?Receptionist () CallingNum=201 (WITHHELD) Internal=1 Time=66 AState=Connected
89479132mS CD2: CALL:S 0.2854.0,0.2855.0,2,2,1,0,1,0,Receptionist(201),RAS,,0.1,21.42,100.102,?Receptionist,1.101,201,oink,,102,?Receptionist,100,,0,16,0,0,0.0,,,,,,,1,66,45,0,0,0,enu,,0,,0,0,0,0,,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89480940mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[MsgPlaying] Type=Default (100) Reason=CMDRdirect Called SubAddress[2015/1/28 17:11] Calling[9156296257] Type=Default Plan=Default Calling SubAddress[24]
Display [FRED LOYA INS >Main Cover exts]
Tag type=Text flags=0x1 [SECT39534.] [0x53 0x45 0x43 0x54 0x33 0x39 0x35 0x33 0x34 0x00 ]
89480941mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[201] Type=Default (100) Reason=CMDRdirect
Display [MailBoxReply]
Tag type=Binary flags=0x1 [VMM$SECT39534$O$2015/01/28 17:11$9156296257$24$0$0$201$] [0x56 0x4d 0x4d 0x24 0x53 0x45 0x43 0x54 0x33 0x39 0x35 0x33 0x34 0x24 0x4f 0x24 0x32 0x30 0x31 0x35 0x2f 0x30 0x31 0x2f 0x32 0x38 0x20 0x31 0x37 ]
89480941mS CMCallPkt: v=0
CMVoiceMailStatus
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[Receptionist] Type=Default (100) Reason=CMDRdirect
UUI type=Local [...] [0x01 0x02 0x1e ]
89481805mS LIC: Processing token (serial number=1361507211)
89481805mS LIC: ProcessToken (Serial number = 1361507211)
89483241mS RES: Thu 29/1/2015 09:43:01 FreeMem=66873704(2) CMMsg=6 (9) Buff=5200 895 1000 7462 5 Links=5152
89483241mS RES2: IP 500 V2 7.0(27) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=2 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
89486806mS LIC: Processing token (serial number=1361507211)
89486806mS LIC: ProcessToken (Serial number = 1361507211)
89488741mS RES: Thu 29/1/2015 09:43:07 FreeMem=66873700(2) CMMsg=6 (9) Buff=5200 945 998 7462 5 Links=5206
89488741mS RES2: IP 500 V2 7.0(27) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=50 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=2 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
89489381mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Summary] Type=Default (100) Reason=CMDRdirect Calling[2,1,30] Type=Default Plan=Default
Display [Receptionist]
89489381mS CMCallPkt: v=0
CMVoiceMailMsg
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[MsgPlaying] Type=Default (100) Reason=CMDRdirect Called SubAddress[2015/1/28 17:11] Calling[9156296257] Type=Default Plan=Default Calling SubAddress[24]
Display [FRED LOYA INS >Main Cover exts]
Tag type=Text flags=0x1 [SECT39534.] [0x53 0x45 0x43 0x54 0x33 0x39 0x35 0x33 0x34 0x00 ]
89491410mS CMCallEvt: 0.2854.0 577 Receptionist.0: StateChange: END=A CMCSConnected->CMCSCompleted
89491412mS CMExtnEvt: v=1 State, new=PortRecoverDelay old=Connected,0,0,Receptionist
89491413mS CMLOGGING: CALL:2015/01/2909:42,00:00:12,000,201,O,?Receptionist,?Receptionist,Receptionist,,,1,,""n/a,0
89491413mS CD: CALL: 0.2854.0 BState=Connected Cut=2 Music=0.0 Aend="Receptionist(201)" (0.1) Bend="(Voicemail)" [VoiceMail] (21.42) CalledNum=?Receptionist () CallingNum=201 (WITHHELD) Internal=1 Time=12347 AState=Idle
89491414mS CD2: CALL:S 0.2854.0,0.2855.0,0,2,0,0,1,0,Receptionist(201),RAS,,0.1,21.42,100.102,?Receptionist,1.101,201,oink,,102,?Receptionist,100,,0,16,0,0,0.0,,,,,,,1,12347,0,0,12326,0,enu,,0,,0,0,0,0,,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89491414mS CD: CALL: 0.2854.0 Deleted
89491414mS CD2: CALL 0.2854.0,0.2855.0,577,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
89491414mS CMExtnEvt: Receptionist: CALL LOST (CMCauseNormal)
89491414mS CMExtnEvt: Receptionist: Extn(201) Calling Party Number(201) Type(CMNTypeInternal)
89491414mS CMExtnEvt: Receptionist: CMExtnHandler::SetCurrent( id: 2854->0 )
89491415mS CMCallEvt: 0.2854.0 -1 Receptionist.-1: StateChange: END=X CMCSCompleted->CMCSDelete
89491416mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
89491416mS CMCallEvt: 0.2856.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
89491416mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
89491417mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f59cbe3c other_cp_b f550c864
89491418mS CMMap: a=0.1 b=21.42 M0
89491418mS CMMap: PCG::UnmapBChan pcp[108]b1r0 cp_b f550c864 other_cp_b 0
89491419mS PRN: Begin Stack Trace
89491419mS PRN: pc=f060cf3c
89491419mS PRN: lr=f08a9688
89491419mS PRN: findfunc 00000000 f08a9688 f089e5f0 f02987b4 f0298760 00000000 00000000 00000000 00000000 00000000
89491419mS PRN: End Stack Trace
89491419mS PRN: ScheduleHistory NOT enabled: Task = DCPLDisplayTask File = ../platform/platform.cpp Line = 1113
89491419mS PRN: .FATAL TLB Data address=00000010 d=5 pc=f060cf3c 00000000 f08a9688 f089e5f0 f02987b4 f0298760 IP 500 V2 7.0(27)[/tt][sub][sup][/sup][/sub][/sub]