При входящем на СО-линию "недопустимая опция"

[efffgen]

День добрый. Нуждаюсь в помощи спецов. Имеется avaya ip office 500 v2.
Настроен AA. Имею номерной план станции 2XX. При поступлении входящего звонка на СО-линию проигрывается речевое приветствие:
"Здравствуйте, Вы позвонили..." предлагает отправить факс (нажмите 0) ИЛИ дождитесь ответа специалиста - настроен перевод на экстеншн
НО!!! вместо приветствия слышу "недопустимая опция" и сразу перевод на внутренний номер.
Пробовал набирать с сотового приветствие перевод - всё работает. С городской нумерации - НЕТ("недопустимая опция")
куда копать??

[gnugk]

А почему имя записи не указано?

[efffgen]

Приветствие записывал с помощью телефона, а не брал готовый файл WAV.
Как я понимаю оно не обязательно!? или я ошибаюсь?

[Pterodahtil]

я всегда считал, что без имени оно не работает, так как обращение к файлам идет именно по этому имени. А это имя не есть имя файла. При записи по телефону, все равно создается файл.
Но раз работает....
Для порядка напишите.

[efffgen]

написал, а вот ситуация не изменилась.. с города при звонке в ответ слышу два щелчка => "недопустимая опция"

[gnugk]

А если звонить с сотового, то все работает, как я понял?

Попробуйте в настройках аналоговых линий отключить ICLID и оставить просто Начало цикла.

[efffgen]

да с сотового все ок!
в том то и дело, что я пробовал ставить и "Начало цикла" - без изменений(

[gnugk]

посмотрите трассировку в System Monitor

[efffgen]

вот два трасерта:
1. Вызов с города на CO8 => AA => перевод Extn708 => перевод Extn706
(в итоге: гудок => два щелчка => недопустимая опция => перевод Extn708)

157825mS CMLineRx: v=8
CMSetup
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=3 chan=4
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
157825mS CD: CALL: 8.5.1 BState=Idle Cut=1 Music=0.0 Aend="Line 8" (3.4) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=3 AState=Idle
157825mS CMCallEvt: 8.5.1 1 Alog Trunk:8: StateChange: END=A CMCSIdle->CMCSDialInitiated
157825mS CMTARGET: 8.5.1 1 Alog Trunk:8: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=1 ses=0
157826mS CMTARGET: 8.5.1 1 Alog Trunk:8: SET BESTMATCH: length 0 vs -1 match= dest=AA:AA
157826mS CMCallEvt: Priority hike: call 1 priority 0->3
157826mS CMTARGET: 8.5.1 1 Alog Trunk:8: LOOKUP ICR: DDI= CGPN= (Destination AA:AA ) => CDPN=AA:AA
157826mS CMTARGET: 8.5.1 1 Alog Trunk:8: ADD TARGET (N): number=AA:AA type=0 depth=1 nobar=1 setorig=1 ses=0
157826mS CMTARGET: 8.5.1 1 Alog Trunk:8: ADD VM TARGET
157827mS CMTARGET: **** 8.5.1 1 Alog Trunk:8: MakeVoicemailTarget pbx=<null> local=1 type=3
157827mS CMCallEvt: 0.1004.0 -1 BaseEP: NEW CMEndpoint f51e5084 TOTAL NOW=3 CALL_LIST=1
157827mS CMTARGET: 0.1004.0 1 RAS.0: ADD PRIMARY
157827mS CMTARGET: 8.5.1 1 Alog Trunk:8: ADD VM TARGET: SUCCEEDED
157827mS CMTARGET: 8.5.1 1 Alog Trunk:8: INITIAL TARGETING SUCCEEDED
157827mS CMTARGET: 8.5.1 1 Alog Trunk:8: GetNoAnswerTimer:11
157828mS CMCallEvt: 8.5.1 1 Alog Trunk:8: StateChange: END=A CMCSDialInitiated->CMCSDialled
157828mS CMLineTx: v=8
CMProceeding
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
157828mS CMCallEvt: 0.1003.0 1 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
157829mS CMCallEvt: 0.1004.0 1 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
157829mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1004 )
157829mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#AA:AA] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=21 chan=42
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 3
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
Display [??>#AA:AA]
Timed: 07/06/13 09:51
Locale: rus
157830mS CMMap: a=21.42 b=1.254 T
157832mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
157833mS CMCallEvt: 0.1004.0 1 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
157833mS CMCallEvt: 0.1003.0 1 TargetingEP: RequestEnd 0.1004.0 1 RAS.0
157833mS CMTARGET: 8.5.1 1 Alog Trunk:8: CancelTimer CMTCNoAnswerTimeout
157833mS CMCallEvt: 0.1003.0 -1 BaseEP: DELETE CMEndpoint f51ef534 TOTAL NOW=2 CALL_LIST=1
157834mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
157834mS CMCallEvt: 8.5.1 1 Alog Trunk:8: StateChange: END=A CMCSDialled->CMCSOGConnReq
157834mS CMLineTx: v=8
CMConnect
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#AA:AA
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#AA:AA
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1004 ipaddr=192.168.42.1 apps=0
157834mS CMLineRx: v=8
CMConnectAck
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=3 chan=4
157835mS CMCallEvt: 8.5.1 1 Alog Trunk:8: StateChange: END=A CMCSOGConnReq->CMCSConnected
157835mS CMTARGET: 8.5.1 1 Alog Trunk:8: ~CMTargetHandler f51e6870 ep f51f0eec
157835mS CMCallEvt: 0.1004.0 1 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
157836mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
157838mS CMMap: PCG::MapBChan pcp[85]b1r0 cp_b f5954690 other_cp_b 0 type CGTypeSimple
157838mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f597f82c other_cp_b f5954690 type CGTypeSimple
157839mS CMMap: a=3.4 b=21.42 M12
157839mS CMMap: a=3.4 b=21.42 CheckSpeechPathMonitor (pcp[32]b1r0) register
157839mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
157839mS PRN: Confer DSP: enabled DTMF detector 0
157890mS CD: CALL: 8.5.1 BState=Connected Cut=3 Music=0.0 Aend="Line 8" (3.4) Bend="#AA:AA(#AA:AA)" [VoiceMail] (21.42) CalledNum=#AA:AA () CallingNum= () Internal=0 Time=68 AState=Connected
157891mS CMLineRx: v=8
CMConnectAck
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=3 chan=4
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
165268mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RemoteHold.Invoke.CodePageUnknown
invokeId 14625
165269mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1004->0 )
165269mS CMExtnEvt: v=1011 State, new=Idle old=Connected,0,0,RAS
165270mS CMLineTx: v=8
CMFacility
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
HoldNotific.Invoke.CodePageUnknown
invokeId 14626
165270mS CMExtnRx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[708] Type=Default (100) Reason=CMDRdirect
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
165270mS CMCallEvt: 0.1005.0 -1 BaseEP: NEW CMEndpoint f51dcaa4 TOTAL NOW=3 CALL_LIST=1
165270mS CMCallEvt: CREATE CALL:2 (f51dc5b8)
165271mS CMCallEvt: 0.1006.0 -1 BaseEP: NEW CMEndpoint f51ef534 TOTAL NOW=4 CALL_LIST=1
165272mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1005 )
165272mS CMCallEvt: 0.1005.0 2 RAS.1: StateChange: END=A CMCSIdle->CMCSDialInitiated
165272mS CMExtnEvt: v=1011 State, new=Dialling old=Idle,0,0,RAS
165272mS CMTARGET: 0.1005.0 2 RAS.1: Setting Hard Timer 4000
165272mS CMTARGET: 0.1005.0 2 RAS.1: LOOKUP CALL ROUTE: type=100 called_party=708 sub= calling= dir=out complete=0 ses=0
165273mS CMTARGET: 0.1005.0 2 RAS.1: ADD TARGET (N): number=708 type=100 depth=1 nobar=1 setorig=1 ses=0
165273mS CMTARGET: 0.1005.0 2 RAS.1: SET USER: Extn708 orig=1
165273mS CMTARGET: 0.1005.0 2 RAS.1: ADD USER: Extn708 depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
165273mS CMTARGET: TryLoggedOutTargeting for user Extn708 Failed
165273mS CMTARGET: 0.1005.0 2 RAS.1: ADD TARGET (N): number=706 type=100 depth=2 nobar=1 setorig=0 ses=0
165273mS CMTARGET: 0.1005.0 2 RAS.1: SET USER: Extn706 orig=0
165274mS CMTARGET: 0.1005.0 2 RAS.1: ADD USER: Extn706 depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x4) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
165274mS CMCallEvt: 0.1007.0 -1 BaseEP: NEW CMEndpoint f51da048 TOTAL NOW=5 CALL_LIST=2
165274mS CMCallEvt: 0.1007.0 -1 Extn706.-1: NEW CMExtnEndpoint f51da048, Name=Extn706, Extn=706, Phys Extn=706
165275mS CMTARGET: 0.1007.0 2 Extn706.0: ADD PRIMARY
165275mS CMTARGET: 0.1005.0 2 RAS.1: CancelTimer CMTCDelayedProcessing
165275mS CMTARGET: 0.1005.0 2 RAS.1: INITIAL TARGETING SUCCEEDED
165276mS CMTARGET: 0.1005.0 2 RAS.1: GetNoAnswerTimer:11
165276mS CMCallEvt: 0.1005.0 2 RAS.1: StateChange: END=A CMCSDialInitiated->CMCSDialled
165276mS CMExtnEvt: v=1011 State, new=Proceeding old=Dialling,0,0,RAS
165276mS CMExtnTx: v=RAS, p1=0
CMProceeding
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
165277mS CMCallEvt: 0.1006.0 2 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
165277mS CMCallEvt: 0.1007.0 2 Extn706.0: StateChange: END=T CMCSIdle->CMCSOffering
165278mS CMExtnEvt: Extn706: CMExtnHandler::SetCurrent( id: 0->1007 )
165278mS CMExtnTx: v=706, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1007 in=0
Called[706] Type=Default (100) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=0 rx_gain=32 tx_gain=32 ep_callid=1005 ipaddr=192.168.42.1 apps=0
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Extn706
IE CMIEIcrPriorityDetail (239) Priority = 3
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Extn708
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:2) number=708
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
Display [??>Extn706]
Timed: 07/06/13 09:51
Locale: rus
165278mS CMExtnRx: v=706, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1007 in=0
165278mS CMCallEvt: 0.1007.0 2 Extn706.0: StateChange: END=T CMCSOffering->CMCSRinging
165279mS CMExtnEvt: v=4 State, new=Ringing old=Idle,0,0,Extn706
165279mS CMCallEvt: 0.1006.0 2 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
165279mS CMCallEvt: 0.1005.0 2 RAS.1: StateChange: END=A CMCSDialled->CMCSRingBack
165280mS CMExtnEvt: v=1011 State, new=Alerting old=Proceeding,0,0,RAS
165280mS CMExtnTx: v=RAS, p1=0
CMAlerting
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn706
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=706
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceStdPhone type=76 number=4 channel=0 rx_gain=32 tx_gain=32 ep_callid=1007 ipaddr=192.168.42.1 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
165282mS CMMap: PCG::UnmapBChan pcp[85]b1r0 cp_b f5954690 other_cp_b f597f82c
165282mS CMMap: a=21.42 b=3.4 M02
165282mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f597f82c other_cp_b 0
165282mS CMMap: a=3.4 b=0.0 H1
165282mS CMMap: a=3.4 b=21.42 CheckSpeechPathMonitor (pcp[32]b1r0) unregister
165283mS PRN: Confer DSP: disabled DTMF detector 0
165283mS PRN: Confer DSP channel 0: freed DTMF detector 0, 24 free
165312mS RES: Fri 7/6/2013 09:51:33 FreeMem=60255360(2) CMMsg=1 (6) Buff=5200 942 999 12459 5 Links=6307 BTree=0 CPU=2/6/23762/24136/26772/2
165312mS RES2: IP 500 V2 8.1(65) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=2 CMReady=2 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=25 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
165312mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
165333mS CMExtnRx: v=RAS, p1=0
CMTransferComplete
Line: type=RAS 1 Call: lid=0 id=1011 in=0
165333mS CMCallEvt: CompleteTransferTo 'this' is consultation, 'tgep' is original
165334mS CMTARGET: TransferProposal: X_call: ID=2 Aend= -> 706
165334mS CMTARGET: TransferProposal: Y_call: ID=1 Aend= -> #AA:AA
165334mS CMTARGET: Surviving Call=1
165334mS CMTARGET: Goodbying Call=2
165334mS CMTARGET: AEND -> 708 BEND
165334mS CMCallEvt: 0.1005.0 2 RAS.1: CompleteTransfer to 0.1004.0 (0.1004.0 1 RAS.0) (Return @ 0ms -> null) ssdata 0
165335mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
165335mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#AA:AA] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BChan: slot=21 chan=42
Cause=126, Transfer(IPO)
165335mS CMCallEvt: 0.1004.0 -1 RAS.-1: StateChange: END=X CMCSConnected->CMCSDelete
165337mS CMTARGET: 0.1005.0 2 RAS.0: ChangeOriginator: To 8.5.1 1 Alog Trunk:8
165338mS CMExtnTx: v=706, p1=0
CMFacility
Line: type=DigitalExtn 2 Call: lid=0 id=1007 in=0
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
Timed: 07/06/13 09:51
165338mS CMLineTx: v=8
CMFacility
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RetrieveNotific.Invoke.CodePageUnknown
invokeId 14627
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn708
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:2) number=708
Cause=126, Transfer(IPO)
165339mS CD: CALL: 8.5.1 BState=Ringing Cut=3 Music=2.0 Aend="Line 8" (3.4) Bend="Extn708(708)" [Extn706(706)] (0.0) CalledNum=706 (Extn706) CallingNum= () Internal=0 Time=7517 AState=Connected
165339mS CMLOGGING: CALL:2013/06/0709:51,00:00:00,000,,O,706,708,,,,1,,""n/a,0
165339mS CD: CALL: 0.1005.0 BState=Idle Cut=1 Music=2.0 Aend="VoiceMail" (21.42) Bend="Extn708(708)" [] (0.0) CalledNum= () CallingNum= () Internal=1 Time=69 AState=Ringing
165339mS CD: CALL: 0.1005.0 Deleted
165339mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
165340mS CMCallEvt: 0.1005.0 -1 RAS.0: StateChange: END=X CMCSRingBack->CMCSCompleted
165340mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Alerting,0,0,RAS
165340mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[706] Type=Default (100) Reason=CMDRdirect Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BChan: slot=21 chan=42
Cause=126, Transfer(IPO)
165340mS CMMap: a=21.42 b=0.0 T0
165341mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1005->0 )
165341mS CMCallEvt: 0.1005.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
165341mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
165341mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=126, Transfer(IPO)
165342mS CMCallEvt: END CALL:2 (f51dc5b8)
165343mS CMCallEvt: 0.1004.0 -1 BaseEP: DELETE CMEndpoint f51e5084 TOTAL NOW=4 CALL_LIST=1
165343mS CMCallEvt: 0.1005.0 -1 BaseEP: DELETE CMEndpoint f51dcaa4 TOTAL NOW=3 CALL_LIST=1
165343mS CD: CALL: 8.5.1 BState=Ringing Cut=3 Music=2.0 Aend="Line 8" (3.4) Bend="Extn708(708)" [Extn706(706)] (0.0) CalledNum=706 (Extn706) CallingNum= () Internal=0 Time=7521 AState=Connected
165344mS CMMap: a=3.4 b=0.0 H0
165344mS CMMap: a=3.4 b=0.0 R1
171080mS CMExtnRx: v=706, p1=0
CMConnect
Line: type=DigitalExtn 2 Call: lid=0 id=1007 in=0
171081mS CMCallEvt: 0.1007.0 1 Extn706.0: StateChange: END=T CMCSRinging->CMCSConnReq
171081mS CMCallEvt: 0.1006.0 1 TargetingEP: RequestEnd 0.1007.0 1 Extn706.0
171081mS CMTARGET: 8.5.1 1 Alog Trunk:8: CancelTimer CMTCCoverageTimeout
171081mS CMCallEvt: 0.1006.0 -1 BaseEP: DELETE CMEndpoint f51ef534 TOTAL NOW=2 CALL_LIST=1
171082mS CMExtnEvt: v=4 State, new=Connected old=Ringing,0,0,Extn706
171083mS CMCallEvt: 0.1007.0 1 Extn706.0: StateChange: END=B CMCSConnReq->CMCSConnected
171083mS CMTARGET: 8.5.1 1 Alog Trunk:8: ~CMTargetHandler f51e6870 ep f51f0eec
171084mS CMExtnTx: v=706, p1=0
CMConnectAck
Line: type=DigitalExtn 2 Call: lid=0 id=1007 in=0
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
Timed: 07/06/13 09:51
171085mS CMLineTx: v=8
CMFacility
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn706
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=706
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceStdPhone type=76 number=4 channel=0 rx_gain=32 tx_gain=32 ep_callid=1007 ipaddr=192.168.42.1 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
Cause=126, Transfer(IPO)
171085mS CMLineTx: v=8
CMFacility
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=0 chan=13
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn706
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=706
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceStdPhone type=76 number=4 channel=0 rx_gain=32 tx_gain=32 ep_callid=1007 ipaddr=192.168.42.1 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
Cause=126, Transfer(IPO)
171086mS CD: CALL: 8.5.1 BState=Connected Cut=3 Music=0.0 Aend="Line 8" (3.4) Bend="Extn708(708)" [Extn706(706)] (0.13) CalledNum=#AA:AA () CallingNum= () Internal=0 Time=13264 AState=Connected
171087mS CMMap: a=3.4 b=0.0 R0
171087mS CMMap: PCG::MapBChan pcp[85]b1r0 cp_b f5954690 other_cp_b 0 type CGTypeSimple
171087mS CMMap: PCG::MapBChan pcp[90]b1r0 cp_b f592c6d0 other_cp_b f5954690 type CGTypeSimple
171087mS CMMap: a=3.4 b=0.13 M12
171312mS RES: Fri 7/6/2013 09:51:39 FreeMem=60312044(2) CMMsg=4 (6) Buff=5200 943 1000 12463 5 Links=6337 BTree=0 CPU=1/2/23762/25253/26772/2
171312mS RES2: IP 500 V2 8.1(65) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=25 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
171312mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
174032mS CMExtnRx: v=706, p1=0
CMReleaseComp
Line: type=DigitalExtn 2 Call: lid=0 id=1007 in=0
174032mS CMCallEvt: 0.1007.0 1 Extn706.0: StateChange: END=B CMCSConnected->CMCSCompleted
174033mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Connected,0,0,Extn706
174033mS CMLOGGING: CALL:2013/06/0709:51,00:00:10,005,,I,#AA:AA,,,,,0,,""n/a,0
174033mS CD: CALL: 8.5.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 8" (3.4) Bend="Extn708(708)" [Extn706(706)] (0.13) CalledNum=#AA:AA () CallingNum= () Internal=0 Time=16211 AState=Connected
174034mS CD: CALL: 8.5.1 Deleted
174035mS CMCallEvt: 8.5.1 -1 Alog Trunk:8: StateChange: END=X CMCSConnected->CMCSDelete
174035mS CMCallEvt: 8.5.1 -1 BaseEP: DELETE CMEndpoint f51f0eec TOTAL NOW=1 CALL_LIST=0
174035mS CMExtnEvt: Extn706: CALL LOST (CMCauseNormal)
174035mS CMExtnEvt: Extn706: Extn(706) Calling Party Number() Type(CMNTypeUnknown)
174035mS CMExtnEvt: Extn706: CMExtnHandler::SetCurrent( id: 1007->0 )
174036mS CMCallEvt: 0.1007.0 -1 Extn706.-1: StateChange: END=X CMCSCompleted->CMCSDelete
174036mS CMCallEvt: END CALL:1 (f51f0a00)
174037mS CMCallEvt: 0.1007.0 -1 BaseEP: DELETE CMEndpoint f51da048 TOTAL NOW=0 CALL_LIST=0
174038mS CMMap: PCG::UnmapBChan pcp[85]b1r0 cp_b f5954690 other_cp_b f592c6d0
174038mS CMMap: a=0.13 b=3.4 M02
174039mS CMMap: PCG::UnmapBChan pcp[90]b1r0 cp_b f592c6d0 other_cp_b 0
176036mS CMExtnEvt: Extn706: Recover Timer reason=CMTRWrapUp
176036mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Extn706
176037mS CMExtnTx: v=706, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Extn706 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn706 Msgs=0]
Timed: 07/06/13 09:51


2. Вызов с сотового на CO8 => AA => перевод Extn708 => перевод Extn706
(в итоге: гудок => проигрывается сообщение => перевод Extn708)

282213mS CMLineRx: v=8
CMSetup
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=3 chan=4
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
282213mS CD: CALL: 8.5.1 BState=Idle Cut=1 Music=0.0 Aend="Line 8" (3.4) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=2 AState=Idle
282213mS CMCallEvt: 8.5.1 3 Alog Trunk:8: StateChange: END=A CMCSIdle->CMCSDialInitiated
282213mS CMTARGET: 8.5.1 3 Alog Trunk:8: LOOKUP CALL ROUTE: type=0 called_party= sub= calling= dir=in complete=1 ses=0
282214mS CMTARGET: 8.5.1 3 Alog Trunk:8: SET BESTMATCH: length 0 vs -1 match= dest=AA:AA
282214mS CMCallEvt: Priority hike: call 3 priority 0->3
282214mS CMTARGET: 8.5.1 3 Alog Trunk:8: LOOKUP ICR: DDI= CGPN= (Destination AA:AA ) => CDPN=AA:AA
282214mS CMTARGET: 8.5.1 3 Alog Trunk:8: ADD TARGET (N): number=AA:AA type=0 depth=1 nobar=1 setorig=1 ses=0
282214mS CMTARGET: 8.5.1 3 Alog Trunk:8: ADD VM TARGET
282215mS CMTARGET: **** 8.5.1 3 Alog Trunk:8: MakeVoicemailTarget pbx=<null> local=1 type=3
282215mS CMCallEvt: 0.1010.0 -1 BaseEP: NEW CMEndpoint f51da048 TOTAL NOW=3 CALL_LIST=1
282215mS CMTARGET: 0.1010.0 3 RAS.0: ADD PRIMARY
282215mS CMTARGET: 8.5.1 3 Alog Trunk:8: ADD VM TARGET: SUCCEEDED
282215mS CMTARGET: 8.5.1 3 Alog Trunk:8: INITIAL TARGETING SUCCEEDED
282216mS CMTARGET: 8.5.1 3 Alog Trunk:8: GetNoAnswerTimer:11
282216mS CMCallEvt: 8.5.1 3 Alog Trunk:8: StateChange: END=A CMCSDialInitiated->CMCSDialled
282216mS CMLineTx: v=8
CMProceeding
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
282217mS CMCallEvt: 0.1009.0 3 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
282217mS CMCallEvt: 0.1010.0 3 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
282217mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1010 )
282218mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#AA:AA] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=21 chan=42
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 3
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
Display [??>#AA:AA]
Timed: 07/06/13 09:53
Locale: rus
282218mS CMMap: a=21.42 b=1.254 T
282221mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1011 in=0
282221mS CMCallEvt: 0.1010.0 3 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
282221mS CMCallEvt: 0.1009.0 3 TargetingEP: RequestEnd 0.1010.0 3 RAS.0
282221mS CMTARGET: 8.5.1 3 Alog Trunk:8: CancelTimer CMTCNoAnswerTimeout
282221mS CMCallEvt: 0.1009.0 -1 BaseEP: DELETE CMEndpoint f51ef534 TOTAL NOW=2 CALL_LIST=1
282222mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
282222mS CMCallEvt: 8.5.1 3 Alog Trunk:8: StateChange: END=A CMCSDialled->CMCSOGConnReq
282222mS CMLineTx: v=8
CMConnect
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#AA:AA
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#AA:AA
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=1010 ipaddr=192.168.42.1 apps=0
282223mS CMLineRx: v=8
CMConnectAck
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=3 chan=4
282223mS CMCallEvt: 8.5.1 3 Alog Trunk:8: StateChange: END=A CMCSOGConnReq->CMCSConnected
282223mS CMTARGET: 8.5.1 3 Alog Trunk:8: ~CMTargetHandler f51e6870 ep f51f0eec
282224mS CMCallEvt: 0.1010.0 3 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
282224mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
282227mS CMMap: PCG::MapBChan pcp[85]b1r0 cp_b f5954690 other_cp_b 0 type CGTypeSimple
282228mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f597f82c other_cp_b f5954690 type CGTypeSimple
282228mS CMMap: a=3.4 b=21.42 M12
282228mS CMMap: a=3.4 b=21.42 CheckSpeechPathMonitor (pcp[32]b1r0) register
282228mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
282228mS PRN: Confer DSP: enabled DTMF detector 0
282259mS CD: CALL: 8.5.1 BState=Connected Cut=3 Music=0.0 Aend="Line 8" (3.4) Bend="#AA:AA(#AA:AA)" [VoiceMail] (21.42) CalledNum=#AA:AA () CallingNum= () Internal=0 Time=48 AState=Connected
282261mS CMLineRx: v=8
CMConnectAck
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
BChan: slot=3 chan=4
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
282318mS RES: Fri 7/6/2013 09:53:30 FreeMem=60288620(2) CMMsg=4 (6) Buff=5200 919 1000 12463 5 Links=6317 BTree=0 CPU=1/2/23762/25221/26772/2
282318mS RES2: IP 500 V2 8.1(65) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=25 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
282318mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
288318mS RES: Fri 7/6/2013 09:53:35 FreeMem=60288620(2) CMMsg=4 (6) Buff=5200 892 1000 12463 5 Links=6278 BTree=0 CPU=1/3/23384/24559/26772/2
288318mS RES2: IP 500 V2 8.1(65) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=52 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=25 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
288318mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
293318mS RES: Fri 7/6/2013 09:53:40 FreeMem=60288096(2) CMMsg=4 (6) Buff=5200 895 1000 12461 5 Links=6274 BTree=0 CPU=1/3/23384/24580/26772/2
293318mS RES2: IP 500 V2 8.1(65) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=25 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
293318mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
296161mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=RAS 1 Call: lid=0 id=1011 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RemoteHold.Invoke.CodePageUnknown
invokeId 14628
296161mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1010->0 )
296162mS CMExtnEvt: v=1011 State, new=Idle old=Connected,0,0,RAS
296162mS CMLineTx: v=8
CMFacility
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
HoldNotific.Invoke.CodePageUnknown
invokeId 14629
296163mS CMExtnRx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[708] Type=Default (100) Reason=CMDRdirect
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
296163mS CMCallEvt: 0.1011.0 -1 BaseEP: NEW CMEndpoint f51dcaa4 TOTAL NOW=3 CALL_LIST=1
296164mS CMCallEvt: CREATE CALL:4 (f51dc5b8)
296164mS CMCallEvt: 0.1012.0 -1 BaseEP: NEW CMEndpoint f51ef534 TOTAL NOW=4 CALL_LIST=1
296165mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1011 )
296165mS CMCallEvt: 0.1011.0 4 RAS.1: StateChange: END=A CMCSIdle->CMCSDialInitiated
296165mS CMExtnEvt: v=1011 State, new=Dialling old=Idle,0,0,RAS
296166mS CMTARGET: 0.1011.0 4 RAS.1: Setting Hard Timer 4000
296166mS CMTARGET: 0.1011.0 4 RAS.1: LOOKUP CALL ROUTE: type=100 called_party=708 sub= calling= dir=out complete=0 ses=0
296166mS CMTARGET: 0.1011.0 4 RAS.1: ADD TARGET (N): number=708 type=100 depth=1 nobar=1 setorig=1 ses=0
296166mS CMTARGET: 0.1011.0 4 RAS.1: SET USER: Extn708 orig=1
296166mS CMTARGET: 0.1011.0 4 RAS.1: ADD USER: Extn708 depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
296166mS CMTARGET: TryLoggedOutTargeting for user Extn708 Failed
296167mS CMTARGET: 0.1011.0 4 RAS.1: ADD TARGET (N): number=706 type=100 depth=2 nobar=1 setorig=0 ses=0
296167mS CMTARGET: 0.1011.0 4 RAS.1: SET USER: Extn706 orig=0
296167mS CMTARGET: 0.1011.0 4 RAS.1: ADD USER: Extn706 depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x4) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
296167mS CMCallEvt: 0.1013.0 -1 BaseEP: NEW CMEndpoint f51e5084 TOTAL NOW=5 CALL_LIST=2
296167mS CMCallEvt: 0.1013.0 -1 Extn706.-1: NEW CMExtnEndpoint f51e5084, Name=Extn706, Extn=706, Phys Extn=706
296168mS CMTARGET: 0.1013.0 4 Extn706.0: ADD PRIMARY
296168mS CMTARGET: 0.1011.0 4 RAS.1: CancelTimer CMTCDelayedProcessing
296169mS CMTARGET: 0.1011.0 4 RAS.1: INITIAL TARGETING SUCCEEDED
296169mS CMTARGET: 0.1011.0 4 RAS.1: GetNoAnswerTimer:11
296169mS CMCallEvt: 0.1011.0 4 RAS.1: StateChange: END=A CMCSDialInitiated->CMCSDialled
296169mS CMExtnEvt: v=1011 State, new=Proceeding old=Dialling,0,0,RAS
296169mS CMExtnTx: v=RAS, p1=0
CMProceeding
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
296170mS CMCallEvt: 0.1012.0 4 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
296171mS CMCallEvt: 0.1013.0 4 Extn706.0: StateChange: END=T CMCSIdle->CMCSOffering
296171mS CMExtnEvt: Extn706: CMExtnHandler::SetCurrent( id: 0->1013 )
296171mS CMExtnTx: v=706, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
Called[706] Type=Default (100) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=0 rx_gain=32 tx_gain=32 ep_callid=1011 ipaddr=192.168.42.1 apps=0
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Extn706
IE CMIEIcrPriorityDetail (239) Priority = 3
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Extn708
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:2) number=708
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
Display [??>Extn706]
Timed: 07/06/13 09:53
Locale: rus
296171mS CMExtnRx: v=706, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
296171mS CMCallEvt: 0.1013.0 4 Extn706.0: StateChange: END=T CMCSOffering->CMCSRinging
296172mS CMExtnEvt: v=4 State, new=Ringing old=Idle,0,0,Extn706
296172mS CMCallEvt: 0.1012.0 4 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
296172mS CMCallEvt: 0.1011.0 4 RAS.1: StateChange: END=A CMCSDialled->CMCSRingBack
296173mS CMExtnEvt: v=1011 State, new=Alerting old=Proceeding,0,0,RAS
296173mS CMExtnTx: v=RAS, p1=0
CMAlerting
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn706
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=706
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceStdPhone type=76 number=4 channel=0 rx_gain=32 tx_gain=32 ep_callid=1013 ipaddr=192.168.42.1 apps=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Extn708
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=708
296174mS CMMap: PCG::UnmapBChan pcp[85]b1r0 cp_b f5954690 other_cp_b f597f82c
296174mS CMMap: a=21.42 b=3.4 M02
296174mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f597f82c other_cp_b 0
296174mS CMMap: a=3.4 b=0.0 H1
296175mS CMMap: a=3.4 b=21.42 CheckSpeechPathMonitor (pcp[32]b1r0) unregister
296175mS PRN: Confer DSP: disabled DTMF detector 0
296175mS PRN: Confer DSP channel 0: freed DTMF detector 0, 24 free
296196mS CMExtnRx: v=RAS, p1=0
CMTransferComplete
Line: type=RAS 1 Call: lid=0 id=1011 in=0
296196mS CMCallEvt: CompleteTransferTo 'this' is consultation, 'tgep' is original
296196mS CMTARGET: TransferProposal: X_call: ID=4 Aend= -> 706
296196mS CMTARGET: TransferProposal: Y_call: ID=3 Aend= -> #AA:AA
296196mS CMTARGET: Surviving Call=3
296196mS CMTARGET: Goodbying Call=4
296196mS CMTARGET: AEND -> 708 BEND
296197mS CMCallEvt: 0.1011.0 4 RAS.1: CompleteTransfer to 0.1010.0 (0.1010.0 3 RAS.0) (Return @ 0ms -> null) ssdata 0
296197mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
296198mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#AA:AA] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BChan: slot=21 chan=42
Cause=126, Transfer(IPO)
296198mS CMCallEvt: 0.1010.0 -1 RAS.-1: StateChange: END=X CMCSConnected->CMCSDelete
296200mS CMTARGET: 0.1011.0 4 RAS.0: ChangeOriginator: To 8.5.1 3 Alog Trunk:8
296200mS CMExtnTx: v=706, p1=0
CMFacility
Line: type=DigitalExtn 2 Call: lid=0 id=1013 in=0
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:101 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=rus HW=15 VER=8 class=CMDeviceAlogTrunk type=0 number=8 channel=0 rx_gain=32 tx_gain=32 ep_callid=5 ipaddr=192.168.42.1 apps=0
Timed: 07/06/13 09:53
296201mS CMLineTx: v=8
CMFacility
Line: type=AnalogueLine 8 Call: lid=8 id=5 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RetrieveNotific.Invoke.CodePageUnknown
invokeId 14630
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn708
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:2) number=708
Cause=126, Transfer(IPO)
296201mS CD: CALL: 8.5.1 BState=Ringing Cut=3 Music=2.0 Aend="Line 8" (3.4) Bend="Extn708(708)" [Extn706(706)] (0.0) CalledNum=706 (Extn706) CallingNum= () Internal=0 Time=13990 AState=Connected
296201mS CMLOGGING: CALL:2013/06/0709:53,00:00:00,000,,O,706,708,,,,1,,""n/a,0
296202mS CD: CALL: 0.1011.0 BState=Idle Cut=3 Music=2.0 Aend="VoiceMail" (21.42) Bend="Extn708(708)" [] (0.0) CalledNum= () CallingNum= () Internal=1 Time=38 AState=Ringing
296202mS CD: CALL: 0.1011.0 Deleted
296202mS CMExtnEvt: RAS: CALL LOST (CMCauseTransfer)
296202mS CMCallEvt: 0.1011.0 -1 RAS.0: StateChange: END=X CMCSRingBack->CMCSCompleted
296202mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Alerting,0,0,RAS
296203mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[706] Type=Default (100) Reason=CMDRdirect Calling[] Type=Unknown Plan=ALOG Pres=NAInterworking (2)
BChan: slot=21 chan=42
Cause=126, Transfer(IPO)
296203mS CMMap: a=21.42 b=0.0 T0
296204mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1011->0 )
296204mS CMCallEvt: 0.1011.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
296204mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
296204mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=126, Transfer(IPO)
296204mS CMCallEvt: END CALL:4 (f51dc5b8)
296205mS CMCallEvt: 0.1010.0 -1 BaseEP: DELETE CMEndpoint f51da048 TOTAL NOW=4 CALL_LIST=1
296205mS CMCallEvt: 0.1011.0 -1 BaseEP: DELETE CMEndpoint f51dcaa4 TOTAL NOW=3 CALL_LIST=1
296206mS CD: CALL: 8.5.1 BState=Ringing Cut=3 Music=2.0 Aend="Line 8" (3.4) Bend="Extn708(708)" [Extn706(706)] (0.0) CalledNum=706 (Extn706) CallingNum= () Internal=0 Time=13995 AState=Connected
296207mS CMMap: a=3.4 b=0.0 H0
296207mS CMMap: a=3.4 b=0.0 R1
298318mS RES: Fri 7/6/2013 09:53:45 FreeMem=60294168(2) CMMsg=4 (6) Buff=5200 943 1000 12463 5 Links=6337 BTree=0 CPU=1/3/23384/25204/26772/2
298318mS RES2: IP 500 V2 8.1(65) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=25 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
298318mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)

********** SysMonitor v10.1 (65) [connected to 192.168.42.1 (00E0070745AF)] **********
303368mS PRN: Monitor Status IP 500 V2 8.1(65)
303369mS PRN: LAW=A PRI=0, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=1(TOT=4)
306169mS CMTARGET: 8.5.1 3 Alog Trunk:8: TimerExpired cause=CMTCCoverageTimeout
306169mS CMTARGET: 8.5.1 3 Alog Trunk:8: GetNoAnswerTimer:11
307169mS CMTARGET: 8.5.1 3 Alog Trunk:8: TimerExpired cause=CMTCNoAnswerTimeout
307169mS CMTARGET: 8.5.1 3 Alog Trunk:8: Retarget NOANSWER EXCEPTED=00000004 ValidTargets=4
307169mS CMTARGET: 8.5.1 3 Alog Trunk:8: Retarget on target_cfg_user=Extn708
307169mS CMTARGET: 8.5.1 3 Alog Trunk:8: ADD USER: Extn708 depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x4) excpt(0x4), allow_redir(1) remote=00000000 simult 0 (0)
307169mS CMTARGET: TryLoggedOutTargeting for user Extn708 Failed
307170mS CMTARGET: 8.5.1 3 Alog Trunk:8: SELECT: TRY VOICEMAIL orig_hg() orig_user(708)
307170mS CMTARGET: 8.5.1 3 Alog Trunk:8: VM targeting failed. Remaining on final target Extn708
307170mS CMTARGET: 8.5.1 3 Alog Trunk:8: GetNoAnswerTimer:11


Я сравнивал отличий в звонках особо не заметил(
может я в sysmonitor фильтр не правильно настроил

[efffgen]

поигрался с sysmonitor, вот еще трейсы(

[gnugk]

При звонке из города:
DTMFHit: digit: 3 detected: Inband
AutoAttIntroNode::DTMFHit: digit: 3

А ваша система ждет 0.