Calls were placed via IAX2 from remote server to exten '6101' on Asterisk. Good call: [macro-out-t1nstar] exten => s,1,CallingPres(64) exten => s,2,SetCallerID(1234561234) exten => s,3,Dial(zap/g1/w${ARG1},15) ; Try calling that extension for 15 seconds ... ... *CLI> Sep 15 22:52:34 DEBUG[147465]: chan_iax2.c:811 make_trunk: Made call 1 into trunk call 16384 -- Accepting AUTHENTICATED call from 10.0.40.240, requested format = 2, actual format = 2 -- Executing SetVar("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "ARG1=6101") in new stack -- Executing NoOp("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "20040915-225234 iax2 to out-t1nstar for 6101") in new stack -- Executing DigitTimeout("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "0") in new stack -- Set Digit Timeout to 0 -- Executing Macro("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "out-t1nstar|6101") in new stack -- Executing CallingPres("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "64") in new stack -- Executing SetCallerID("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "1234561234") in new stack -- Executing Dial("IAX2/astpbx-woodbay@astpbx-woodbay/16384", "zap/g1/6101|15") in new stack -- Making new call for cr 32773 > Protocol Discriminator: Q.931 (8) len=40 > Call Ref: len= 2 (reference 5/0x5) (Originator) > Message type: SETUP (5) > [04 03 80 90 a2] > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) > Ext: 1 User information layer 1: u-Law (34) > [18 03 a1 83 81] > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0 > ChanSel: Reserved > Ext: 1 Coding: 0 Number Specified Channel Type: 3 > Ext: 1 Channel: 1 ] > [1e 02 80 83] > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0) > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ] > [6c 0c 21 81 31 32 33 34 35 36 31 32 33 34] > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) > Presentation: Presentation permitted, user number passed network screening (1) '1234561234' ] > [70 05 c1 36 31 30 31] > Called Number (len= 7) [ Ext: 1 TON: Subscriber Number (4) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '6101' ] -- Called g1/6101 Sep 15 22:52:34 DEBUG[344082]: chan_iax2.c:2890 find_tpeer: Created trunk peer for '10.0.40.240:4569' Sep 15 22:52:34 DEBUG[344082]: chan_iax2.c:2912 iax2_trunk_queue: Expanded trunk '10.0.40.240:4569' to 6400 bytes < Protocol Discriminator: Q.931 (8) len=10 < Call Ref: len= 2 (reference 32773/0x8005) (Terminator) < Message type: CALL PROCEEDING (2) < [18 03 a9 83 81] < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 < ChanSel: Reserved < Ext: 1 Coding: 0 Number Specified Channel Type: 3 < Ext: 1 Channel: 1 ] -- Processing IE 24 (cs0, Channel Identification) < Protocol Discriminator: Q.931 (8) len=5 < Call Ref: len= 2 (reference 32773/0x8005) (Terminator) < Message type: ALERTING (1) Sep 15 22:52:34 DEBUG[196620]: chan_zap.c:1211 zt_enable_ec: Enabled echo cancellation on channel 1 -- Zap/1-1 is ringing -------------------------------------------------------------------------------------------------------- Bad call: [macro-out-t1nstar] exten => s,1,CallingPres(64) exten => s,2,SetCallerID(1234561234) exten => s,3,Dial(zap/g1/w${ARG1},15) ; Try calling that extension for 15 seconds ... ... *CLI> Sep 15 22:53:27 DEBUG[147465]: chan_iax2.c:811 make_trunk: Made call 1 into trunk call 16385 -- Accepting AUTHENTICATED call from 10.0.40.240, requested format = 2, actual format = 2 -- Executing SetVar("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "ARG1=6101") in new stack -- Executing NoOp("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "20040915-225327 iax2 to out-t1nstar for 6101") in new stack -- Executing DigitTimeout("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "0") in new stack -- Set Digit Timeout to 0 -- Executing Macro("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "out-t1nstar|6101") in new stack -- Executing CallingPres("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "64") in new stack -- Executing SetCallerID("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "1234561234") in new stack -- Executing Dial("IAX2/astpbx-woodbay@astpbx-woodbay/16385", "zap/g1/w6101|15") in new stack -- Making new call for cr 32774 > Protocol Discriminator: Q.931 (8) len=36 > Call Ref: len= 2 (reference 6/0x6) (Originator) > Message type: SETUP (5) > [04 03 80 90 a2] > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) > Ext: 1 User information layer 1: u-Law (34) > [18 03 a1 83 81] > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0 > ChanSel: Reserved > Ext: 1 Coding: 0 Number Specified Channel Type: 3 > Ext: 1 Channel: 1 ] > [1e 02 80 83] > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0) > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ] > [6c 0c 21 81 00 00 00 00 00 00 00 00 00 00] > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) > Presentation: Presentation permitted, user number passed network screening (1) '' ] > [70 01 c1] > Called Number (len= 3) [ Ext: 1 TON: Subscriber Number (4) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '' ] -- Called g1/w6101 Sep 15 22:53:27 DEBUG[360466]: chan_iax2.c:2890 find_tpeer: Created trunk peer for '10.0.40.240:4569' Sep 15 22:53:27 DEBUG[360466]: chan_iax2.c:2912 iax2_trunk_queue: Expanded trunk '10.0.40.240:4569' to 6400 bytes < Protocol Discriminator: Q.931 (8) len=12 < Call Ref: len= 2 (reference 32774/0x8006) (Terminator) < Message type: STATUS (125) < [08 02 81 e4] < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) < Ext: 1 Cause: Invalid information element contents (100), class = Protocol Error (6) ] < [14 01 06] < Call State (len= 3) [ Ext: 0 Coding: CCITT (ITU) standard (0) Call state: Call Present (6) -- Processing IE 8 (cs0, Cause) -- Processing IE 20 (cs0, Call State) < Protocol Discriminator: Q.931 (8) len=9 < Call Ref: len= 2 (reference 32774/0x8006) (Terminator) < Message type: RELEASE COMPLETE (90) < [08 02 80 95] < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0) < Ext: 1 Cause: Call Rejected (21), class = Normal Event (1) ] -- Processing IE 8 (cs0, Cause) -- Channel 0/1, span 1 got hangup -- Zap/1-1 is circuit-busy Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:2455 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/1-1 Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:1965 zt_hangup: Hangup: channel: 1 index = 0, normal = 22, callwait = -1, thirdcall = -1 Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:2093 zt_hangup: Already hungup... Calling hangup once, and clearing call NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:1243 zt_disable_ec: disabled echo cancellation on channel 1 Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:2367 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/1-1 Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:1186 update_conf: Updated conferencing on 1, with 0 conference users Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:2449 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/1-1 Sep 15 22:53:27 DEBUG[360466]: chan_zap.c:1243 zt_disable_ec: disabled echo cancellation on channel 1 -- Hungup 'Zap/1-1'