[Asterisk-Users] Outbound problem sip chanel

Wed Feb 22 12:56:15 MST 2006

I setup my aah box with a sip trunk at irisxa.iristel.net

Incaming it is ok but when I try to dial 8 and the nr where I want to call I
get all line is busy.

In my log I have these:

Feb 22 14:33:04 DEBUG[3156] manager.c: Manager received command 'Command'
Feb 22 14:33:04 DEBUG[3156] manager.c: Manager received command 'Command'
Feb 22 14:33:19 VERBOSE[2721] logger.c: -- Accepting AUTHENTICATED call from
192.168.50.145:
> requested format = g729,
> requested prefs = (),
> actual format = ulaw,
> host prefs = (ulaw|alaw|gsm),
> priority = mine
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing Macro("IAX2/206-3",
"dialout-trunk|2|5149635279|") in new stack
Feb 22 14:33:19 DEBUG[3239] pbx.c: Expression result is '1'
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing GotoIf("IAX2/206-3",
"1?3:2)") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Goto (macro-dialout-trunk,s,3)
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing Macro("IAX2/206-3",
"user-callerid") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing DBget("IAX2/206-3",
"AMPUSER=DEVICE/206/user") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- DBget: varname=AMPUSER,
family=DEVICE, key=206/user
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- DBget: set variable AMPUSER to
206
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing DBget("IAX2/206-3",
"AMPUSERCIDNAME=AMPUSER/206/cidname") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- DBget: varname=AMPUSERCIDNAME,
family=AMPUSER, key=206/cidname
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- DBget: set variable
AMPUSERCIDNAME to Cristian Paun
Feb 22 14:33:19 DEBUG[3239] pbx.c: Expression result is '0'
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing GotoIf("IAX2/206-3",
"0?5") in new stack
Feb 22 14:33:19 DEBUG[3239] pbx.c: Not taking any branch
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing
SetCallerID("IAX2/206-3", ""Cristian Paun" <206>") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing NoOp("IAX2/206-3",
"Using CallerID "Cristian Paun" <206>") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing Macro("IAX2/206-3",
"record-enable|206|OUT") in new stack
Feb 22 14:33:19 DEBUG[3239] pbx.c: Function result is '0'
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing GotoIf("IAX2/206-3", "0
> 0?2:4") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Goto (macro-record-enable,s,4)
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing AGI("IAX2/206-3",
"recordingcheck|20060222-143319|1140636799.15") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Launched AGI Script
/var/lib/asterisk/agi-bin/recordingcheck
Feb 22 14:33:19 VERBOSE[3239] logger.c:
recordingcheck|20060222-143319|1140636799.15: Outbound recording not enabled
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- AGI Script recordingcheck
completed, returning 0
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing NoOp("IAX2/206-3", "No
recording needed") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing Macro("IAX2/206-3",
"outbound-callerid|2") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing DBget("IAX2/206-3",
"USEROUTCID=AMPUSER/206/outboundcid") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- DBget: varname=USEROUTCID,
family=AMPUSER, key=206/outboundcid
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- DBget: set variable USEROUTCID to

Feb 22 14:33:19 DEBUG[3239] pbx.c: Expression result is '0'
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing GotoIf("IAX2/206-3",
"0?4") in new stack
Feb 22 14:33:19 DEBUG[3239] pbx.c: Not taking any branch
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing
SetCallerID("IAX2/206-3", "K2 Systems Inc.") in new stack
Feb 22 14:33:19 DEBUG[3239] pbx.c: Expression result is '1'
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing GotoIf("IAX2/206-3",
"1?6") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Goto
(macro-outbound-callerid,s,6)
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing NoOp("IAX2/206-3",
"CallerID set to K2 Systems Inc.") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing SetGroup("IAX2/206-3",
"OUT_2") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing
CheckGroup("IAX2/206-3", "2") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing SetVar("IAX2/206-3",
"DIAL_NUMBER=5149635279") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing SetVar("IAX2/206-3",
"DIAL_TRUNK=2") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Executing AGI("IAX2/206-3",
"fixlocalprefix") in new stack
Feb 22 14:33:19 VERBOSE[3239] logger.c: -- Launched AGI Script
/var/lib/asterisk/agi-bin/fixlocalprefix
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- AGI Script fixlocalprefix
completed, returning 0
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing SetVar("IAX2/206-3",
"OUTNUM=85149635279") in new stack
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing Cut("IAX2/206-3",
"custom=OUT_2|:|1") in new stack
Feb 22 14:33:20 WARNING[3239] ast_expr2.y: non-numeric argument
Feb 22 14:33:20 DEBUG[3239] pbx.c: Expression result is '0'
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing GotoIf("IAX2/206-3",
"0?16") in new stack
Feb 22 14:33:20 DEBUG[3239] pbx.c: Not taking any branch
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing Dial("IAX2/206-3",
"SIP/iristel/85149635279") in new stack
Feb 22 14:33:20 DEBUG[3239] chan_sip.c: Setting NAT on RTP to 524288
Feb 22 14:33:20 DEBUG[3239] chan_sip.c: Outgoing Call for 85149635279
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Called iristel/85149635279
Feb 22 14:33:20 DEBUG[2722] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet) on
'4360dc7425a0f29412d564a1595abce2 at 192.168.50.160' Request 102: Found
Feb 22 14:33:20 DEBUG[2722] chan_sip.c: Acked pending invite 102
Feb 22 14:33:20 DEBUG[2722] chan_sip.c: Stopping retransmission on
'4360dc7425a0f29412d564a1595abce2 at 192.168.50.160' of Request 102: Match
Found
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- SIP/iristel-3a3f is circuit-busy
Feb 22 14:33:20 DEBUG[3239] chan_sip.c: update_call_counter(85149635279) -
decrement call limit counter
Feb 22 14:33:20 VERBOSE[3239] logger.c: == Everyone is busy/congested at
this time (1:0/1/0)
Feb 22 14:33:20 DEBUG[3239] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing Goto("IAX2/206-3",
"s-CONGESTION|1") in new stack
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Goto
(macro-dialout-trunk,s-CONGESTION,1)
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing NoOp("IAX2/206-3",
"Dial failed due to CONGESTION") in new stack
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing Macro("IAX2/206-3",
"outisbusy") in new stack
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Executing Playback("IAX2/206-3",
"all-circuits-busy-now") in new stack
Feb 22 14:33:20 DEBUG[3239] channel.c: Scheduling timer at 160 sample
intervals
Feb 22 14:33:20 VERBOSE[3239] logger.c: -- Playing 'all-circuits-busy-now'
(language 'en')
Feb 22 14:33:20 DEBUG[2721] chan_iax2.c: Ooh, voice format changed to 4
Feb 22 14:33:20 NOTICE[3239] chan_iax2.c: I should never be called!
Feb 22 14:33:22 DEBUG[3239] channel.c: Scheduling timer at 0 sample
intervals
Feb 22 14:33:22 DEBUG[3239] channel.c: Scheduling timer at 0 sample
intervals
Feb 22 14:33:22 VERBOSE[3239] logger.c: -- Executing Playback("IAX2/206-3",
"pls-try-call-later") in new stack
Feb 22 14:33:22 DEBUG[3239] channel.c: Scheduling timer at 160 sample
intervals
Feb 22 14:33:22 VERBOSE[3239] logger.c: -- Playing 'pls-try-call-later'
(language 'en')
Feb 22 14:33:24 DEBUG[3239] channel.c: Scheduling timer at 0 sample
intervals
Feb 22 14:33:24 DEBUG[3239] channel.c: Scheduling timer at 0 sample
intervals
Feb 22 14:33:24 VERBOSE[3239] logger.c: -- Executing Macro("IAX2/206-3",
"hangupcall") in new stack
Feb 22 14:33:24 VERBOSE[3239] logger.c: -- Executing ResetCDR("IAX2/206-3",
"w") in new stack
Feb 22 14:33:24 DEBUG[3239] cdr_addon_mysql.c: cdr_mysql: inserting a CDR
record.
Feb 22 14:33:24 DEBUG[3239] 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,uniqueid) VALUES ('2006-02-22
14:33:19','\"K2 Systems Inc.\" <206>','206','85149635279','from-internal',
'IAX2/206-3','SIP/iristel-3a3f','ResetCDR','w',5,4,'FAILED',3,'','1140636799
.15')
Feb 22 14:33:24 VERBOSE[3239] logger.c: -- Executing NoCDR("IAX2/206-3", "")
in new stack
Feb 22 14:33:24 WARNING[3239] cdr.c: CDR on channel 'IAX2/206-3' not posted
Feb 22 14:33:24 WARNING[3239] cdr.c: CDR on channel 'IAX2/206-3' lacks end
Feb 22 14:33:24 VERBOSE[3239] logger.c: -- Executing Wait("IAX2/206-3", "5")
in new stack
Feb 22 14:33:24 VERBOSE[3239] logger.c: -- Executing Wait("IAX2/206-3", "5")
in new stack

Can anyone translate these log message and let me know what I did wrongÉ

Thanks

Cristian

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20060222/1c7f19cc/attachment.htm