Jun 16 12:44:47 DEBUG[31721] chan_sip.c: Setting NAT on RTP to 0
Jun 16 12:44:47 DEBUG[31721] chan_sip.c: Stopping retransmission on 'b3981b1-db164972@10.19.25.16' of Response 101: Match Found
Jun 16 12:44:47 DEBUG[31721] chan_sip.c: Setting NAT on RTP to 0
Jun 16 12:44:47 DEBUG[31721] chan_sip.c: Checking SIP call limits for device 1
Jun 16 12:44:47 DEBUG[31721] chan_sip.c: build_route: Contact hop: gabriel <sip:1@10.19.25.16:5060>
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Macro("SIP/1-580a", "dialout-trunk|2|19789870175||") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "DIAL_TRUNK=2") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "DIAL_NUMBER=19789870175") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "ROUTE_PASSWD=") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "1?noauth") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-dialout-trunk,s,6)
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "GROUP()=OUT_2") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Macro("SIP/1-580a", "user-callerid") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '0'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "0?report") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Not taking any branch
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '0'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "0?start") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Not taking any branch
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "REALCALLERIDNUM=1") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing NoOp("SIP/1-580a", "REALCALLERIDNUM is 1") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "AMPUSER=1") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is 'gabriel'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "AMPUSERCIDNAME=gabriel") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '0'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "0?report") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Not taking any branch
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "CALLERID(all)=gabriel <1>") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '"gabriel" <1>'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing NoOp("SIP/1-580a", "Using CallerID "gabriel" <1>") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Macro("SIP/1-580a", "record-enable|1|OUT") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '0'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "0 > 0?2:4") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-record-enable,s,4)
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing AGI("SIP/1-580a", "recordingcheck|20060616-124447|1150476287.18") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jun 16 12:44:47 VERBOSE[32643] logger.c:   recordingcheck|20060616-124447|1150476287.18: Outbound recording not enabled
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- AGI Script recordingcheck completed, returning 0
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing NoOp("SIP/1-580a", "No recording needed") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Macro("SIP/1-580a", "outbound-callerid|2") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '0'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "0?start") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Not taking any branch
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "REALCALLERIDNUM=1") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing NoOp("SIP/1-580a", "REALCALLERIDNUM is 1") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is ''
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "USEROUTCID=") in new stack
Jun 16 12:44:47 DEBUG[32643] db.c: Unable to find key '1/emergency_cid' in family 'DEVICE'
Jun 16 12:44:47 DEBUG[32643] func_db.c: DB: DEVICE/1/emergency_cid not found in database.
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is ''
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "EMERGENCYCID=") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "TRUNKOUTCID=") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "1?trunkcid") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-outbound-callerid,s,11)
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "1?usercid") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-outbound-callerid,s,13)
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "1?report") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-outbound-callerid,s,15)
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is '"gabriel" <1>'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing NoOp("SIP/1-580a", "CallerID set to "gabriel" <1>") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '1'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "1?nomax") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-dialout-trunk,s,12)
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing AGI("SIP/1-580a", "fixlocalprefix") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jun 16 12:44:47 VERBOSE[32643] logger.c:   fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- AGI Script fixlocalprefix completed, returning 0
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "OUTNUM=19789870175") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Function result is 'IAX2/teliax'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Set("SIP/1-580a", "custom=IAX2/teliax") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Expression result is '0'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing GotoIf("SIP/1-580a", "0?customtrunk") in new stack
Jun 16 12:44:47 DEBUG[32643] pbx.c: Not taking any branch
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Dial("SIP/1-580a", "IAX2/teliax/19789870175|120|r") in new stack
Jun 16 12:44:47 WARNING[32643] chan_iax2.c: No such host: teliax
Jun 16 12:44:47 NOTICE[32643] app_dial.c: Unable to create channel of type 'IAX2' (cause 3 - No route to destination)
Jun 16 12:44:47 VERBOSE[32643] logger.c:   == Everyone is busy/congested at this time (1:0/0/1)
Jun 16 12:44:47 DEBUG[32643] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Goto("SIP/1-580a", "s-CHANUNAVAIL|1") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing NoOp("SIP/1-580a", "Dial failed due to CHANUNAVAIL") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Macro("SIP/1-580a", "outisbusy|") in new stack
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Executing Playback("SIP/1-580a", "all-circuits-busy-now") in new stack
Jun 16 12:44:47 DEBUG[31714] channel.c: Avoiding initial deadlock for 'SIP/1-580a'
Jun 16 12:44:47 DEBUG[31714] channel.c: Avoiding initial deadlock for 'SIP/1-580a'
Jun 16 12:44:47 VERBOSE[32643] logger.c:     -- Playing 'all-circuits-busy-now' (language 'en')
Jun 16 12:44:47 DEBUG[31721] chan_sip.c: Stopping retransmission on 'b3981b1-db164972@10.19.25.16' of Response 102: Match Found
Jun 16 12:44:49 VERBOSE[32643] logger.c:     -- Executing Playback("SIP/1-580a", "pls-try-call-later") in new stack
Jun 16 12:44:49 VERBOSE[32643] logger.c:     -- Playing 'pls-try-call-later' (language 'en')
Jun 16 12:44:51 VERBOSE[32643] logger.c:     -- Executing Macro("SIP/1-580a", "hangupcall") in new stack
Jun 16 12:44:51 VERBOSE[32643] logger.c:     -- Executing ResetCDR("SIP/1-580a", "w") in new stack