Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

  • Congratulations Mike Lewis on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

SIP Waiting for Line

Status
Not open for further replies.

vpmol

Vendor
Mar 11, 2008
245
US
Can anyone look at my monitor trace and see if you can figure out what I might be doing wrong? I have set up dozens of SIP trunks, this one is in Puerto Rico and the inbound works fine.
Just getting waiting for line on outbound. Many thanks if someone can solve this issue.

********** Reconnect Selected **********

********** contact lost with 10.0.0.10 at 14:31:03 18/1/2018 - reselect = 0 **********
******************************************************************

********** SysMonitor v9.1.6.0 build 153 **********

********** contact made with 10.0.0.10 at 14:31:04 18/1/2018 **********

********** System (10.0.0.10) has been up and running for 19hrs, 15mins and 32secs(69332681mS) **********

********** Warning: TEXT File Logging selected **********


********** Warning: TEXT Logging to C:\Documents and Settings\TEMP.USER-FCB97DFE54.003\Desktop\\TMP1.$$$ File: STARTED on 18/1/2018 14:31:04 **********
69332681mS PRN: Monitor Started IP=10.0.0.106 IP 500 V2 9.0.0.0 build 829 00E00708E4DC
(Supports Unicode, System Locale is default)
69332681mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=83)
14:21:16 69335617mS PRN: 207: Digit Pressed 9
14:21:16 69335618mS CMCallEvt: 0.1407.0 -1 BaseEP: NEW CMEndpoint f4e18484 TOTAL NOW=1 CALL_LIST=0
14:21:16 69335618mS CMCallEvt: 0.1407.0 -1 Extn207.-1: NEW CMExtnEndpoint f4e18484, Name=Extn207, Extn=207, Phys Extn=207
14:21:16 69335619mS CMCallEvt: CREATE CALL:84 (f4e295ac)
14:21:16 69335619mS CMCallEvt: 0.1408.0 -1 BaseEP: NEW CMEndpoint f573fe84 TOTAL NOW=2 CALL_LIST=0
14:21:16 69335621mS CMExtnEvt: Extn207: CMExtnHandler::SetCurrent( id: 0->1407 )
14:21:16 69335621mS CMExtnRx: v=207, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[207] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn207
14:21:16 69335621mS CMCallEvt: 0.1407.0 84 Extn207.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
14:21:16 69335621mS CMExtnEvt: v=7 State, new=Dialling old=Idle,0,0,Extn207
14:21:16 69335622mS CMTARGET: 0.1407.0 84 Extn207.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=207 dir=out complete=0 ses=0
14:21:16 69335622mS CMTARGET: 0.1407.0 84 Extn207.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
14:21:16 69335622mS CMCallEvt: 0.1407.0 84 Extn207.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
14:21:16 69335623mS CMExtnTx: v=207, p1=0
CMSetupAck
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
BChan: slot=20 chan=7
Timed: 18/01/18 14:21
14:21:16 69335623mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
14:21:16 69335623mS CMTARGET: 0.1407.0 84 Extn207.0: Setting Hard Timer 4000
14:21:16 69335624mS CMTARGET: 0.1407.0 84 Extn207.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=207 dir=out complete=0 ses=0
14:21:16 69335624mS CMTARGET: 0.1407.0 84 Extn207.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
14:21:16 69335624mS CMTARGET: 0.1407.0 84 Extn207.0: SYS SC: 9 2 sc=type=Dial code=9N, num=Ns7877552060 callinfop->sending_complete=0 secondary_dialtone=
14:21:16 69335624mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
14:21:16 69335624mS CMARS: MakeCallTarget - Called Number:
14:21:16 69335625mS CMCallEvt: 0.1409.0 -1 BaseEP: NEW CMEndpoint f5217be0 TOTAL NOW=3 CALL_LIST=1
14:21:16 69335625mS CMTARGET: 0.1407.0 84 Extn207.0: CancelTimer CMTCDelayedProcessing
14:21:16 69335625mS CMTARGET: 0.1407.0 84 Extn207.0: INITIAL TARGETING SUCCEEDED
14:21:16 69335626mS CMTARGET: 0.1407.0 84 Extn207.0: GetNoAnswerTimer:15
14:21:16 69335626mS CMCallEvt: 0.1408.0 84 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
14:21:16 69335627mS CMCallEvt: 0.1409.0 84 ARS for SIP: StateChange: END=T CMCSIdle->CMCSOffering
14:21:16 69335627mS CMCallEvt: 0.1408.0 84 TargetingEP: RequestEnd 0.1409.0 84 ARS for SIP
14:21:16 69335628mS CMTARGET: 0.1407.0 84 Extn207.0: CancelTimer CMTCNoAnswerTimeout
14:21:16 69335628mS CMCallEvt: 0.1409.0 84 ARS for SIP: StateChange: END=B CMCSOffering->CMCSOverlapRecv
14:21:16 69335629mS CMARS: FORM: SIP - Received Number:
14:21:16 69335629mS CMARS: CMARSTargetingPending
14:21:16 69335629mS CMCallEvt: 0.1408.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
14:21:16 69335629mS CMCallEvt: 0.1408.0 -1 BaseEP: DELETE CMEndpoint f573fe84 TOTAL NOW=2 CALL_LIST=1
14:21:16 69335629mS CD: CALL: 0.1407.0 BState=Idle Cut=2 Music=0.0 Aend="Extn207(207)" (20.7) Bend="" [] (0.0) CalledNum=9 () CallingNum=207 (Extn207) Internal=1 Time=11 AState=Dialling
14:21:16 69335682mS PRN: +++ START OF ALARM LOG DUMP +++
14:21:16 69335682mS PRN: ALARM: 29/11/2017 18:17:36 IP 500 V2 9.0.0.0 build 829 <BROWNOUT> CRIT RAISED addr=00000000 d=4 pc=f0005dac f0005da4 f00067f0 f0324d20 f0324cec 00000000
14:21:16 69335682mS PRN: +++ END OF ALARM LOG DUMP +++
14:21:17 69336682mS PRN: +++ START OF UDP MONITOR CLIENT DUMP +++
14:21:17 69336682mS PRN: CLIENT: IPAddress=10.0.0.106 UDPPort=1187 LastReceived=69332746(3936) LastSent=69336682(0) LastOldUpdate=69332681(4001) SentPackets=47 MissedPackets=0 OldSentPackets=0 OldMissedPackets=0
14:21:17 69336682mS PRN: ++++ END OF UDP MONITOR CLIENT DUMP ++++
14:21:18 69337106mS PRN: 207: Digit Pressed 7
14:21:18 69337107mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
14:21:18 69337107mS CMARS: FORM: SIP - Received Number: 7
14:21:18 69337107mS CMARS: CMARSTargetingPending
14:21:18 69337444mS PRN: 207: Digit Pressed 8
14:21:18 69337444mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
14:21:18 69337445mS CMARS: FORM: SIP - Received Number: 78
14:21:18 69337445mS CMARS: CMARSTargetingPending
14:21:18 69337794mS PRN: 207: Digit Pressed 7
14:21:18 69337794mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
14:21:18 69337794mS CMARS: FORM: SIP - Received Number: 787
14:21:18 69337795mS CMARS: CMARSTargetingPending
14:21:18 69337797mS RES: Thu 18/1/2018 14:21:18 FreeMem=54678652 54049128(1) CachedMem=629524 CMMsg=5(6) Buff=5192 1355 999 7463 5 Links=17420 BTree=14627 CPU=05.96% CPUStats=2/6/4806/15816/18936/0/0 MCR=0 MCW=0
14:21:18 69337797mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=58 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:21:18 69337797mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
14:21:18 69337882mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
14:21:18 69337882mS PRN: + loader: 1.33
14:21:18 69337882mS PRN: + cpu: id 12 board 5 pld 25 type c10 options ac02
14:21:18 69337882mS PRN: + cpu: sn 14WZ05200015
14:21:18 69337882mS PRN: + fpga: id 1 issue 0 build 827
14:21:18 69337882mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
14:21:18 69337882mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
14:21:18 69337882mS PRN: +------------------------------------------------------------
14:21:18 69337882mS PRN: + Slot 1: Base DIGSTA8 Board=0x13 PLD=0x06
14:21:18 69337882mS PRN: + Mezzanine NONE
14:21:18 69337882mS PRN: +------------------------------------------------------------
14:21:18 69337882mS PRN: + Slot 2: Base COMBO6210 Board=0x03 PLD=0x04
14:21:18 69337882mS PRN: + Mezzanine ATM4 V2 Board=0x01 PLD=0x04
14:21:18 69337882mS PRN: +------------------------------------------------------------
14:21:18 69337883mS PRN: + Slot 3: Base NONE
14:21:18 69337883mS PRN: + Mezzanine NONE
14:21:18 69337883mS PRN: +------------------------------------------------------------
14:21:18 69337883mS PRN: + Slot 4: Base NONE
14:21:18 69337883mS PRN: + Mezzanine NONE
14:21:18 69337883mS PRN: +------------------------------------------------------------
14:21:18 69337883mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
14:21:18 69337883mS PRN: +++++++++++++++++ LIST OF MEMORY CARDS ++++++++++++++++++
14:21:18 69337883mS PRN: +------------------------------------------------------------
14:21:18 69337883mS PRN: + System SD Card: MNTD
14:21:18 69337883mS PRN: + CID Register: 0x2842452020202020109b081c0b00e389
14:21:18 69337883mS PRN: + CSD Register: 0x400e00325b5900003bad7f800a4000c5
14:21:18 69337884mS PRN: + SCR Register: 0x0235800000000000
14:21:18 69337884mS PRN: + OCR Register: 0xc0ff8000
14:21:18 69337885mS PRN: + Status Register: 0x0000000003000000030390000805
14:21:18 69337885mS PRN: +------------------------------------------------------------
14:21:18 69337885mS PRN: + Optional SD Card: UMNT
14:21:18 69337885mS PRN: +------------------------------------------------------------
14:21:18 69337885mS PRN: ++++++++++++++ END OF LIST OF MEMORY CARDS +++++++++++++
14:21:18 69337885mS PRN: ++++++++++++++++++++ MEMORY CAPTURE +++++++++++++++++++++
14:21:18 69337885mS PRN: +------------------------------------------------------------
14:21:18 69337885mS PRN: + 0x00000015, 0x00000000, 0x00000000, 0x00000035, 0x00000000
14:21:18 69337885mS PRN: + 0x00000000, 0x00000000, 0x00000034, 0x00000003, 0x00000000
14:21:18 69337885mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000035, 0x00000000
14:21:18 69337885mS PRN: + 0x00000012, 0x00000000, 0x00000000, 0x00000000, 0x00000000
14:21:18 69337885mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
14:21:18 69337885mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
14:21:18 69337886mS PRN: + 0x00000000, 0x00000000, 0x00000002, 0x00000009, 0x00000000
14:21:18 69337886mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
14:21:18 69337886mS PRN: + 0x00000000, 0x73466707, 0xdada0982, 0xc04ebf71, 0x1d9a9ed7
14:21:18 69337886mS PRN: +------------------------------------------------------------
14:21:18 69337886mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
14:21:20 69339606mS PRN: 207: Digit Pressed 2
14:21:20 69339607mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
14:21:20 69339607mS CMARS: FORM: SIP - Received Number: 7872
14:21:20 69339607mS CMARS: CMARSTargetingPending
14:21:20 69339969mS PRN: 207: Digit Pressed 8
14:21:20 69339969mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
14:21:20 69339970mS CMARS: FORM: SIP - Received Number: 78728
14:21:20 69339970mS CMARS: CMARSTargetingPending
14:21:21 69340306mS PRN: 207: Digit Pressed 1
14:21:21 69340307mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
14:21:21 69340307mS CMARS: FORM: SIP - Received Number: 787281
14:21:21 69340307mS CMARS: CMARSTargetingPending
14:21:21 69340656mS PRN: 207: Digit Pressed 2
14:21:21 69340656mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
14:21:21 69340657mS CMARS: FORM: SIP - Received Number: 7872812
14:21:21 69340657mS CMARS: CMARSTargetingPending
14:21:22 69341106mS PRN: 207: Digit Pressed 0
14:21:22 69341106mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
14:21:22 69341107mS CMARS: FORM: SIP - Received Number: 78728120
14:21:22 69341107mS CMARS: CMARSTargetingPending
14:21:22 69341431mS PRN: 207: Digit Pressed 0
14:21:22 69341431mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
14:21:22 69341432mS CMARS: FORM: SIP - Received Number: 787281200
14:21:22 69341432mS CMARS: CMARSTargetingPending
14:21:21 69341831mS PRN: 207: Digit Pressed 0
14:21:21 69341831mS CMExtnRx: v=207, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
14:21:21 69341832mS CMARS: FORM: SIP - Received Number: 7872812000
14:21:21 69341832mS CMARS: CMARSTargetingPending
14:21:26 69346625mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 7872812000 Addr: f5217be0
14:21:26 69346625mS CMARS: FORM: SIP - Received Number: 7872812000
14:21:26 69346625mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: N"@173.243.80.173" - Called_Party: 7872812000@173.243.80.173 - Line Group Id: 10
14:21:26 69346625mS CMARS: FindActiveARSByGroupID GroupID=10 - Not Found
14:21:26 69346625mS CMLRQ: FindActiveLRQByGroupID GroupID=10 - Not Found
14:21:26 69346626mS CMMap: a=2.18 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 10
14:21:26 69346626mS Sip: License, Valid 1, Available 4, Consumed 0
14:21:26 69346627mS CMCallEvt: 0.1410.0 -1 BaseEP: NEW CMEndpoint f4ef189c TOTAL NOW=3 CALL_LIST=1
14:21:26 69346627mS Sip: SIPDialog f4eec50c created, size 1
14:21:26 69346631mS CMARS: FOUND LINE - Line Id: 17 - using line group id: 10 - Called Number: 7872812000@173.243.80.173 - Calling Number: 7877552060
14:21:26 69346631mS CMARS: SEND Setup TO LINE
14:21:26 69346631mS CMCallEvt: 17.1410.0 84 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
14:21:26 69346632mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=1410 in=0
Called[7872812000@173.243.80.173] Type=Default (100) Reason=CMDRdirect SndComp Calling[7877552060] Type=Default Plan=Unknown
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 14 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn207
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=207
IE CMIEDeviceDetail (231) 0a00000a0000057f LOCALE=enu HW=15 VER=9 class=CMDeviceStdPhone type=77 number=7 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1407 ipaddr=10.0.0.10 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Locale: enu
14:21:26 69346633mS Sip: 17.1410.0 84 SIPTrunk Endpoint(f4ef189c) received CMSetup
14:21:26 69346634mS Sip: 17.1410.0 84 SIPTrunk Endpoint(f4eec50c) SetLocalRTPAddress to 10.0.0.10:46750
14:21:26 69346636mS SIP Tx: UDP 10.0.0.10:5060 -> 173.243.80.173:5060
INVITE sip:7872812000@173.243.80.173 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.10:5060;rport;branch=z9hG4bK45a115877be779c2cd3ac9c3c7260bdf
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477422 INVITE
Contact: "Anonymous" <sip:7877552060@10.0.0.10:5060;transport=udp>
Authorization: Digest username="2061013",realm="Asterisk",nonce="5d7028b1",response="91ecdb8061735c8d7057f438ce4ca7e6",uri="sip:7872812000@173.243.80.173"
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Supported: timer,100rel
User-Agent: IP Office 9.0.0.0 build 829
P-Asserted-Identity: <sip:*@10.0.0.10:5060>
Content-Length: 292

v=0
o=UserA 2279015783 339487090 IN IP4 10.0.0.10
s=Session SDP
c=IN IP4 10.0.0.10
t=0 0
m=audio 46750 RTP/AVP 0 8 18 4 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
14:21:26 69346637mS CD: CALL: 0.1407.0 BState=Idle Cut=2 Music=0.0 Aend="Extn207(207)" (20.7) Bend="" [Line 17] (0.0) CalledNum=97872812000 () CallingNum=207 (Extn207) Internal=1 Time=11019 AState=Dialling
14:21:26 69346696mS SIP Rx: UDP 173.243.80.173:5060 -> 10.0.0.10:5060
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK45a115877be779c2cd3ac9c3c7260bdf;received=10.0.0.10;rport=5060
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>;tag=as217e3a7a
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477422 INVITE
Server: Asterisk
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Digest algorithm=MD5, realm="Asterisk", nonce="7f1f1962"
Content-Length: 0

14:21:26 69346700mS SIP Tx: UDP 10.0.0.10:5060 -> 173.243.80.173:5060
ACK sip:7872812000@173.243.80.173 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.10:5060;rport;branch=z9hG4bK45a115877be779c2cd3ac9c3c7260bdf
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>;tag=as217e3a7a
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477422 ACK
Max-Forwards: 70
Authorization: Digest username="2061013",realm="Asterisk",nonce="5d7028b1",response="172ede8911648c9a07359b6afa48ea4c",uri="sip:7872812000@173.243.80.173"
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
User-Agent: IP Office 9.0.0.0 build 829
Content-Length: 0

14:21:26 69346703mS SIP Tx: UDP 10.0.0.10:5060 -> 173.243.80.173:5060
INVITE sip:7872812000@173.243.80.173 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.10:5060;rport;branch=z9hG4bK86aec28309070df7447b4392eaa355cf
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477423 INVITE
P-Asserted-Identity: <sip:*@10.0.0.10:5060>
Contact: "Anonymous" <sip:7877552060@10.0.0.10:5060;transport=udp>
Max-Forwards: 70
Authorization: Digest username="2061013",realm="Asterisk",nonce="7f1f1962",response="d59b99ca2f813902e7e43770a5858af0",uri="sip:7872812000@173.243.80.173"
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer,100rel
User-Agent: IP Office 9.0.0.0 build 829
Content-Type: application/sdp
Content-Length: 292

v=0
o=UserA 2279015783 339487090 IN IP4 10.0.0.10
s=Session SDP
c=IN IP4 10.0.0.10
t=0 0
m=audio 46750 RTP/AVP 0 8 18 4 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
14:21:26 69346726mS SIP Rx: UDP 173.243.80.173:5060 -> 10.0.0.10:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK86aec28309070df7447b4392eaa355cf;received=10.0.0.10;rport=5060
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477423 INVITE
Server: Asterisk
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:7872812000@173.243.80.173:5060>
Content-Length: 0

14:21:26 69346729mS CMLineRx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=1410 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[7877552060] Type=Default Plan=Unknown
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=97872812000
IE CMIEDeviceDetail (231) 0a00000a00000582 LOCALE=enu HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=1410 ipaddr=10.0.0.10 apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=0
14:21:26 69346729mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
14:21:26 69346766mS SIP Rx: UDP 173.243.80.173:5060 -> 10.0.0.10:5060
SIP/2.0 500 Server internal failure
Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK86aec28309070df7447b4392eaa355cf;received=10.0.0.10;rport=5060
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>;tag=as64397f1c
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477423 INVITE
Server: Asterisk
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0

14:21:26 69346770mS SIP Tx: UDP 10.0.0.10:5060 -> 173.243.80.173:5060
ACK sip:7872812000@173.243.80.173 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.10:5060;rport;branch=z9hG4bK86aec28309070df7447b4392eaa355cf
From: "Anonymous" <sip:7877552060@173.243.80.173>;tag=1e899a75e3786f10
To: <sip:7872812000@173.243.80.173>;tag=as64397f1c
Call-ID: 26865fe050c8c400e3e87c460d7c5d54
CSeq: 1218477423 ACK
Max-Forwards: 70
Authorization: Digest username="2061013",realm="Asterisk",nonce="7f1f1962",response="a94ff3dad5f4c24c8f7423c56777ea78",uri="sip:7872812000@173.243.80.173"
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
User-Agent: IP Office 9.0.0.0 build 829
Content-Length: 0

14:21:26 69346771mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1410 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[7877552060] Type=Default Plan=Unknown
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=97872812000
IE CMIEDeviceDetail (231) 0a00000a00000582 LOCALE=enu HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=1410 ipaddr=10.0.0.10 apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=0
Cause=41, Temporary failure
14:21:26 69346771mS Sip: Receive Temporary Failure on SIPTrunkEndpoint
14:21:26 69346771mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
14:21:26 69346772mS CMLineTx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1410 in=0
Cause=16, Normal call clearing
14:21:26 69346772mS Sip: 17.1410.0 -1 SIPTrunk Endpoint(f4ef189c) received CMReleaseComp
14:21:26 69346773mS Sip: SIPDialog f4eec50c destroyed, size 0
14:21:26 69346773mS CMCallEvt: 17.1410.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSOffering->CMCSDelete
14:21:26 69346773mS CMARS: Target: Short_Code: N; - Line_Group_ID: 10 has been set to: CMARS_OUTOFSERVICE
14:21:26 69346773mS CMCallEvt: 0.1409.0 84 ARS for SIP: StateChange: END=B CMCSOverlapRecv->CMCSAccept
14:21:26 69346774mS CMCallEvt: 0.1407.0 84 Extn207.0: StateChange: END=A CMCSDialling->CMCSRingBack
14:21:26 69346774mS CMExtnEvt: v=7 State, new=Alerting old=Dialling,0,0,Extn207
14:21:26 69346774mS CMExtnTx: v=207, p1=0
CMProgress
Line: type=DigitalExtn 2 Call: lid=0 id=1407 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 18/01/18 14:21
14:21:26 69346775mS CMCallEvt: 17.1410.0 -1 BaseEP: DELETE CMEndpoint f4ef189c TOTAL NOW=2 CALL_LIST=1
14:21:26 69346777mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
14:21:26 69346777mS CMMap: a=21.11 b=1.255 T
14:21:26 69346778mS CMMap: PCG::MapBChan pcp[73]b1r0 cp_b f5642a28 other_cp_b f567b510 type CGTypeDTMF
14:21:26 69346778mS CMMap: a=21.11 b=20.7 M22
14:21:26 69346778mS CMMap: DTMF::playTones g T[133] ,EEEEE
14:21:26 69346778mS CMMap: Started playing tones, cp[73]b1r0 (from g null)
14:21:26 69346778mS CMMap: cp[73]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
14:21:26 69346781mS CD: CALL: 0.1407.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn207(207)" (20.7) Bend="" [] (0.0) CalledNum=97872812000 () CallingNum=207 (Extn207) Internal=1 Time=11163 AState=Ringing
14:21:27 69346805mS RES: Thu 18/1/2018 14:21:27 FreeMem=54662588 54049128(1) CachedMem=613460 CMMsg=5(6) Buff=5200 1355 999 7463 5 Links=17416 BTree=14626 CPU=08.48% CPUStats=12/17/4806/15554/18936/0/0 MCR=0 MCW=0
14:21:27 69346805mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=59 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:21:27 69346805mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
14:21:28 69347046mS Sip: SIPDialog f4eec50c created, size 1
14:21:28 69347048mS SIP Tx: UDP 10.0.0.10:5060 -> 173.243.80.173:5060
OPTIONS sip:173.243.80.173 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.10:5060;rport;branch=z9hG4bK711ff0be4789e7ad7cf471279c1dceeb
From: <sip:10.0.0.10>;tag=3dd6cab83b489af1
To: <sip:173.243.80.173>
Call-ID: 9d410718dccc0f7ac7dea77e845d542b
CSeq: 1587515574 OPTIONS
Contact: <sip:10.0.0.10:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
User-Agent: IP Office 9.0.0.0 build 829
Content-Length: 0

14:21:28 69347106mS SIP Rx: UDP 173.243.80.173:5060 -> 10.0.0.10:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.10:5060;rport;branch=z9hG4bK711ff0be4789e7ad7cf471279c1dceeb;received=10.0.0.10
From: <sip:10.0.0.10:5060>;tag=3dd6cab83b489af1
To: <sip:173.243.80.173>;tag=as089ec486
Call-ID: 9d410718dccc0f7ac7dea77e845d542b
CSeq: 1587515574 OPTIONS
Server: Asterisk
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:173.243.80.173:5060>
Accept: application/sdp
Content-Length: 0

14:21:28 69347773mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: SIP
14:21:29 69348599mS CMMap: PCG::UnmapBChan pcp[73]b1r0 cp_b f5642a28 other_cp_b f567b510
14:21:29 69348599mS CMMap: a=21.11 b=20.7 M02
14:21:29 69348600mS CMMap: a=21.11 b=0.0 T0
14:21:29 69348600mS CMMap: a=21.11 b=0.0 DTMF::~DTMF freed CMRTUnknown resource busy 1, total 45
14:21:29 69348601mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
14:21:29 69348601mS CMMap: a=21.11 b=1.255 T
14:21:29 69348601mS CMMap: PCG::MapBChan pcp[73]b1r0 cp_b f5642a28 other_cp_b f567b510 type CGTypeDTMF
14:21:29 69348601mS CMMap: a=21.11 b=20.7 M22
14:21:29 69348601mS CMMap: DTMF::playTones g T[134] ,EEEEE
14:21:29 69348602mS CMMap: Started playing tones, cp[73]b1r0 (from g null)
14:21:29 69348602mS CMMap: cp[73]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE

********** SysMonitor v9.1.6.0 build 153 [connected to 10.0.0.10 (00E00708E4DC)] **********
14:21:31 69350261mS PRN: Monitor Status IP 500 V2 9.0.0.0 build 829
14:21:31 69350261mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=1(TOT=84)
14:21:31 69350423mS CMMap: PCG::UnmapBChan pcp[73]b1r0 cp_b f5642a28 other_cp_b f567b510
14:21:31 69350423mS CMMap: a=21.11 b=20.7 M02
14:21:31 69350423mS CMMap: a=21.11 b=0.0 T0
14:21:31 69350424mS CMMap: a=21.11 b=0.0 DTMF::~DTMF freed CMRTUnknown resource busy 1, total 45
14:21:32 69351806mS RES: Thu 18/1/2018 14:21:32 FreeMem=54670084 54049128(1) CachedMem=620956 CMMsg=5(6) Buff=5200 1356 1000 7463 5 Links=17422 BTree=14626 CPU=04.53% CPUStats=1/4/4806/16047/18936/0/0 MCR=0 MCW=0
14:21:32 69351806mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:21:32 69351806mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)

********** Warning: Logging to Screen Stopped **********
 
I do not see a Tx: Invite.
So looks like a outgoing line ID is wrong.

Did you enable all SIP tick boxes in monitor?
 
Hey Okkie26

I see :
14:21:26 69346696mS SIP Rx: UDP 173.243.80.173:5060 -> 10.0.0.10:5060
SIP/2.0 401 Unauthorized

14:21:26 69346766mS SIP Rx: UDP 173.243.80.173:5060 -> 10.0.0.10:5060
SIP/2.0 500 Server internal failure

Which means there are problems with the authentication AND another problem on the SIP Server side>

Server: Asterisk is not what i would call a professional SIP Provider engine.....

Try to let them solve their problem first, they tend to push the problem to you but act as a real dumb fool and call them every ten minutes, eventually they will look on their own side.

 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top