[Asterisk-Users] cannot set outgoing cid

Sebastian Reitenbach itlistuser at rapideye.de
Fri Mar 31 08:08:45 MST 2006


Hi,

sorry for the long debug output below. I configured Asterisk with AMP to send 
the whole number including the extensions of the callers to the called party. 
Whatever I configure in AMP it looks like it is used, In my eyes it is ok, but 
doesn't seem to work.

033811234451 is the call id i configured, and it seems to use them, but the 
caller will only see a 0338189040 instead of my extension. 

any hint to what could be wrong is greatly appreciated.

kind regards
Sebastian

Mar 31 16:53:56 DEBUG[24358] chan_sip.c: = No match Their Call ID: 
3c336898975e-udfiigp7a38i at 192-168-11-189 Their Tag 5jd9htv3rz Our tag: 
as1e957ca5
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: Allocating new SIP dialog for 
ipixsmdwtxqusdu at 192.168.11.79 - INVITE (With RTP)
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: **** Received INVITE (5) - Command in 
SIP INVITE
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: * SIP extension value: 2 for call 
ipixsmdwtxqusdu at 192.168.11.79
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: Setting NAT on RTP to 0
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: = Found Their Call ID: 
ipixsmdwtxqusdu at 192.168.11.79 Their Tag fzxtk Our tag: as33b84ae6
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: **** Received ACK (6) - Command in 
SIP ACK
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: Stopping retransmission on 
'ipixsmdwtxqusdu at 192.168.11.79' of Response 830: Match Found
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: = Found Their Call ID: 
ipixsmdwtxqusdu at 192.168.11.79 Their Tag fzxtk Our tag: as33b84ae6
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: **** Received INVITE (5) - Command in 
SIP INVITE
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: Setting NAT on RTP to 0
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: Checking SIP call limits for device 
451
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: Updating call counter for incoming 
call
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: build_route: Contact hop: 
<sip:451_asterisk_intern at 192.168.11.79;user=phone>
Mar 31 16:53:56 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:53:56 DEBUG[24349] channel.c: Avoiding initial deadlock for 
'SIP/451-0e31'
Mar 31 16:53:56 DEBUG[24349] channel.c: Avoiding initial deadlock for 
'SIP/451-0e31'
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'Macro'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
Macro("SIP/451-0e31", "dialout-trunk|1|03381765432|") in new stack
Mar 31 16:53:56 DEBUG[24349] devicestate.c: Changing state for SIP/451 - state 
2 (In use)
Mar 31 16:53:56 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:53:56 DEBUG[11748] app_queue.c: Device 'SIP/451' changed to state 
'2' (In use)
Mar 31 16:53:56 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:53:56 DEBUG[11747] pbx.c: Expression result is '1'
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "1?3:2)") in new stack
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Goto (macro-dialout-trunk,s,3)
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'Macro'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
Macro("SIP/451-0e31", "user-callerid") in new stack
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'DBget'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
DBget("SIP/451-0e31", "AMPUSER=DEVICE/451/user") in new stack
Mar 31 16:53:56 DEBUG[11688] chan_zap.c: Write returned -1 (Resource 
temporarily unavailable) on channel 2
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- DBget: varname=AMPUSER, 
family=DEVICE, key=451/user
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- DBget: set variable AMPUSER to 
451
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'DBget'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
DBget("SIP/451-0e31", "AMPUSERCIDNAME=AMPUSER/451/cidname") in new stack
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- DBget: varname=AMPUSERCIDNAME, 
family=AMPUSER, key=451/cidname
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- DBget: set variable 
AMPUSERCIDNAME to Sebastian Reitenbach
Mar 31 16:53:56 DEBUG[11747] pbx.c: Expression result is '0'
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "0?5") in new stack
Mar 31 16:53:56 DEBUG[11747] pbx.c: Not taking any branch
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'SetCallerID'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
SetCallerID("SIP/451-0e31", ""Sebastian Reitenbach" <451>") in new stack
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'NoOp'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing NoOp("SIP/451-0e31", 
"Using CallerID "Sebastian Reitenbach" <451>") in new stack
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'Macro'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
Macro("SIP/451-0e31", "record-enable|451|OUT") in new stack
Mar 31 16:53:56 DEBUG[11747] pbx.c: Function result is '0'
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "0 > 0?2:4") in new stack
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Goto (macro-record-enable,s,4)
Mar 31 16:53:56 DEBUG[11747] pbx.c: Launching 'AGI'
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Executing AGI("SIP/451-0e31", 
"recordingcheck|20060331-165356|1143816836.643") in new stack
Mar 31 16:53:56 VERBOSE[11747] logger.c:     -- Launched AGI 
Script /var/lib/asterisk/agi-bin/recordingcheck
Mar 31 16:53:57 VERBOSE[11747] logger.c:   recordingcheck|20060331-165356|
1143816836.643: Outbound recording not enabled
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- AGI Script recordingcheck 
completed, returning 0
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'NoOp'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing NoOp("SIP/451-0e31", 
"No recording needed") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'Macro'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
Macro("SIP/451-0e31", "outbound-callerid|1") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'DBget'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
DBget("SIP/451-0e31", "USEROUTCID=AMPUSER/451/outboundcid") in new stack
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- DBget: varname=USEROUTCID, 
family=AMPUSER, key=451/outboundcid
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- DBget: set variable USEROUTCID 
to 033811234451
Mar 31 16:53:57 DEBUG[11747] pbx.c: Expression result is '0'
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "0?4") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Not taking any branch
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'SetCallerID'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
SetCallerID("SIP/451-0e31", "033811234100") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Expression result is '0'
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "0?6") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Not taking any branch
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'SetCallerID'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
SetCallerID("SIP/451-0e31", "033811234451") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'NoOp'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing NoOp("SIP/451-0e31", 
"CallerID set to 033811234451") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'SetGroup'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
SetGroup("SIP/451-0e31", "OUT_1") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Function result is '1'
Mar 31 16:53:57 DEBUG[11747] pbx.c: Expression result is '0'
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "0?108") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Not taking any branch
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'SetVar'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
SetVar("SIP/451-0e31", "DIAL_NUMBER=03381765432") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'SetVar'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
SetVar("SIP/451-0e31", "DIAL_TRUNK=1") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'AGI'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing AGI("SIP/451-0e31", 
"fixlocalprefix") in new stack
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Launched AGI 
Script /var/lib/asterisk/agi-bin/fixlocalprefix
Mar 31 16:53:57 VERBOSE[11747] logger.c:   fixlocalprefix: Removed prefix. New 
number: 3381765432
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- AGI Script fixlocalprefix 
completed, returning 0
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'SetVar'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
SetVar("SIP/451-0e31", "OUTNUM=3381765432") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'Cut'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing Cut("SIP/451-0e31", 
"custom=OUT_1|:|1") in new stack
Mar 31 16:53:57 WARNING[11747] ast_expr2.y: non-numeric argument
Mar 31 16:53:57 DEBUG[11747] pbx.c: Expression result is '0'
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'GotoIf'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing 
GotoIf("SIP/451-0e31", "0?16") in new stack
Mar 31 16:53:57 DEBUG[11747] pbx.c: Not taking any branch
Mar 31 16:53:57 DEBUG[11747] pbx.c: Launching 'Dial'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Executing Dial("SIP/451-0e31", 
"ZAP/g1/3381765432") in new stack
Mar 31 16:53:57 DEBUG[11747] chan_zap.c: Using channel 1
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-14.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable MACRO_DEPTH.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-13.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable custom.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-12.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable OUTNUM.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-11.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable DIAL_NUMBER.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-10.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable DIAL_TRUNK.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-9.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-8.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-7.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable GROUP.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-6.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable MACRO_PRIORITY.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable MACRO_CONTEXT.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable MACRO_EXTEN.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable ARG1.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-outbound-callerid-s-6.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-outbound-callerid-s-5.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-outbound-callerid-s-4.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-outbound-callerid-s-3.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-outbound-callerid-s-2.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable DBGETSTATUS.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable USEROUTCID.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-outbound-callerid-s-1.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-5.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable ARG2.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-record-enable-s-5.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-record-enable-s-4.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-record-enable-s-1.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-4.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-user-callerid-s-5.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-user-callerid-s-4.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-user-callerid-s-3.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable AMPUSERCIDNAME.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-user-callerid-s-2.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable AMPUSER.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-user-callerid-s-1.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-3.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-macro-dialout-trunk-s-1.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable ARG3.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable 
STACK-from-internal-003381765432-1.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable SIPCALLID.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable SIPUSERAGENT.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable SIPDOMAIN.
Mar 31 16:53:57 DEBUG[11747] channel.c: Not copying variable SIPURI.
Mar 31 16:53:57 DEBUG[24349] devicestate.c: Changing state for Zap/1 - state 2 
(In use)
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Requested transfer capability: 
0x00 - SPEECH
Mar 31 16:53:57 DEBUG[11751] app_queue.c: Device 'Zap/1' changed to state 
'2' (In use)
Mar 31 16:53:57 DEBUG[24349] channel.c: Avoiding initial deadlock for 
'Zap/1-1'
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Called g1/3381765432
Mar 31 16:53:57 DEBUG[11747] channel.c: Set channel Zap/1-1 to read format 
slin
Mar 31 16:53:57 DEBUG[11747] channel.c: Set channel SIP/451-0e31 to write 
format slin
Mar 31 16:53:57 DEBUG[11747] channel.c: Set channel SIP/451-0e31 to read 
format slin
Mar 31 16:53:57 DEBUG[11747] channel.c: Set channel Zap/1-1 to write format 
slin
Mar 31 16:53:57 DEBUG[24349] devicestate.c: Changing state for Zap/1 - state 2 
(In use)
Mar 31 16:53:57 DEBUG[11752] app_queue.c: Device 'Zap/1' changed to state 
'2' (In use)
Mar 31 16:53:57 DEBUG[11747] rtp.c: Ooh, format changed from unknown to ulaw
Mar 31 16:53:57 DEBUG[11747] rtp.c: Got RTCP report of 36 bytes
Mar 31 16:53:57 DEBUG[24364] chan_zap.c: Queuing frame from 
PRI_EVENT_PROCEEDING on channel 0/1 span 1
Mar 31 16:53:57 VERBOSE[11747] logger.c:     -- Zap/1-1 is proceeding passing 
it to SIP/451-0e31
Mar 31 16:54:01 DEBUG[24364] chan_zap.c: Enabled echo cancellation on channel 
1
Mar 31 16:54:01 VERBOSE[11747] logger.c:     -- Zap/1-1 answered SIP/451-0e31
Mar 31 16:54:01 DEBUG[11747] channel.c: Set channel SIP/451-0e31 to read 
format slin
Mar 31 16:54:01 DEBUG[11747] channel.c: Set channel Zap/1-1 to write format 
slin
Mar 31 16:54:01 DEBUG[11747] channel.c: Set channel Zap/1-1 to read format 
slin
Mar 31 16:54:01 DEBUG[11747] channel.c: Set channel SIP/451-0e31 to write 
format slin
Mar 31 16:54:01 DEBUG[11747] chan_sip.c: sip_answer(SIP/451-0e31)
Mar 31 16:54:01 DEBUG[24349] devicestate.c: Changing state for Zap/1 - state 2 
(In use)
Mar 31 16:54:01 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:54:01 DEBUG[24349] devicestate.c: Changing state for SIP/451 - state 
2 (In use)
Mar 31 16:54:01 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:54:01 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:54:01 DEBUG[24358] chan_sip.c: = Found Their Call ID: 
ipixsmdwtxqusdu at 192.168.11.79 Their Tag fzxtk Our tag: as5ce7b16c
Mar 31 16:54:01 DEBUG[24358] chan_sip.c: **** Received ACK (6) - Command in 
SIP ACK
Mar 31 16:54:01 DEBUG[24358] chan_sip.c: Stopping retransmission on 
'ipixsmdwtxqusdu at 192.168.11.79' of Response 831: Match Found
Mar 31 16:54:01 DEBUG[11753] app_queue.c: Device 'Zap/1' changed to state 
'2' (In use)
Mar 31 16:54:01 DEBUG[11754] app_queue.c: Device 'SIP/451' changed to state 
'2' (In use)
Mar 31 16:54:03 DEBUG[11747] rtp.c: Got RTCP report of 80 bytes
Mar 31 16:54:06 DEBUG[11688] chan_zap.c: Write returned -1 (Resource 
temporarily unavailable) on channel 2
Mar 31 16:54:10 DEBUG[11747] rtp.c: Got RTCP report of 80 bytes
Mar 31 16:54:14 DEBUG[24358] chan_sip.c: = Found Their Call ID: 
ipixsmdwtxqusdu at 192.168.11.79 Their Tag fzxtk Our tag: as5ce7b16c
Mar 31 16:54:14 DEBUG[24358] chan_sip.c: **** Received BYE (8) - Command in 
SIP BYE
Mar 31 16:54:14 DEBUG[11747] channel.c: Didn't get a frame from channel: 
SIP/451-0e31
Mar 31 16:54:14 DEBUG[11747] channel.c: Bridge stops bridging channels 
SIP/451-0e31 and Zap/1-1
Mar 31 16:54:14 DEBUG[11747] channel.c: Hanging up channel 'Zap/1-1'
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: zt_hangup(Zap/1-1)
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: Set option AUDIO MODE, value: ON(1) 
on Zap/1-1
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: Hangup: channel: 1 index = 0, normal 
= 20, callwait = -1, thirdcall = -1
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: Not yet hungup...  Calling hangup 
once with icause, and clearing call
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: disabled echo cancellation on channel 
1
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: Set option TDD MODE, value: OFF(0) on 
Zap/1-1
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: Updated conferencing on 1, with 0 
conference users
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: Set option AUDIO MODE, value: OFF(0) 
on Zap/1-1
Mar 31 16:54:14 DEBUG[11747] chan_zap.c: disabled echo cancellation on channel 
1
Mar 31 16:54:14 VERBOSE[11747] logger.c:     -- Hungup 'Zap/1-1'
Mar 31 16:54:14 DEBUG[11747] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Mar 31 16:54:14 DEBUG[11747] app_macro.c: Spawn extension 
(macro-dialout-trunk,s,14) exited non-zero on 'SIP/451-0e31' in macro 
'dialout-trunk'
Mar 31 16:54:14 DEBUG[11747] pbx.c: Spawn extension (macro-dialout-trunk,s,14) 
exited non-zero on 'SIP/451-0e31'
Mar 31 16:54:14 DEBUG[24349] devicestate.c: Changing state for Zap/1 - state 0 
(Unknown)
Mar 31 16:54:14 DEBUG[11755] app_queue.c: Device 'Zap/1' changed to state 
'0' (Unknown)
Mar 31 16:54:14 DEBUG[11747] cdr_addon_mysql.c: cdr_mysql: inserting a CDR 
record.
Mar 31 16:54:14 DEBUG[11747] cdr_addon_mysql.c: cdr_mysql: SQL command as 
follows: INSERT INTO cdr 
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,bil
lsec,disposition,amaflags,accountcode,uniqueid) 
VALUES ('2006-03-31 
16:53:56','033811234451','033811234451','003381765432','from-internal', 
'SIP/451-0e31','Zap/1-1','Dial','ZAP/g1/3381765432',18,13,'ANSWERED',3,'','11438
16836.643')
Mar 31 16:54:14 DEBUG[11747] channel.c: Hanging up channel 'SIP/451-0e31'
Mar 31 16:54:14 DEBUG[11747] chan_sip.c: Hangup call SIP/451-0e31, SIP callid 
ipixsmdwtxqusdu at 192.168.11.79)
Mar 31 16:54:14 DEBUG[11747] chan_sip.c: update_call_counter(451) - decrement 
call limit counter
Mar 31 16:54:14 DEBUG[11747] chan_sip.c: Updating call counter for incoming 
call
Mar 31 16:54:14 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:54:14 DEBUG[24349] devicestate.c: Changing state for SIP/451 - state 
1 (Not in use)
Mar 31 16:54:14 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:54:14 DEBUG[24349] chan_sip.c: Checking device state for peer 451
Mar 31 16:54:14 DEBUG[11756] app_queue.c: Device 'SIP/451' changed to state 
'1' (Not in use)







More information about the asterisk-users mailing list