Next step in diagnosing a dahdi issue

Asterisk Asterisk Support
1

I was wondering if anyone could point me in the right direction as to diagnosing an issue I’m having with one of my analog trunks.
I have a TDM400 2x2 clone setup which I thought was working correctly.
Now I’m unsure if it ever worked right.
Anyway, in addition to lowest cost routing I have 4 outbound routes setup to specifically force calls through each of my trunks. #1 thru
#4 defines which trunk to use. All but #2 (my second dahdi trunk g1) work fine.
Inward calls to that trunk also work fine which lead me to suspect it isn’t a hardware fault.

I include an asterisk log showing a comparison between a call forced through g1 using #2, which doesn’t work and falls over on the line with the “WARNING”, and a perfectly working call being forced out on g0 using #1. Both destinations are to my cellphone so I have put xxx’s to replace the last 6 digits for privacy.
The warning doesn’t give my uneducated brain much to go on and I was wondering if someone could give me the next step to do as far as diagnosis. i.e How do I diagnose app_dial.c?

Even though I have different network providers on each of the PSTN trunks I have eliminated that as the issue as I physically swapped cables which didn’t cause different routes to fail.

I hope someone can can help by pointing me in the right direction.

Environment
PBX in a Flash Version 1.3 Daemon Status

  • Asterisk * ONLINE * Dahdi * ONLINE * MySQL * ONLINE *
  • SSH * ONLINE * Apache * ONLINE * Iptables * ONLINE *
  • Fail2ban * ONLINE * IP Connect* ONLINE * Ip6tables * OFFLINE *
  • BlueTooth * ONLINE * Hidd * ONLINE * NTPD * ONLINE *
  • Sendmail * ONLINE * Samba * OFFLINE * Webmin * ONLINE *
  • Ethernet0 * ONLINE * Ethernet1 * N/A * Wlan0 * N/A *
  • Running Asterisk Version : Asterisk 1.6.0.9
  • Asterisk Source Version : 1.6.0.1
  • Dahdi Source Version : 2.0.0+2.0.0
  • Libpri Source Version : 1.4.7
  • Addons Source Version : 1.6.0

pbx.local on 10.0.0.6 - eth0
CentOS release 5.3 (Final) :32 Bit Kernel: 2.6.18-92.1.6.el5

Asterisk log as follows:-
Failing with Unavailable message

[2009-11-26 07:53:55] VERBOSE[3117] logger.c: – ast_get_srv: SRV
lookup for ‘_sip._UDP.2talk.co.nz’ mapped to host fep8.2talk.co.nz, port 5060
[2009-11-26 07:54:17] VERBOSE[3117] logger.c: == Using SIP RTP TOS
bits 184
[2009-11-26 07:54:17] VERBOSE[3117] logger.c: == Using SIP RTP CoS
mark 5
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[#2021xxxxxx@from-internal:1] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40muser-callerid,SKIPTTL, [0;37;40m")
in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:1] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mAMPUSER=801 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:2] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?report [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:3] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m1?Set(REALCALLERIDNUM=801)
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:4] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mAMPUSER=801 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:5] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mAMPUSERCIDNAME=LinkSys SIP Phone
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:6] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?report [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:7] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mAMPUSERCID=099742533 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:8] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mCALLERID(all)=“LinkSys SIP Phone”
<099742533> [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:9] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?Set(CHANNEL(language)=)
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:10] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m1?continue [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Goto (macro-user-
callerid,s,19)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-user-callerid:19] [1;36;40mNoOp [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mUsing CallerID “LinkSys SIP Phone”
<099742533> [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[#2021xxxxxx@from-internal:2] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m_NODEST= [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[#2021xxxxxx@from-internal:3] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40mrecord-enable,801,OUT, [0;37;40m")
in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-record-enable:1] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m1?check [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Goto (macro-
record-enable,s,4)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-record-enable:4] [1;36;40mAGI [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mrecordingcheck,
20091126-075417,1259175257.24 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Launched AGI
Script /var/lib/asterisk/agi-bin/recordingcheck
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: recordingcheck,
20091126-075417,1259175257.24: Outbound recording not enabled
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – <SIP/801-
b7c01d00>AGI Script recordingcheck completed, returning 0
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-record-enable:5] [1;36;40mMacroExit [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[#2021xxxxxx@from-internal:4] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40mdialout-trunk,2,021xxxxxx,
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:1] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mDIAL_TRUNK=2 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:2] [1;36;40mGosubIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?sub-pincheck,s,1 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:3] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?disabletrunk,1 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:4] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mDIAL_NUMBER=021xxxxxx [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:5] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mDIAL_TRUNK_OPTIONS=trw [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:6] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mOUTBOUND_GROUP=OUT_2 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:7] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?nomax [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:8] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?chanfull [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:9] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?skipoutcid [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:10] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mDIAL_TRUNK_OPTIONS=tw [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:11] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40moutbound-callerid,2 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:1] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m0?Set(CALLERPRES()
=) [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:2] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m0?Set
(REALCALLERIDNUM=099742533) [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:3] [1;36;40mGotoIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m1?normcid
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Goto (macro-
outbound-callerid,s,6)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:6] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40mUSEROUTCID=“PRIMESOFT”<098208272>
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:7] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40mEMERGENCYCID= [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:8] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40mTRUNKOUTCID=“PRIMESOFT”<
+648208272> [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:9] [1;36;40mGotoIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m1?trunkcid
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Goto (macro-
outbound-callerid,s,12)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:12] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m1?Set(CALLERID(all)
=PRIMESOFT<+648208272>) [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:13] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m1?Set(CALLERID(all)
=PRIMESOFT<098208272>) [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outbound-callerid:14] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m0?Set(CALLERPRES()
=prohib_passed_screen) [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:12] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?AGI(fixlocalprefix) [0;37;40m")
in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:13] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mOUTNUM=021xxxxxx [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:14] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mcustom=DAHDI/g1 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:15] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?Set(DIAL_TRUNK_OPTIONS=M
(setmusic^)tw) [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:16] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40mdialout-trunk-predial-hook,
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk-predial-hook:1] [1;36;40mMacroExit [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:17] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?bypass,1 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:18] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40m0?customtrunk [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:19] [1;36;40mDial [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mDAHDI/g1/021xxxxxx,300,tw [0;37;40m")
in new stack
[2009-11-26 07:54:17] WARNING[6193] app_dial.c: Unable to create channel of type ‘DAHDI’ (cause 0 - Unknown)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: == Everyone is busy/
congested at this time (1:0/0/1)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-dialout-trunk:20] [1;36;40mGoto [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40ms-CHANUNAVAIL,1 [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Goto (macro-
dialout-trunk,s-CHANUNAVAIL,1)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing [s- CHANUNAVAIL@macro-dialout-trunk:1] [1;36;40mGotoIf [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40m1?noreport
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Goto (macro-
dialout-trunk,s-CHANUNAVAIL,3)
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing [s- CHANUNAVAIL@macro-dialout-trunk:3] [1;36;40mNoOp [0;37;40m (" [1;35;40mSIP/801-b7c01d00 [0;37;40m", " [1;35;40mTRUNK Dial failed due to CHANUNAVAIL (hangupcause: 0) - failing through to other trunks
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[#2021xxxxxx@from-internal:5] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c01d00 [0;37;40m", " [1;35;40moutisbusy, [0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – Executing
[s@macro-outisbusy:1] [1;36;40mPlayback [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mall-circuits-busy-now,noanswer
[0;37;40m") in new stack
[2009-11-26 07:54:17] VERBOSE[6193] logger.c: – <SIP/801-
b7c01d00> Playing ‘all-circuits-busy-now.slin’ (language ‘en’)
[2009-11-26 07:54:19] VERBOSE[6193] logger.c: – Executing
[s@macro-outisbusy:2] [1;36;40mPlayback [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mpls-try-call-later,noanswer
[0;37;40m") in new stack
[2009-11-26 07:54:19] VERBOSE[6193] logger.c: – <SIP/801-
b7c01d00> Playing ‘pls-try-call-later.slin’ (language ‘en’)
[2009-11-26 07:54:21] VERBOSE[3117] logger.c: – ast_get_srv: SRV
lookup for ‘_sip._UDP.akl.italk.co.nz’ mapped to host akl1.italk.co.nz, port 5060
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-outisbusy:3] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40mhangupcall [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:1] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m1?skiprg [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Goto (macro-
hangupcall,s,4)
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:4] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m1?skipblkvm [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Goto (macro-
hangupcall,s,7)
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:7] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m1?theend [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Goto (macro-
hangupcall,s,9)
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:9] [1;36;40mHangup [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on ‘SIP/801-b7c01d00’ in macro ‘hangupcall’
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: == Spawn extension
(macro-outisbusy, s, 3) exited non-zero on ‘SIP/801-b7c01d00’ in macro ‘outisbusy’
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: == Spawn extension
(from-internal, #2021xxxxxx, 5) exited non-zero on ‘SIP/801-b7c01d00’
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing [h@from-
internal:1] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/801-b7c01d00
[0;37;40m", " [1;35;40mhangupcall [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:1] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m1?skiprg [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Goto (macro-
hangupcall,s,4)
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:4] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m1?skipblkvm [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Goto (macro-
hangupcall,s,7)
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:7] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m1?theend [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Goto (macro-
hangupcall,s,9)
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: – Executing
[s@macro-hangupcall:9] [1;36;40mHangup [0;37;40m(" [1;35;40mSIP/801-
b7c01d00 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on ‘SIP/801-b7c01d00’ in macro ‘hangupcall’
[2009-11-26 07:54:21] VERBOSE[6193] logger.c: == Spawn extension
(from-internal, h, 1) exited non-zero on ‘SIP/801-b7c01d00’

Working properly

[2009-11-26 07:54:36] VERBOSE[3117] logger.c: == Using SIP RTP TOS
bits 184
[2009-11-26 07:54:36] VERBOSE[3117] logger.c: == Using SIP RTP CoS
mark 5
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[#1021xxxxxx@from-internal:1] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40muser-callerid,SKIPTTL, [0;37;40m")
in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:1] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mAMPUSER=801 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:2] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?report [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:3] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m1?Set(REALCALLERIDNUM=801)
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:4] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mAMPUSER=801 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:5] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mAMPUSERCIDNAME=LinkSys SIP Phone
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:6] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?report [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:7] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mAMPUSERCID=099742533 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:8] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mCALLERID(all)=“LinkSys SIP Phone”
<099742533> [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:9] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?Set(CHANNEL(language)=)
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:10] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m1?continue [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Goto (macro-user-
callerid,s,19)
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-user-callerid:19] [1;36;40mNoOp [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mUsing CallerID “LinkSys SIP Phone”
<099742533> [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[#1021xxxxxx@from-internal:2] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m_NODEST= [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[#1021xxxxxx@from-internal:3] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40mrecord-enable,801,OUT, [0;37;40m")
in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-record-enable:1] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m1?check [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Goto (macro-
record-enable,s,4)
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-record-enable:4] [1;36;40mAGI [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mrecordingcheck,
20091126-075436,1259175276.25 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Launched AGI
Script /var/lib/asterisk/agi-bin/recordingcheck
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: recordingcheck,
20091126-075436,1259175276.25: Outbound recording not enabled
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – <SIP/801-
b7c03390>AGI Script recordingcheck completed, returning 0
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-record-enable:5] [1;36;40mMacroExit [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[#1021xxxxxx@from-internal:4] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40mdialout-trunk,1,021xxxxxx,
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:1] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mDIAL_TRUNK=1 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:2] [1;36;40mGosubIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?sub-pincheck,s,1 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:3] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?disabletrunk,1 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:4] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mDIAL_NUMBER=021xxxxxx [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:5] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mDIAL_TRUNK_OPTIONS=trw [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:6] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mOUTBOUND_GROUP=OUT_1 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:7] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?nomax [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:8] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?chanfull [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:9] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?skipoutcid [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:10] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mDIAL_TRUNK_OPTIONS=tw [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:11] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40moutbound-callerid,1 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:1] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m0?Set(CALLERPRES()
=) [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:2] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m0?Set
(REALCALLERIDNUM=099742533) [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:3] [1;36;40mGotoIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m1?normcid
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Goto (macro-
outbound-callerid,s,6)
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:6] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40mUSEROUTCID=“PRIMESOFT”<098208272>
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:7] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40mEMERGENCYCID= [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:8] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40mTRUNKOUTCID=“PRIMESOFT”<
+648208272> [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:9] [1;36;40mGotoIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m1?trunkcid
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Goto (macro-
outbound-callerid,s,12)
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:12] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m1?Set(CALLERID(all)
=PRIMESOFT<+648208272>) [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:13] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m1?Set(CALLERID(all)
=PRIMESOFT<098208272>) [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-outbound-callerid:14] [1;36;40mExecIf [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m0?Set(CALLERPRES()
=prohib_passed_screen) [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:12] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?AGI(fixlocalprefix) [0;37;40m")
in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:13] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mOUTNUM=021xxxxxx [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:14] [1;36;40mSet [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mcustom=DAHDI/g0 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:15] [1;36;40mExecIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?Set(DIAL_TRUNK_OPTIONS=M
(setmusic^)tw) [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:16] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40mdialout-trunk-predial-hook,
[0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk-predial-hook:1] [1;36;40mMacroExit [0;37;40m (" [1;35;40mSIP/801-b7c03390 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:17] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?bypass,1 [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:18] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/
801-b7c03390 [0;37;40m", " [1;35;40m0?customtrunk [0;37;40m") in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Executing
[s@macro-dialout-trunk:19] [1;36;40mDial [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mDAHDI/g0/021xxxxxx,300,tw [0;37;40m")
in new stack
[2009-11-26 07:54:36] VERBOSE[6196] logger.c: – Called
g0/021xxxxxx
[2009-11-26 07:54:41] VERBOSE[6196] logger.c: – DAHDI/2-1
answered SIP/801-b7c03390
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Executing
[h@macro-dialout-trunk:1] [1;36;40mMacro [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40mhangupcall, [0;37;40m") in new stack
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Executing
[s@macro-hangupcall:1] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40m1?skiprg [0;37;40m") in new stack
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Goto (macro-
hangupcall,s,4)
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Executing
[s@macro-hangupcall:4] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40m1?skipblkvm [0;37;40m") in new stack
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Goto (macro-
hangupcall,s,7)
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Executing
[s@macro-hangupcall:7] [1;36;40mGotoIf [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40m1?theend [0;37;40m") in new stack
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Goto (macro-
hangupcall,s,9)
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Executing
[s@macro-hangupcall:9] [1;36;40mHangup [0;37;40m(" [1;35;40mSIP/801-
b7c03390 [0;37;40m", " [1;35;40m [0;37;40m") in new stack
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on ‘SIP/801-b7c03390’ in macro ‘hangupcall’
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: == Spawn extension
(macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/801-b7c03390’
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: – Hungup ‘DAHDI/
2-1’
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: == Spawn extension
(macro-dialout-trunk, s, 19) exited non-zero on ‘SIP/801-b7c03390’ in macro ‘dialout-trunk’
[2009-11-26 07:54:52] VERBOSE[6196] logger.c: == Spawn extension
(from-internal, #1021xxxxxx, 4) exited non-zero on ‘SIP/801-b7c03390’

Thanks in advance Greg