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@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@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@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@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@192.168.11.79' of Response 830: Match Found
Mar 31 16:53:56 DEBUG[24358] chan_sip.c: = Found Their Call ID:
ipixsmdwtxqusdu@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@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@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@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@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@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)
Are you allowed to set your callerid with your provider? Do you have a PRI or is it a POTS line? On 3/31/06, Sebastian Reitenbach <itlistuser@rapideye.de> wrote:> 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@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@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@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@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@192.168.11.79' of Response 830: Match Found > Mar 31 16:53:56 DEBUG[24358] chan_sip.c: = Found Their Call ID: > ipixsmdwtxqusdu@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@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@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@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@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@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) > > > > > _______________________________________________ > --Bandwidth and Colocation provided by Easynews.com -- > > Asterisk-Users mailing list > To UNSUBSCRIBE or update options visit: > http://lists.digium.com/mailman/listinfo/asterisk-users >-- Tom Vile Baldwin Technology Solutions, Inc Consulting - Web Design - VoIP Telephony www.baldwintechsolutions.com Phone: 518-631-2855 x205 Fax: 518-631-2856
Hi Sebastian -> 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.There's no greeat trick to it. Your provider just has to support it. My PRI provider here in the US allows me to set my CID, but it has to fall exactly within certain parameters: it has to be 9 digits and within my DID block. If it doesn't fit those parameters, the provider will just blank out the caller ID. As I understand it, the policies of various providers vary greatly, and some providers won't allow you to set CID at all. - Noah
Hi, it is a PRI. With the old telephone system the extensions were transmitted. Only replaced the telephone systems and whatever I do, only the central dial-in number is transmitted. kind regards Sebastian "Tom Vile" <tvile@baldwintechsolutions.com> wrote:> Are you allowed to set your callerid with your provider? Do you have > a PRI or is it a POTS line? > > On 3/31/06, Sebastian Reitenbach <itlistuser@rapideye.de> wrote: > > Hi, > > > > sorry for the long debug output below. I configured Asterisk with AMP tosend> > the whole number including the extensions of the callers to the calledparty.> > 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, butthe> > caller will only see a 0338189040 instead of my extension. > > > > any hint to what could be wrong is greatly appreciated. > >
Hi, it is a PRI. With the old telephone system the extensions were transmitted. Only replaced the telephone systems and whatever I do, only the central dial-in number is transmitted. kind regards Sebastian "Tom Vile" <tvile@baldwintechsolutions.com> wrote:> Are you allowed to set your callerid with your provider? Do you have > a PRI or is it a POTS line? > > On 3/31/06, Sebastian Reitenbach <itlistuser@rapideye.de> wrote: > > Hi, > > > > sorry for the long debug output below. I configured Asterisk with AMP tosend> > the whole number including the extensions of the callers to the calledparty.> > 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, butthe> > caller will only see a 0338112340 instead of my extension. > > > > any hint to what could be wrong is greatly appreciated. > >
Reasonably Related Threads
- asterisk doesn't wait for whole extension
- problem with outgoing calls Unable to create channel of type 'ZAP' (cause 34 - Circuit/channel congestion)
- Outbound problem sip chanel
- problem with outgoing calls Unable to createchannel of type 'ZAP' (cause 34 - Circuit/channel congestion)
- How to tell if a dropped call is my fault