PLEASE HELP!- Asterisk hungs up after 2 seconds

[wdeveloper]

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

[vijaymuddu]

Post your vicidial and asterisk version

[wdeveloper]

Post your vicidial and asterisk version

my asterisk version 1.8.23 , my vicidial version vicidial-2.9.44, my goautodial 3.0

this is my cli output after activate debuging, thanks


[Nov 6 11:27:38] VERBOSE[15387] pbx.c: > Channel Local/003397044XXXX@default-00000000;1 was answered.
[Nov 6 11:27:38] DEBUG[14925] devicestate.c: No provider found, checking channel drivers for Local - 003397044XXXX@default
[Nov 6 11:27:38] DEBUG[14925] chan_local.c: Checking if extension 003397044XXXX@default exists (devicestate)
[Nov 6 11:27:38] DEBUG[14925] devicestate.c: Changing state for Local/003397044XXXX@default - state 2 (In use)
[Nov 6 11:27:38] DEBUG[14925] devicestate.c: device 'Local/003397044XXXX@default' state '2'
[Nov 6 11:27:38] DEBUG[15120] manager.c: Examining event:
Event: Newstate
Privilege: call,all
Channel: Local/003397044XXXX@default-00000000;1
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 0000000000
CallerIDName: V1061127320000047779
ConnectedLineNum: 0000000000
ConnectedLineName: V1061127320000047779
Uniqueid: 1446827253.2


[Nov 6 11:27:38] DEBUG[15000] 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.
[Nov 6 11:27:38] DEBUG[15000] 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.
[Nov 6 11:27:38] DEBUG[15000] 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.
[Nov 6 11:27:38] DEBUG[15404] pbx.c: Launching 'Playback'
[Nov 6 11:27:38] VERBOSE[15404] pbx.c: -- Executing [8368@default:1] Playback("Local/003397044XXXX@default-00000000;1", "sip-silence") in new stack
[Nov 6 11:27:38] DEBUG[15404] channel.c: Set channel Local/003397044XXXX@default-00000000;1 to write format gsm
[Nov 6 11:27:38] DEBUG[15388] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw
[Nov 6 11:27:38] DEBUG[15388] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160
[Nov 6 11:27:38] DEBUG[15388] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x1d52f7d8'
[Nov 6 11:27:38] DEBUG[15404] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Nov 6 11:27:38] VERBOSE[15404] file.c: -- <Local/003397044XXXX@default-00000000;1> Playing 'sip-silence.gsm' (language 'en')
[Nov 6 11:27:38] DEBUG[15387] manager.c: Running action 'Logoff'
[Nov 6 11:27:38] VERBOSE[15387] manager.c: == Manager 'sendcron' logged off from 127.0.0.1
[Nov 6 11:27:38] DEBUG[15404] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 6 11:27:38] DEBUG[15404] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 6 11:27:38] DEBUG[15404] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 6 11:27:38] DEBUG[15404] channel.c: Set channel Local/003397044XXXX@default-00000000;1 to write format slin
[Nov 6 11:27:38] DEBUG[15404] pbx.c: Launching 'AGI'
[Nov 6 11:27:38] VERBOSE[15404] pbx.c: -- Executing [8368@default:2] AGI("Local/003397044XXXX@default-00000000;1", "agi::4577/call_log") in new stack
[Nov 6 11:27:38] DEBUG[15404] res_agi.c: Wow, connected!
[Nov 6 11:27:38] VERBOSE[15404] res_agi.c: -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=TESTCAMP))
[Nov 6 11:27:38] VERBOSE[15404] res_agi.c: -- <Local/003397044XXXX@default-00000000;1>AGI Script agi::4577/call_log completed, returning 0
[Nov 6 11:27:38] DEBUG[15404] pbx.c: Launching 'AGI'
[Nov 6 11:27:38] VERBOSE[15404] pbx.c: -- Executing [8368@default:3] AGI("Local/003397044XXXX@default-00000000;1", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack
[Nov 6 11:27:38] VERBOSE[15404] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 6 11:27:39] DEBUG[15388] channel.c: Planning to masquerade channel SIP/0033972XXXXXX-00000000 into the structure of Local/003397044XXXX@default-00000000;1
[Nov 6 11:27:39] DEBUG[15388] channel.c: Done planning to masquerade channel SIP/0033972XXXXXX-00000000 into the structure of Local/003397044XXXX@default-00000000;1
[Nov 6 11:27:39] DEBUG[15388] chan_local.c: Masquerading Local/003397044XXXX@default-00000000;1 <- SIP/0033972XXXXXX-00000000
[Nov 6 11:27:39] DEBUG[15404] chan_local.c: Blocked indication -1
[Nov 6 11:27:39] DEBUG[15404] channel.c: Actually Masquerading SIP/0033972XXXXXX-00000000(6) into the structure of Local/003397044XXXX@default-00000000;1(6)
[Nov 6 11:27:39] DEBUG[15120] manager.c: Examining event:
Event: Masquerade
Privilege: call,all
Clone: SIP/0033972XXXXXX-00000000
CloneState: Up
Original: Local/003397044XXXX@default-00000000;1
OriginalState: Up


[Nov 6 11:27:39] DEBUG[15120] manager.c: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/0033972XXXXXX-00000000
Newname: SIP/0033972XXXXXX-00000000<MASQ>
Uniqueid: 1446827253.4


[Nov 6 11:27:39] DEBUG[15120] manager.c: Examining event:
Event: Rename
Privilege: call,all
Channel: Local/003397044XXXX@default-00000000;1
Newname: SIP/0033972XXXXXX-00000000
Uniqueid: 1446827253.2


[Nov 6 11:27:39] DEBUG[15120] manager.c: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/0033972XXXXXX-00000000<MASQ>
Newname: Local/003397044XXXX@default-00000000;1<ZOMBIE>
Uniqueid: 1446827253.4


[Nov 6 11:27:39] DEBUG[15120] manager.c: Examining event:
Event: NewCallerid
Privilege: call,all
Channel: SIP/0033972XXXXXX-00000000
CallerIDNum: 0000000000
CallerIDName: V1061127320000047779
Uniqueid: 1446827253.2
CID-CallingPres: 0 (Presentation Allowed, Not Screened)


[Nov 6 11:27:39] DEBUG[15404] channel.c: Set channel SIP/0033972XXXXXX-00000000 to write format slin
[Nov 6 11:27:39] DEBUG[15404] channel.c: Set channel SIP/0033972XXXXXX-00000000 to read format slin
[Nov 6 11:27:39] DEBUG[15404] channel.c: Putting channel SIP/0033972XXXXXX-00000000 in slin/slin formats
[Nov 6 11:27:39] DEBUG[15404] chan_sip.c: SIP Fixup: New owner for dialogue 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060: SIP/0033972XXXXXX-00000000 (Old parent: Local/003397044XXXX@default-00000000;1<ZOMBIE>)
[Nov 6 11:27:39] DEBUG[15404] channel.c: Done Masquerading SIP/0033972XXXXXX-00000000 (6)
[Nov 6 11:27:39] DEBUG[15404] res_rtp_asterisk.c: Changing ssrc from 609914259 to 321984035 due to a source change
[Nov 6 11:27:39] DEBUG[15388] chan_local.c: Not posting to 'Local/003397044XXXX@default-00000000;2' queue since already masqueraded out
[Nov 6 11:27:39] DEBUG[15388] 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
[Nov 6 11:27:39] DEBUG[15388] channel.c: Bridge stops bridging channels Local/003397044XXXX@default-00000000;2 and Local/003397044XXXX@default-00000000;1<ZOMBIE>
[Nov 6 11:27:39] DEBUG[15388] channel.c: Soft-Hanging up channel 'Local/003397044XXXX@default-00000000;2'
[Nov 6 11:27:39] DEBUG[15388] pbx.c: Result of 'HANGUPCAUSE' is '16'
[Nov 6 11:27:39] DEBUG[15388] pbx.c: Result of 'DIALSTATUS' is 'ANSWER'
[Nov 6 11:27:39] DEBUG[15388] pbx.c: Result of 'DIALEDTIME' is '6'
[Nov 6 11:27:39] DEBUG[15388] pbx.c: Result of 'ANSWEREDTIME' is '1'
[Nov 6 11:27:39] DEBUG[15388] pbx.c: Launching 'AGI'
[Nov 6 11:27:39] VERBOSE[15388] pbx.c: -- Executing [h@default:1] AGI("Local/003397044XXXX@default-00000000;2", "agi::4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----6-----1") in new stack
[Nov 6 11:27:39] DEBUG[15388] res_agi.c: Hungup channel detected, running agi in dead mode.
[Nov 6 11:27:39] DEBUG[15388] res_agi.c: Wow, connected!
[Nov 6 11:27:39] DEBUG[15120] 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

[Nov 6 11:27:39] VERBOSE[15404] res_agi.c: -- <SIP/0033972XXXXXX-00000000>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Launching 'AGI'
[Nov 6 11:27:39] VERBOSE[15404] pbx.c: -- Executing [8368@default:4] AGI("SIP/0033972XXXXXX-00000000", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack
[Nov 6 11:27:39] VERBOSE[15404] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 6 11:27:39] DEBUG[14941] 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
[Nov 6 11:27:39] DEBUG[15110] manager.c: Running action 'Command'
[Nov 6 11:27:39] VERBOSE[15404] res_agi.c: -- <SIP/0033972XXXXXX-00000000>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Launching 'Hangup'
[Nov 6 11:27:39] VERBOSE[15404] pbx.c: -- Executing [8368@default:5] Hangup("SIP/0033972XXXXXX-00000000", "") in new stack
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Spawn extension (default,8368,5) exited non-zero on 'SIP/0033972XXXXXX-00000000'
[Nov 6 11:27:39] VERBOSE[15404] pbx.c: == Spawn extension (default, 8368, 5) exited non-zero on 'SIP/0033972XXXXXX-00000000'
[Nov 6 11:27:39] DEBUG[15404] channel.c: Soft-Hanging up channel 'SIP/0033972XXXXXX-00000000'
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Result of 'HANGUPCAUSE' is '16'
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Result of 'DIALSTATUS' is NULL
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Result of 'DIALEDTIME' is NULL
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Result of 'ANSWEREDTIME' is NULL
[Nov 6 11:27:39] DEBUG[15404] pbx.c: Launching 'AGI'
[Nov 6 11:27:39] VERBOSE[15404] pbx.c: -- Executing [h@default:1] AGI("SIP/0033972XXXXXX-00000000", "agi::4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Nov 6 11:27:39] DEBUG[15404] res_agi.c: Hungup channel detected, running agi in dead mode.
[Nov 6 11:27:39] DEBUG[15404] res_agi.c: Wow, connected!
[Nov 6 11:27:39] VERBOSE[15404] res_agi.c: -- <SIP/0033972XXXXXX-00000000>AGI Script agi::4577/call_log--HVcauses ... ---------- completed, returning 0
[Nov 6 11:27:39] DEBUG[15404] channel.c: Hanging up channel 'SIP/0033972XXXXXX-00000000'
[Nov 6 11:27:39] DEBUG[15404] chan_sip.c: Hangup call SIP/0033972XXXXXX-00000000, SIP callid 7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060
[Nov 6 11:27:39] DEBUG[15404] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x1d52f7d8'
[Nov 6 11:27:39] VERBOSE[15404] chan_sip.c: Scheduling destruction of SIP dialog '7a5722ef4f5a3e86317a356e3436d9bf@_IP_:5060' in 32000 ms (Method: INVITE)
[Nov 6 11:27:39] VERBOSE[15404] chan_sip.c: set_destination: Parsing <sip:XX.XX:5060;transport=udp;lr> for address/port to send to
[Nov 6 11:27:39] DEBUG[15404] netsock2.c: Splitting 'XX.XX:5060' into...
[Nov 6 11:27:39] DEBUG[15404] netsock2.c: ...host '91.121.XX.XX' and port '5060'.
[Nov 6 11:27:39] VERBOSE[15404] chan_sip.c: set_destination: set destination to XX.XX:5060
[Nov 6 11:27:39] VERBOSE[15404] 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
---
[Nov 6 11:27:39] DEBUG[15404] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #22
[Nov 6 11:27:39] DEBUG[15404] chan_sip.c: Trying to put 'BYE sip:10.' onto UDP socket destined for XX.XX:5060
[Nov 6 11:27:39] DEBUG[15120] 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


[Nov 6 11:27:39] DEBUG[14925] devicestate.c: No provider found, checking channel drivers for SIP - 0033972XXXXXX
[Nov 6 11:27:39] DEBUG[14925] chan_sip.c: Checking device state for peer 0033972XXXXXX
[Nov 6 11:27:39] DEBUG[14925] devicestate.c: Changing state for SIP/0033972XXXXXX - state 1 (Not in use)
[Nov 6 11:27:39] DEBUG[14925] devicestate.c: device 'SIP/0033972XXXXXX' state '1'
[Nov 6 11:27:39] DEBUG[15000] 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.
[Nov 6 11:27:39] VERBOSE[14933] 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

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


[Nov 6 11:27:40] VERBOSE[15388] res_agi.c: -- <Local/003397044XXXX@default-00000000;2>AGI Script agi::4577/call_log--HVcauses ... ---6-----1 completed, returning 0
[Nov 6 11:27:40] DEBUG[15388] channel.c: Hanging up zombie 'Local/003397044XXXX@default-00000000;1<ZOMBIE>'
[Nov 6 11:27:40] DEBUG[15120] 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


[Nov 6 11:27:40] DEBUG[14925] devicestate.c: No provider found, checking channel drivers for Local - 003397044XXXX@default
[Nov 6 11:27:40] DEBUG[14925] chan_local.c: Checking if extension 003397044XXXX@default exists (devicestate)
[Nov 6 11:27:40] DEBUG[15120] manager.c: Examining event:
Event: Dial
Privilege: call,all
SubEvent: End
Channel: Local/003397044XXXX@default-00000000;2
UniqueID: 1446827253.3
DialStatus: ANSWER


[Nov 6 11:27:40] DEBUG[14925] devicestate.c: Changing state for Local/003397044XXXX@default - state 1 (Not in use)
[Nov 6 11:27:40] DEBUG[14925] devicestate.c: device 'Local/003397044XXXX@default' state '1'
[Nov 6 11:27:40] DEBUG[15388] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Nov 6 11:27:40] DEBUG[15000] 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:40] DEBUG[15388] pbx.c: Spawn extension (default,003397044XXXX,2) exited non-zero on 'Local/003397044XXXX@default-00000000;2'
[Nov 6 11:27:40] VERBOSE[15388] pbx.c: == Spawn extension (default, 003397044XXXX, 2) exited non-zero on 'Local/003397044XXXX@default-00000000;2'
[Nov 6 11:27:40] DEBUG[15388] channel.c: Soft-Hanging up channel 'Local/003397044XXXX@default-00000000;2'
[Nov 6 11:27:40] DEBUG[15388] channel.c: Hanging up channel 'Local/003397044XXXX@default-00000000;2'
[Nov 6 11:27:40] DEBUG[15120] 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


[Nov 6 11:27:40] DEBUG[14925] devicestate.c: No provider found, checking channel drivers for Local - 003397044XXXX@default
[Nov 6 11:27:40] DEBUG[14925] chan_local.c: Checking if extension 003397044XXXX@default exists (devicestate)
[Nov 6 11:27:40] DEBUG[14925] devicestate.c: Changing state for Local/003397044XXXX@default - state 1 (Not in use)
[Nov 6 11:27:40] DEBUG[14925] devicestate.c: device 'Local/003397044XXXX@default' state '1'
[Nov 6 11:27:40] DEBUG[15000] 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[14938] chan_iax2.c: ip callno count decremented to 5 for 127.0.0.1
[Nov 6 11:27:47] DEBUG[14938] chan_iax2.c: ip callno count decremented to 4 for 127.0.0.1
[Nov 6 11:27:47] DEBUG[14970] chan_iax2.c: ip callno count incremented to 5 for 127.0.0.1
[Nov 6 11:27:47] DEBUG[14969] chan_iax2.c: ip callno count incremented to 6 for 127.0.0.1
[Nov 6 11:27:47] DEBUG[14973] chan_iax2.c: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[Nov 6 11:27:47] DEBUG[14973] chan_iax2.c: Peer ASTblind: got pong, lastms 3, historicms 3, maxms 2000
[Nov 6 11:27:47] DEBUG[14974] chan_iax2.c: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[Nov 6 11:27:47] DEBUG[14974] chan_iax2.c: Peer ASTloop: got pong, lastms 3, historicms 3, maxms 2000
[Nov 6 11:27:47] VERBOSE[14933] chan_sip.c:
<--- SIP read from UDP:XX.XX:5060 --->
OPTIONS sip:0033972XXXXXX@_IP_:5060 SIP/2.0
Call-ID: 00-07236-06cfefe6-6a375db91@91.121.XX.XX
Contact: <sip:XX.XX:5060>
CSeq: 1 OPTIONS
From: <sip:keepalive@XX.XX:5060>;tag=00-07236-06cfefe5-47ad4afa4
Max-Forwards: 70
To: <sip:0033972XXXXXX@sip.ovh.fr>
Via: SIP/2.0/UDP XX.XX:5060;rport;branch=z9hG4bK-JYTB-03efaf44-186a76d9
Content-Length: 0

Thank you