Hi All, I have been struggling with this for a few weeks now, I have read all I can find on the 404 error but everything I can find seems related to incoming from providers not other extensions
Note (all ip addresses have been changed for security)
Below is the cli debug from the extension in question, it is extension 103, the call was placed from ex 101.
Calls from ex 101 to other extensions or external work fine
Nothing can place a call to 103 internal or external
The setup is freepbx is on a static public IP
I have opened and forwarded all recomended ports I can find.
Both extensions are behind dls nat routers, ex 101 is also a 7940 that is working fine so I believe the basic config is working ok
The 404 seems to be from the phone (cisco 7940), only other thing I can see in the log is a congestion problem?? But not sure how to interpret them really
This is the line info from the 7940 at ex103
SIP Configuration Generic File
Line 1 appearance
line1_name: "103"
line1_shortname : “Resource 103”
Line 1 Registration Authentication
line1_authname: “103”
Line 1 Registration Password
line1_password: "xxxxxx"
line1_contact : “103”
the phone appears to be registered ok
Name/username Host Dyn Nat ACL Port Status
103/103 213.121.131.184 D N A 5070 OK (309 ms)
110/110 81.131.84.235 D N A 5060 OK (19 ms)
101/101 81.153.185.96 D N A 5070 OK (319 ms)
Any pointers or guidance or help would be greatly appreciated.
let me know any othe info that may help
I believe the call is making it to the phone but the phone is rejecting it ??
Thanks Paul
-- Executing [103@from-internal:1] e[1;36;40mMacroe[0;37;40m("e[1;35;40mSIP/101-097fea60e[0;37;40m", "e[1;35;40mexten-vm|novm|103e[0;37;40m") in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:1] e[1;36;40mMacroe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40muser-calleride[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:1] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mAMPUSER=101e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:2] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?reporte[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:3] e[1;36;40mExecIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1|Set|REALCALLERIDNUM=101e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:4] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mAMPUSER=101e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:5] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mAMPUSERCIDNAME=Julia Resourcee[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:6] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?reporte[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:7] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mAMPUSERCID=101e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:8] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mCALLERID(all)=“Julia Resource” <101>e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:9] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mREALCALLERIDNUM=101e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:10] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?continuee[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:11] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m__TTL=64e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:12] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?continuee[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Goto (macro-user-callerid,s,19)
e[Khost81-138-84-236*CLI>
– Executing [s@macro-user-callerid:19] e[1;36;40mNoOpe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mUsing CallerID “Julia Resource” <101>e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:2] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mRingGroupMethod=nonee[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:3] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mVMBOX=novme[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:4] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mEXTTOCALL=103e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:5] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mCFUEXT=e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:6] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mCFBEXT=e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:7] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mRT=”“e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:8] e[1;36;40mMacroe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mrecord-enable|103|INe[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-record-enable:1] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?checke[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Goto (macro-record-enable,s,4)
e[Khost81-138-84-236*CLI>
– Executing [s@macro-record-enable:4] e[1;36;40mAGIe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mrecordingcheck|20090516-161512|1242486912.72e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
e[Khost81-138-84-236*CLI>
recordingcheck|20090516-161512|1242486912.72: Inbound recording not enabled
e[Khost81-138-84-236*CLI>
– AGI Script recordingcheck completed, returning 0
e[Khost81-138-84-236*CLI>
– Executing [s@macro-record-enable:5] e[1;36;40mMacroExite[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40me[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-exten-vm:9] e[1;36;40mMacroe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mdial||tr|103e[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Executing [s@macro-dial:1] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?diale[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Goto (macro-dial,s,3)
e[Khost81-138-84-236*CLI>
– Executing [s@macro-dial:3] e[1;36;40mAGIe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mdialparties.agie[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
e[Khost81-138-84-236*CLI>
dialparties.agi: Starting New Dialparties.agi
e[Khost81-138-84-236*CLI>
== Parsing ‘/etc/asterisk/manager.conf’: Found
e[Khost81-138-84-236*CLI>
== Parsing ‘/etc/asterisk/manager_additional.conf’: Found
e[Khost81-138-84-236*CLI>
== Parsing ‘/etc/asterisk/manager_custom.conf’: Found
e[Khost81-138-84-236*CLI>
== Manager ‘admin’ logged on from 127.0.0.1
e[Khost81-138-84-236*CLI>
dialparties.agi: Caller ID name is ‘Julia Resource’ number is ‘101’
e[Khost81-138-84-236*CLI>
dialparties.agi: USE_CONFIRMATION: ‘FALSE’
e[Khost81-138-84-236*CLI>
dialparties.agi: RINGGROUP_INDEX: ‘’
e[Khost81-138-84-236*CLI>
dialparties.agi: Methodology of ring is ‘none’
e[Khost81-138-84-236*CLI>
– dialparties.agi: Added extension 103 to extension map
e[Khost81-138-84-236*CLI>
– dialparties.agi: Extension 103 cf is disabled
e[Khost81-138-84-236*CLI>
– dialparties.agi: Extension 103 do not disturb is disabled
e[Khost81-138-84-236*CLI>
> dialparties.agi: extnum 103 has: cw: 1; hascfb: 0 [] hascfu: 0 []
e[Khost81-138-84-236*CLI>
> dialparties.agi: ExtensionState: 0
e[Khost81-138-84-236*CLI>
– dialparties.agi: dbset CALLTRACE/103 to 101
e[Khost81-138-84-236*CLI>
– dialparties.agi: Filtered ARG3: 103
e[Khost81-138-84-236*CLI>
== Manager ‘admin’ logged off from 127.0.0.1
e[Khost81-138-84-236*CLI>
– AGI Script dialparties.agi completed, returning 0
e[Khost81-138-84-236*CLI>
– Executing [s@macro-dial:7] e[1;36;40mDiale[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mSIP/103||tre[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
Audio is at 81.138.84.236 port 18452
e[Khost81-138-84-236*CLI>
Adding codec 0x4 (ulaw) to SDP
e[Khost81-138-84-236*CLI>
Adding codec 0x8 (alaw) to SDP
e[Khost81-138-84-236*CLI>
Adding non-codec 0x1 (telephone-event) to SDP
e[Khost81-138-84-236*CLI>
Reliably Transmitting (NAT) to 213.121.131.184:5070:
INVITE sip:[email protected]:5070;transport=udp SIP/2.0
Via: SIP/2.0/UDP 81.138.84.236:5060;branch=z9hG4bK1bdff7dd;rport
From: “Julia Resource” sip:[email protected];tag=as7529094e
To: sip:[email protected]:5070;transport=udp
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 16 May 2009 15:15:12 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 264
v=0
o=root 2776 2776 IN IP4 81.138.84.236
s=session
c=IN IP4 81.138.84.236
t=0 0
m=audio 18452 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
e[Khost81-138-84-236*CLI>
– Called 103
e[Khost81-138-84-236*CLI>
<— SIP read from 213.121.131.184:5070 —>
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 81.138.84.236:5060;branch=z9hG4bK1bdff7dd;rport
From: “Julia Resource” sip:[email protected];tag=as7529094e
To: sip:[email protected]:5070;transport=udp
Call-ID: [email protected]
Date: Sat, 16 May 2009 15:17:03 GMT
CSeq: 102 INVITE
Content-Length: 0
<------------->
— (8 headers 0 lines) —
Transmitting (NAT) to 213.121.131.184:5070:
ACK sip:[email protected]:5070;transport=udp SIP/2.0
Via: SIP/2.0/UDP 81.138.84.236:5060;branch=z9hG4bK1bdff7dd;rport
From: “Julia Resource” sip:[email protected];tag=as7529094e
To: sip:[email protected]:5070;transport=udp
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0
e[Khost81-138-84-236*CLI>
– SIP/103-097fd358 is circuit-busy
== Everyone is busy/congested at this time (1:0/1/0)
– Executing [s@macro-dial:8] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mDIALSTATUS=CONGESTIONe[0;37;40m”) in new stack
– Executing [s@macro-dial:9] e[1;36;40mGosubIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?CONGESTION|1e[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:10] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?exit|returne[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:11] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mSV_DIALSTATUS=CONGESTIONe[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:12] e[1;36;40mGosubIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?docfu|1e[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:13] e[1;36;40mGosubIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?docfb|1e[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:14] e[1;36;40mSete[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mDIALSTATUS=CONGESTIONe[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:15] e[1;36;40mNoOpe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mVoicemail is novme[0;37;40m”) in new stack
– Executing [s@macro-exten-vm:16] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?s-CONGESTION|1e[0;37;40m”) in new stack
– Goto (macro-exten-vm,s-CONGESTION,1)
– Executing [s-CONGESTION@macro-exten-vm:1] e[1;36;40mNoOpe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mIVR_RETVM: IVR_CONTEXT: e[0;37;40m”) in new stack
– Executing [s-CONGESTION@macro-exten-vm:2] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m0?exit|1e[0;37;40m”) in new stack
– Executing [s-CONGESTION@macro-exten-vm:3] e[1;36;40mPlayTonese[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mcongestione[0;37;40m”) in new stack
– Executing [s-CONGESTION@macro-exten-vm:4] e[1;36;40mCongestione[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m10e[0;37;40m”) in new stack
== Spawn extension (macro-exten-vm, s-CONGESTION, 4) exited non-zero on ‘SIP/101-097fea60’ in macro ‘exten-vm’
== Spawn extension (from-internal, 103, 1) exited non-zero on ‘SIP/101-097fea60’
– Executing [h@from-internal:1] e[1;36;40mMacroe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mhangupcalle[0;37;40m”) in new stack
– Executing [s@macro-hangupcall:1] e[1;36;40mResetCDRe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40mvwe[0;37;40m”) in new stack
e[Khost81-138-84-236*CLI>
Really destroying SIP dialog ‘[email protected]’ Method: INVITE
– Executing [s@macro-hangupcall:2] e[1;36;40mNoCDRe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40me[0;37;40m”) in new stack
– Executing [s@macro-hangupcall:3] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?skiprge[0;37;40m”) in new stack
– Goto (macro-hangupcall,s,6)
– Executing [s@macro-hangupcall:6] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?skipblkvme[0;37;40m”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] e[1;36;40mGotoIfe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40m1?theende[0;37;40m”) in new stack
– Goto (macro-hangupcall,s,11)
– Executing [s@macro-hangupcall:11] e[1;36;40mHangupe[0;37;40m(“e[1;35;40mSIP/101-097fea60e[0;37;40m”, “e[1;35;40me[0;37;40m”) in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/101-097fea60’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/101-097fea60’