PLEASE HELP!- Asterisk hungs up after 2 seconds

Added by wdeveloper goautodial over 8 years ago

Hello,

I have a problem with outgoing calls, calls hungs up 2 seconds after the person answers.

I've seen lot of people having this issue. HOWEVER, none of Their solutions Worked out for me.

Thank you

Replies (4)

RE: PLEASE HELP!- Asterisk hungs up after 2 seconds - Added by Levy Ryan Nolasco over 8 years ago

Hi,

Please post your Carrier entry, Dial plan and asterisk CLI when you encounter the issue.

RE: PLEASE HELP!- Asterisk hungs up after 2 seconds - Added by wdeveloper goautodial over 8 years ago

Hi,

Account Entry:
[0033972XXXXXX]
disallow=all
allow=alaw
type=friend
dtmfmode=rfc2833
context=trunkinbound
nat=yes
qualify=yes
qualifyfreq = 30
host=sip3.ovh.fr
fromuser=0033972XXXXXX
fromdomain=sip3.ovh.fr

Dialplan Entry
; VICIDIAL Carrier: 0033972XXXXXX - 0033972XXXXXX
; 0033972XXXXXX
exten => _00XXXXXXXXXXX,1,AGI
exten => _00XXXXXXXXXXX,2,Dial(${ovh_1}/${EXTEN},,tTo)
exten => _00XXXXXXXXXXX,3,Hangup

RE: PLEASE HELP!- Asterisk hungs up after 2 seconds - Added by wdeveloper goautodial over 8 years ago

this is my cli output after activate debuging, thanks

DEBUG¹⁵⁰⁰⁰ app_queue.c: Device 'SIP/0033972XXXXXX' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
DEBUG¹⁵⁰⁰⁰ app_queue.c: Device 'Local/003397044XXXX@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
DEBUG¹⁵⁰⁰⁰ app_queue.c: Device 'Local/003397044XXXX@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
DEBUG¹⁵⁴⁰⁴ pbx.c: Launching 'Playback'
VERBOSE¹⁵⁴⁰⁴ pbx.c: -- Executing [8368@default:1] Playback("Local/003397044XXXX@default-00000000;1", "sip-silence") in new stack
DEBUG¹⁵⁴⁰⁴ channel.c: Set channel Local/003397044XXXX@default-00000000;1 to write format gsm
DEBUG¹⁵³⁸⁸ res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw
DEBUG¹⁵³⁸⁸ res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160
DEBUG¹⁵³⁸⁸ res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x1d52f7d8'
DEBUG¹⁵⁴⁰⁴ channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
VERBOSE¹⁵⁴⁰⁴ file.c: -- <Local/003397044XXXX@default-00000000;1> Playing 'sip-silence.gsm' (language 'en')
DEBUG¹⁵³⁸⁷ manager.c: Running action 'Logoff'
VERBOSE¹⁵³⁸⁷ manager.c: == Manager 'sendcron' logged off from 127.0.0.1
DEBUG¹⁵⁴⁰⁴ channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
DEBUG¹⁵⁴⁰⁴ channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
DEBUG¹⁵⁴⁰⁴ channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
DEBUG¹⁵⁴⁰⁴ channel.c: Set channel Local/003397044XXXX@default-00000000;1 to write format slin
DEBUG¹⁵⁴⁰⁴ pbx.c: Launching 'AGI'
VERBOSE¹⁵⁴⁰⁴ pbx.c: -- Executing [8368@default:2] AGI in new stack
DEBUG¹⁵⁴⁰⁴ res_agi.c: Wow, connected!
VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=TESTCAMP))
VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- <Local/003397044XXXX@default-00000000;1>AGI Script agi::4577/call_log completed, returning 0
DEBUG¹⁵⁴⁰⁴ pbx.c: Launching 'AGI'
VERBOSE¹⁵⁴⁰⁴ pbx.c: -- Executing [8368@default:3] AGI in new stack
VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
DEBUG¹⁵³⁸⁸ channel.c: Planning to masquerade channel SIP/0033972XXXXXX-00000000 into the structure of Local/003397044XXXX@default-00000000;1
DEBUG¹⁵³⁸⁸ channel.c: Done planning to masquerade channel SIP/0033972XXXXXX-00000000 into the structure of Local/003397044XXXX@default-00000000;1
DEBUG¹⁵³⁸⁸ chan_local.c: Masquerading Local/003397044XXXX@default-00000000;1 <- SIP/0033972XXXXXX-00000000
DEBUG¹⁵⁴⁰⁴ chan_local.c: Blocked indication -1
DEBUG¹⁵⁴⁰⁴ channel.c: Actually Masquerading SIP/0033972XXXXXX-00000000(6) into the structure of Local/003397044XXXX@default-00000000;1(6)

DEBUG¹⁵⁴⁰⁴ channel.c: Set channel SIP/0033972XXXXXX-00000000 to write format slin
DEBUG¹⁵⁴⁰⁴ channel.c: Set channel SIP/0033972XXXXXX-00000000 to read format slin
DEBUG¹⁵⁴⁰⁴ channel.c: Putting channel SIP/0033972XXXXXX-00000000 in slin/slin formats
DEBUG¹⁵⁴⁰⁴ chan_sip.c: SIP Fixup: New owner for dialogue 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060: SIP/0033972XXXXXX-00000000 (Old parent: Local/003397044XXXX@default-00000000;1<ZOMBIE>)
DEBUG¹⁵⁴⁰⁴ channel.c: Done Masquerading SIP/0033972XXXXXX-00000000 (6)
DEBUG¹⁵⁴⁰⁴ res_rtp_asterisk.c: Changing ssrc from 609914259 to 321984035 due to a source change
DEBUG¹⁵³⁸⁸ chan_local.c: Not posting to 'Local/003397044XXXX@default-00000000;2' queue since already masqueraded out
DEBUG¹⁵³⁸⁸ channel.c: Bridge stops because we're zombie or need a soft hangup: c0=Local/003397044XXXX@default-00000000;2, c1=Local/003397044XXXX@default-00000000;1<ZOMBIE>, flags: No,Yes,Yes,Yes
DEBUG¹⁵³⁸⁸ channel.c: Bridge stops bridging channels Local/003397044XXXX@default-00000000;2 and Local/003397044XXXX@default-00000000;1<ZOMBIE>
DEBUG¹⁵³⁸⁸ channel.c: Soft-Hanging up channel 'Local/003397044XXXX@default-00000000;2'
DEBUG¹⁵³⁸⁸ pbx.c: Result of 'HANGUPCAUSE' is '16'
DEBUG¹⁵³⁸⁸ pbx.c: Result of 'DIALSTATUS' is 'ANSWER'
DEBUG¹⁵³⁸⁸ pbx.c: Result of 'DIALEDTIME' is '6'
DEBUG¹⁵³⁸⁸ pbx.c: Result of 'ANSWEREDTIME' is '1'
DEBUG¹⁵³⁸⁸ pbx.c: Launching 'AGI'
VERBOSE¹⁵³⁸⁸ pbx.c: -- Executing [h@default:1] AGI in new stack
DEBUG¹⁵³⁸⁸ res_agi.c: Hungup channel detected, running agi in dead mode.
DEBUG¹⁵³⁸⁸ res_agi.c: Wow, connected!
DEBUG¹⁵¹²⁰ manager.c: Examining event:
Event: Unlink
Privilege: call,all
Channel1: Local/003397044XXXX@default-00000000;2
Channel2: Local/003397044XXXX@default-00000000;1<ZOMBIE>
Uniqueid1: 1446827253.3
Uniqueid2: 1446827253.4
CallerID1: 0000000000
CallerID2: 0000000000

VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- <SIP/0033972XXXXXX-00000000>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
DEBUG¹⁵⁴⁰⁴ pbx.c: Launching 'AGI'
VERBOSE¹⁵⁴⁰⁴ pbx.c: -- Executing [8368@default:4] AGI in new stack
VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
DEBUG¹⁴⁹⁴¹ chan_iax2.c: JB STATS:IAX2/4002-8270 ping=144 ljitterms=59 ljbdelayms=120 ltotlost=5 lrecentlosspct=0 ldropped=3 looo=0 lrecvd=3085 rjitterms=9 rjbdelayms=82 rtotlost=27 rrecentlosspct=0 rdropped=3 rooo=0 rrecvd=3052
DEBUG¹⁵¹¹⁰ manager.c: Running action 'Command'
VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- <SIP/0033972XXXXXX-00000000>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
DEBUG¹⁵⁴⁰⁴ pbx.c: Launching 'Hangup'
VERBOSE¹⁵⁴⁰⁴ pbx.c: -- Executing [8368@default:5] Hangup("SIP/0033972XXXXXX-00000000", "") in new stack
DEBUG¹⁵⁴⁰⁴ pbx.c: Spawn extension (default,8368,5) exited non-zero on 'SIP/0033972XXXXXX-00000000'
VERBOSE¹⁵⁴⁰⁴ pbx.c: == Spawn extension (default, 8368, 5) exited non-zero on 'SIP/0033972XXXXXX-00000000'
DEBUG¹⁵⁴⁰⁴ channel.c: Soft-Hanging up channel 'SIP/0033972XXXXXX-00000000'
DEBUG¹⁵⁴⁰⁴ pbx.c: Result of 'HANGUPCAUSE' is '16'
DEBUG¹⁵⁴⁰⁴ pbx.c: Result of 'DIALSTATUS' is NULL
DEBUG¹⁵⁴⁰⁴ pbx.c: Result of 'DIALEDTIME' is NULL
DEBUG¹⁵⁴⁰⁴ pbx.c: Result of 'ANSWEREDTIME' is NULL
DEBUG¹⁵⁴⁰⁴ pbx.c: Launching 'AGI'
VERBOSE¹⁵⁴⁰⁴ pbx.c: -- Executing [h@default:1] AGI in new stack
DEBUG¹⁵⁴⁰⁴ res_agi.c: Hungup channel detected, running agi in dead mode.
DEBUG¹⁵⁴⁰⁴ res_agi.c: Wow, connected!
VERBOSE¹⁵⁴⁰⁴ res_agi.c: -- <SIP/0033972XXXXXX-00000000>AGI Script agi::4577/call_log--HVcauses ... ---------- completed, returning 0
DEBUG¹⁵⁴⁰⁴ channel.c: Hanging up channel 'SIP/0033972XXXXXX-00000000'
DEBUG¹⁵⁴⁰⁴ chan_sip.c: Hangup call SIP/0033972XXXXXX-00000000, SIP callid 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060
DEBUG¹⁵⁴⁰⁴ res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x1d52f7d8'
VERBOSE¹⁵⁴⁰⁴ chan_sip.c: Scheduling destruction of SIP dialog '7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060' in 32000 ms (Method: INVITE)
VERBOSE¹⁵⁴⁰⁴ chan_sip.c: set_destination: Parsing <sip:XX.XX:5060;transport=udp;lr> for address/port to send to
DEBUG¹⁵⁴⁰⁴ netsock2.c: Splitting 'XX.XX:5060' into...
DEBUG¹⁵⁴⁰⁴ netsock2.c: ...host '91.121.XX.XX' and port '5060'.
VERBOSE¹⁵⁴⁰⁴ chan_sip.c: set_destination: set destination to XX.XX:5060
VERBOSE¹⁵⁴⁰⁴ chan_sip.c: Reliably Transmitting (NAT) to XX.XX:5060:
BYE sip:10.7.1.60:5060 SIP/2.0
Via: SIP/2.0/UDP IP:5060;branch=z9hG4bK2f443bce;rport
Route: <sip:XX.XX:5060;transport=udp;lr>
Max-Forwards: 70
From: "V1061127320000047779" <sip:0033972XXXXXX@_IP_>;tag=as2778b1fb
To: <sip:003397044XXXX@sip.ovh.fr>;tag=00-08092-6b594a0f-39fedb527
Call-ID: 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060
CSeq: 104 BYE
User-Agent: Asterisk PBX 1.8.23.0-1_centos5.go RPM by
Proxy-Authorization: Digest username="0033972XXXXXX", realm="sip.ovh.fr", algorithm=MD5, uri="sip:10.7.1.60:5060", nonce="6b5949554e9b261e26ae9a6448c13d0f", response="be1f7239d93e76b7a81f0ee30fd93044", opaque="6b58d3cc4aa6c67"
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

RE: PLEASE HELP!- Asterisk hungs up after 2 seconds - Added by wdeveloper goautodial over 8 years ago

DEBUG¹⁵⁴⁰⁴ chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #22
DEBUG¹⁵⁴⁰⁴ chan_sip.c: Trying to put 'BYE sip:10.' onto UDP socket destined for XX.XX:5060
DEBUG¹⁵¹²⁰ manager.c: Examining event:
Event: Hangup
Privilege: call,all
Channel: SIP/0033972XXXXXX-00000000
Uniqueid: 1446827253.2
CallerIDNum: 0000000000
CallerIDName: V1061127320000047779
ConnectedLineNum: 0000000000
ConnectedLineName: V1061127320000047779
Cause: 16
Cause-txt: Normal Clearing

DEBUG¹⁴⁹²⁵ devicestate.c: No provider found, checking channel drivers for SIP - 0033972XXXXXX
DEBUG¹⁴⁹²⁵ chan_sip.c: Checking device state for peer 0033972XXXXXX
DEBUG¹⁴⁹²⁵ devicestate.c: Changing state for SIP/0033972XXXXXX - state 1 (Not in use)
DEBUG¹⁴⁹²⁵ devicestate.c: device 'SIP/0033972XXXXXX' state '1'
DEBUG¹⁵⁰⁰⁰ app_queue.c: Device 'SIP/0033972XXXXXX' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
VERBOSE¹⁴⁹³³ chan_sip.c:
<--- SIP read from UDP:XX.XX:5060 --->
SIP/2.0 200 OK
Call-ID: 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060
CSeq: 104 BYE
From: "V1061127320000047779" <sip:0033972XXXXXX@_IP_>;tag=as2778b1fb
Record-Route: <sip:XX.XX:5060;transport=udp;lr>
To: <sip:003397044XXXX@sip.ovh.fr>;tag=00-08092-6b594a0f-39fedb527
Via: SIP/2.0/UDP IP:5060;received=_IP_;rport=5060;branch=z9hG4bK2f443bce
Server: Cirpack/v4.56 (gw_sip)
Content-Length: 0

<------------->
DEBUG¹⁴⁹³³ chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
DEBUG¹⁴⁹³³ chan_sip.c: Header 1 [ 59]: Call-ID: 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060
DEBUG¹⁴⁹³³ chan_sip.c: Header 2 [ 13]: CSeq: 104 BYE
DEBUG¹⁴⁹³³ chan_sip.c: Header 3 [ 76]: From: "V1061127320000047779" <sip:0033972XXXXXX@_IP_>;tag=as2778b1fb
DEBUG¹⁴⁹³³ chan_sip.c: Header 4 [ 55]: Record-Route: <sip:XX.XX:5060;transport=udp;lr>
DEBUG¹⁴⁹³³ chan_sip.c: Header 5 [ 66]: To: <sip:003397044XXXX@sip.ovh.fr>;tag=00-08092-6b594a0f-39fedb527
DEBUG¹⁴⁹³³ chan_sip.c: Header 6 [ 90]: Via: SIP/2.0/UDP IP:5060;received=_IP_;rport=5060;branch=z9hG4bK2f443bce
DEBUG¹⁴⁹³³ chan_sip.c: Header 7 [ 30]: Server: Cirpack/v4.56 (gw_sip)
DEBUG¹⁴⁹³³ chan_sip.c: Header 8 [ 17]: Content-Length: 0
VERBOSE¹⁴⁹³³ chan_sip.c: --- (9 headers 0 lines) ---
DEBUG¹⁴⁹³³ chan_sip.c: = Looking for Call ID: 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060 (Checking To) --From tag as2778b1fb --To-tag 00-08092-6b594a0f-39fedb527
DEBUG¹⁴⁹³³ chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #22
DEBUG¹⁴⁹³³ chan_sip.c: Stopping retransmission on '7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060' of Request 104: Match Found
DEBUG¹⁴⁹³³ chan_sip.c: Destroying SIP dialog 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060
VERBOSE¹⁴⁹³³ chan_sip.c: Really destroying SIP dialog '7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060' Method: INVITE
DEBUG¹⁴⁹³³ rtp_engine.c: Destroyed RTP instance '0x1d52f7d8'
DEBUG¹⁵¹²⁰ manager.c: Examining event:
Event: SIP-Response
Privilege: system,all
ChannelDriver: SIP
Method: BYE
Result: 200|OK

VERBOSE¹⁵³⁸⁸ res_agi.c: -- <Local/003397044XXXX@default-00000000;2>AGI Script agi::4577/call_log--HVcauses ... ---6-----1 completed, returning 0
DEBUG¹⁵³⁸⁸ channel.c: Hanging up zombie 'Local/003397044XXXX@default-00000000;1<ZOMBIE>'
DEBUG¹⁵¹²⁰ manager.c: Examining event:
Event: Hangup
Privilege: call,all
Channel: Local/003397044XXXX@default-00000000;1<ZOMBIE>
Uniqueid: 1446827253.4
CallerIDNum: 0000000000
CallerIDName: V1061127320000047779
ConnectedLineNum: 0000000000
ConnectedLineName: V1061127320000047779
Cause: 16
Cause-txt: Normal Clearing

DEBUG¹⁴⁹²⁵ devicestate.c: No provider found, checking channel drivers for Local - 003397044XXXX@default
DEBUG¹⁴⁹²⁵ chan_local.c: Checking if extension 003397044XXXX@default exists (devicestate)
DEBUG¹⁵¹²⁰ manager.c: Examining event:
Event: Dial
Privilege: call,all
SubEvent: End
Channel: Local/003397044XXXX@default-00000000;2
UniqueID: 1446827253.3
DialStatus: ANSWER

DEBUG¹⁴⁹²⁵ devicestate.c: Changing state for Local/003397044XXXX@default - state 1 (Not in use)
DEBUG¹⁴⁹²⁵ devicestate.c: device 'Local/003397044XXXX@default' state '1'
DEBUG¹⁵³⁸⁸ app_dial.c: Exiting with DIALSTATUS=ANSWER.
DEBUG¹⁵⁰⁰⁰ app_queue.c: Device 'Local/003397044XXXX@default' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
DEBUG¹⁵³⁸⁸ pbx.c: Spawn extension (default,003397044XXXX,2) exited non-zero on 'Local/003397044XXXX@default-00000000;2'
VERBOSE¹⁵³⁸⁸ pbx.c: == Spawn extension (default, 003397044XXXX, 2) exited non-zero on 'Local/003397044XXXX@default-00000000;2'
DEBUG¹⁵³⁸⁸ channel.c: Soft-Hanging up channel 'Local/003397044XXXX@default-00000000;2'
DEBUG¹⁵³⁸⁸ channel.c: Hanging up channel 'Local/003397044XXXX@default-00000000;2'
DEBUG¹⁵¹²⁰ manager.c: Examining event:
Event: Hangup
Privilege: call,all
Channel: Local/003397044XXXX@default-00000000;2
Uniqueid: 1446827253.3
CallerIDNum: 0000000000
CallerIDName: V1061127320000047779
ConnectedLineNum: 0000000000
ConnectedLineName: V1061127320000047779
Cause: 16
Cause-txt: Normal Clearing

DEBUG¹⁴⁹²⁵ devicestate.c: No provider found, checking channel drivers for Local - 003397044XXXX@default
DEBUG¹⁴⁹²⁵ chan_local.c: Checking if extension 003397044XXXX@default exists (devicestate)
DEBUG¹⁴⁹²⁵ devicestate.c: Changing state for Local/003397044XXXX@default - state 1 (Not in use)
DEBUG¹⁴⁹²⁵ devicestate.c: device 'Local/003397044XXXX@default' state '1'
DEBUG¹⁵⁰⁰⁰ app_queue.c: Device 'Local/003397044XXXX@default' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Nov 6 11:27:47] DEBUG¹⁴⁹³⁸ chan_iax2.c: ip callno count decremented to 5 for 127.0.0.1
[Nov 6 11:27:47] DEBUG¹⁴⁹³⁸ chan_iax2.c: ip callno count decremented to 4 for 127.0.0.1
[Nov 6 11:27:47] DEBUG¹⁴⁹⁷⁰ chan_iax2.c: ip callno count incremented to 5 for 127.0.0.1
[Nov 6 11:27:47] DEBUG¹⁴⁹⁶⁹ chan_iax2.c: ip callno count incremented to 6 for 127.0.0.1
[Nov 6 11:27:47] DEBUG¹⁴⁹⁷³ chan_iax2.c: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[Nov 6 11:27:47] DEBUG¹⁴⁹⁷³ chan_iax2.c: Peer ASTblind: got pong, lastms 3, historicms 3, maxms 2000
[Nov 6 11:27:47] DEBUG¹⁴⁹⁷⁴ chan_iax2.c: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[Nov 6 11:27:47] DEBUG¹⁴⁹⁷⁴ chan_iax2.c: Peer ASTloop: got pong, lastms 3, historicms 3, maxms 2000

Thank you

(1-4/4) Go to top

Project

General

Profile

GOautodial Open Source Omni-channel Contact Center Suite