No subject
Mon Jan 10 01:51:56 CST 2011
======================================================================
----------------------------------------------------------------------
(0133107) delphus (reporter) - 2011-03-24 05:00
https://issues.asterisk.org/view.php?id=19016#c133107
----------------------------------------------------------------------
Follows debug 9 of another call with no ringback at all.
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: Allocating new SIP dialog for
386786072050774-376771130038040 at 10.0.1.26 - REGISTER (No RTP)
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: **** Received REGISTER (2) -
Command in SIP REGISTER
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: = Found Their Call ID:
386786072050774-376771130038040 at 10.0.1.26 Their Tag 4577742609 Our tag:
as593efe9c
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: **** Received REGISTER (2) -
Command in SIP REGISTER
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: Allocating new SIP dialog for
(No Call-ID) - OPTIONS (No RTP)
[Mar 24 09:58:08] DEBUG[22465] devicestate.c: Notification of state change
to be queued on device/channel SIP/322
[Mar 24 09:58:08] DEBUG[22465] devicestate.c: No provider found, checking
channel drivers for SIP - 322
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: Checking device state for peer
322
[Mar 24 09:58:08] DEBUG[22465] devicestate.c: Changing state for SIP/322 -
state 1 (Not in use)
[Mar 24 09:58:08] DEBUG[22465] app_queue.c: Device 'SIP/322' changed to
state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: = Found Their Call ID:
3f3eebaa780c6b68260b44d453cdd148 at 10.0.1.254 Their Tag Our tag: as39142bd9
[Mar 24 09:58:08] DEBUG[22465] chan_sip.c: Stopping retransmission on
'3f3eebaa780c6b68260b44d453cdd148 at 10.0.1.254' of Request 102: Match Found
[Mar 24 09:58:10] DEBUG[22465] chan_iax2.c: Determining if address
10.0.1.10 with username 302 requires calltoken validation. Optional = 1
calltoken_required = 3
[Mar 24 09:58:10] DEBUG[22465] chan_iax2.c: ip callno count incremented to
7 for 10.0.1.10
[Mar 24 09:58:10] DEBUG[22465] pbx.c: Function result is '302'
[Mar 24 09:58:10] DEBUG[22465] pbx.c: Launching 'NoOp'
[Mar 24 09:58:10] DEBUG[22465] pbx.c: Function result is '320'
[Mar 24 09:58:10] DEBUG[22465] pbx.c: Launching 'Set'
[Mar 24 09:58:10] DEBUG[22465] pbx.c: Launching 'Dial'
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Asked to create a SIP channel
with formats: 0x8 (alaw)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Allocating new SIP dialog for
(No Call-ID) - INVITE (With RTP)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Setting NAT on RTP to On
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: *** Our native formats are
0x100 (g729)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: *** Joint capabilities are 0x0
(nothing)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: *** Our capabilities are 0x50e
(gsm|ulaw|alaw|g729|ilbc)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: *** AST_CODEC_CHOOSE formats
are 0x100 (g729)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: *** Our preferred formats from
the incoming channel are 0x8 (alaw)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: This channel will not be able
to handle video.
[Mar 24 09:58:10] DEBUG[22465] rtp.c: Channel 'IAX2/302-14746' has no RTP,
not doing anything
[Mar 24 09:58:10] DEBUG[22465] channel.c: Not copying variable
DIALEDTIME.
[Mar 24 09:58:10] DEBUG[22465] channel.c: Not copying variable
ANSWEREDTIME.
[Mar 24 09:58:10] DEBUG[22465] channel.c: Not copying variable
DIALEDPEERNAME.
[Mar 24 09:58:10] DEBUG[22465] channel.c: Not copying variable
DIALEDPEERNUMBER.
[Mar 24 09:58:10] DEBUG[22465] channel.c: Not copying variable
DIALSTATUS.
[Mar 24 09:58:10] DEBUG[22465] channel.c: Not copying variable DIALED.
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Outgoing Call for 320
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Updating call counter for
outgoing call
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Our T38 capability (0), joint
T38 capability (0)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: ** Our capability: 0x50e
(gsm|ulaw|alaw|g729|ilbc) Video flag: False
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: ** Our prefcodec: 0x8 (alaw)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: -- Done with adding codecs to
SDP
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Done building SDP. Settling
with this capability: 0x50e (gsm|ulaw|alaw|g729|ilbc)
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: = Found Their Call ID:
589675a02ca3744e551f27a932d754c0 at 10.0.1.254 Their Tag Our tag: as4b84ee5c
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet) on
'589675a02ca3744e551f27a932d754c0 at 10.0.1.254' Request 102: Found
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: SIP response 100 to standard
invite
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: = Found Their Call ID:
589675a02ca3744e551f27a932d754c0 at 10.0.1.254 Their Tag Our tag: as4b84ee5c
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet) on
'589675a02ca3744e551f27a932d754c0 at 10.0.1.254' Request 102: Found
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: SIP response 180 to standard
invite
[Mar 24 09:58:10] DEBUG[22465] devicestate.c: Notification of state change
to be queued on device/channel SIP/320
[Mar 24 09:58:10] DEBUG[22465] devicestate.c: No provider found, checking
channel drivers for SIP - 320
[Mar 24 09:58:10] DEBUG[22465] chan_sip.c: Checking device state for peer
320
[Mar 24 09:58:10] DEBUG[22465] devicestate.c: Changing state for SIP/320 -
state 1 (Not in use)
[Mar 24 09:58:10] DEBUG[22465] app_queue.c: Device 'SIP/320' changed to
state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Mar 24 09:58:10] DEBUG[22465] chan_iax2.c: Ooh, voice format changed to
8
[Mar 24 09:58:10] DEBUG[22465] chan_iax2.c: ip callno count decremented to
6 for 10.0.1.10
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Allocating new SIP dialog for
(No Call-ID) - NOTIFY (No RTP)
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: = Found Their Call ID:
60e68a505656a7c06e728a820bb084a9 at 10.0.1.254 Their Tag Our tag: as3712e423
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Stopping retransmission on
'60e68a505656a7c06e728a820bb084a9 at 10.0.1.254' of Request 102: Match Found
[Mar 24 09:58:13] DEBUG[22465] chan_iax2.c: Immediately destroying 14746,
having received hangup
[Mar 24 09:58:13] DEBUG[22465] rtp.c: Channel 'IAX2/302-14746' has no RTP,
not doing anything
[Mar 24 09:58:13] DEBUG[22465] channel.c: Hanging up channel
'SIP/320-00000025'
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Hangup call SIP/320-00000025,
SIP callid 589675a02ca3744e551f27a932d754c0 at 10.0.1.254)
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Hanging up channel in state
Ringing (not UP)
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet) on
'589675a02ca3744e551f27a932d754c0 at 10.0.1.254' Request 102: Found
[Mar 24 09:58:13] DEBUG[22465] devicestate.c: Notification of state change
to be queued on device/channel SIP/320
[Mar 24 09:58:13] DEBUG[22465] app_dial.c: Exiting with
DIALSTATUS=CANCEL.
[Mar 24 09:58:13] DEBUG[22465] devicestate.c: No provider found, checking
channel drivers for SIP - 320
[Mar 24 09:58:13] DEBUG[22465] pbx.c: Spawn extension (outgoing,320,3)
exited non-zero on 'IAX2/302-14746'
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Checking device state for peer
320
[Mar 24 09:58:13] DEBUG[22465] devicestate.c: Changing state for SIP/320 -
state 1 (Not in use)
[Mar 24 09:58:13] DEBUG[22465] channel.c: Soft-Hanging up channel
'IAX2/302-14746'
[Mar 24 09:58:13] DEBUG[22465] app_queue.c: Device 'SIP/320' changed to
state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Mar 24 09:58:13] DEBUG[22465] channel.c: Hanging up channel
'IAX2/302-14746'
[Mar 24 09:58:13] DEBUG[22465] chan_iax2.c: We're hanging up
IAX2/302-14746 now...
[Mar 24 09:58:13] DEBUG[22465] chan_iax2.c: Really destroying
IAX2/302-14746 now...
[Mar 24 09:58:13] DEBUG[22465] chan_iax2.c: schedule decrement of callno
used for 10.0.1.10 in 60 seconds
[Mar 24 09:58:13] DEBUG[22465] cdr_addon_mysql.c: cdr_mysql: inserting a
CDR record.
[Mar 24 09:58:13] DEBUG[22465] cdr_addon_mysql.c: cdr_mysql: SQL command
as follows: INSERT INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield)
VALUES ('2011-03-24 09:58:10','302','302','320','outgoing',
'IAX2/302-14746','SIP/320-00000025','Dial','SIP/320|45|tTr',3,0,'NO
ANSWER',3,'','')
[Mar 24 09:58:13] DEBUG[22465] devicestate.c: Notification of state change
to be queued on device/channel IAX2/302
[Mar 24 09:58:13] DEBUG[22465] devicestate.c: No provider found, checking
channel drivers for IAX2 - 302
[Mar 24 09:58:13] DEBUG[22465] chan_iax2.c: Checking device state for
device 302
[Mar 24 09:58:13] DEBUG[22465] chan_iax2.c: iax2_devicestate: Found peer.
What's device state of 302? addr=167837706, defaddr=0 maxms=2000, lastms=1
[Mar 24 09:58:13] DEBUG[22465] devicestate.c: Changing state for IAX2/302
- state 1 (Not in use)
[Mar 24 09:58:13] DEBUG[22465] app_queue.c: Device 'IAX2/302' changed to
state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: = Found Their Call ID:
589675a02ca3744e551f27a932d754c0 at 10.0.1.254 Their Tag ee9acb701560a2f1i4
Our tag: as4b84ee5c
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Acked pending invite 102
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Stopping retransmission on
'589675a02ca3744e551f27a932d754c0 at 10.0.1.254' of Request 102: Match Found
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: SIP response 487 to standard
invite
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Updating call counter for
outgoing call
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Setting SIP_ALREADYGONE on
dialog 589675a02ca3744e551f27a932d754c0 at 10.0.1.254
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: = Found Their Call ID:
589675a02ca3744e551f27a932d754c0 at 10.0.1.254 Their Tag ee9acb701560a2f1i4
Our tag: as4b84ee5c
[Mar 24 09:58:13] DEBUG[22465] chan_sip.c: Stopping retransmission on
'589675a02ca3744e551f27a932d754c0 at 10.0.1.254' of Request 102: Match Found
Issue History
Date Modified Username Field Change
======================================================================
2011-03-24 05:00 delphus Note Added: 0133107
======================================================================
More information about the asterisk-bugs
mailing list