Feb 16 11:25:20 DEBUG[5564] chan_zap.c: Found empty available channel 0/29
Feb 16 11:25:20 VERBOSE[5564] logger.c:     -- Accepting call from '0293353047' to '0409227633' on channel 0/29, span 7
Feb 16 11:25:20 DEBUG[5564] chan_zap.c: Enabled echo cancellation on channel 215
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing Macro("Zap/215-1", "call-ext-sjh|0409227633|0293353047") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing NoOp("Zap/215-1", "Number:0409227633") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing NoOp("Zap/215-1", "CallerID:0293353047") in new stack
Feb 16 11:25:20 DEBUG[31439] pbx.c: Function result is '10'
Feb 16 11:25:20 DEBUG[31439] pbx.c: Expression result is '1'
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing GotoIf("Zap/215-1", "1?20") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Goto (macro-call-ext-sjh,s,20)
Feb 16 11:25:20 DEBUG[31439] pbx.c: Expression result is '1'
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing GotoIf("Zap/215-1", "1?100") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Goto (macro-call-ext-sjh,s,100)
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing AGI("Zap/215-1", "check-callerid.pl|0293353047|0409227633") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/check-callerid.pl
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- AGI Script check-callerid.pl completed, returning 0
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing ChanIsAvail("Zap/215-1", "Zap/g6&Zap/g4") in new stack
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/157-1
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: Hangup: channel: 157 index = 0, normal = 166, callwait = -1, thirdcall = -1
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: disabled echo cancellation on channel 157
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/157-1
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: Updated conferencing on 157, with 0 conference users
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/157-1
Feb 16 11:25:20 DEBUG[31439] chan_zap.c: disabled echo cancellation on channel 157
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Hungup 'Zap/157-1'
Feb 16 11:25:20 DEBUG[4977] channel.c: Avoiding initial deadlock for 'Zap/157-1'
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing Cut("Zap/215-1", "C=AVAILCHAN||1") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing NoOp("Zap/215-1", "Zap/157/0409227633") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing Macro("Zap/215-1", "call-ext-XXX|Zap/157/0409227633") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing NoOp("Zap/215-1", "Number:Zap/157/0409227633") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing NoOp("Zap/215-1", "CallerID:0293353047") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Executing Dial("Zap/215-1", "Zap/157/0409227633") in new stack
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Feb 16 11:25:20 VERBOSE[31439] logger.c:     -- Called 157/0409227633
Feb 16 11:25:20 DEBUG[5487] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/3 span 6
Feb 16 11:25:22 DEBUG[5482] chan_zap.c: Found empty available channel 0/29
Feb 16 11:25:22 VERBOSE[5482] logger.c:     -- Accepting call from '0282262301' to '0410471000' on channel 0/29, span 1
Feb 16 11:25:22 DEBUG[5482] chan_zap.c: Enabled echo cancellation on channel 29
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing Macro("Zap/29-1", "call-ext-sjh|0410471000|0282262301") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing NoOp("Zap/29-1", "Number:0410471000") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing NoOp("Zap/29-1", "CallerID:0282262301") in new stack
Feb 16 11:25:22 DEBUG[31446] pbx.c: Function result is '10'
Feb 16 11:25:22 DEBUG[31446] pbx.c: Expression result is '1'
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing GotoIf("Zap/29-1", "1?20") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Goto (macro-call-ext-sjh,s,20)
Feb 16 11:25:22 DEBUG[31446] pbx.c: Expression result is '1'
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing GotoIf("Zap/29-1", "1?100") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Goto (macro-call-ext-sjh,s,100)
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing AGI("Zap/29-1", "check-callerid.pl|0282262301|0410471000") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/check-callerid.pl
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- AGI Script check-callerid.pl completed, returning 0
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing ChanIsAvail("Zap/29-1", "Zap/g6&Zap/g4") in new stack
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: Already have a dsp on Zap/158-1?
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/158-1
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: Hangup: channel: 158 index = 0, normal = 167, callwait = -1, thirdcall = -1
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: disabled echo cancellation on channel 158
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/158-1
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: Updated conferencing on 158, with 0 conference users
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/158-1
Feb 16 11:25:22 DEBUG[31446] chan_zap.c: disabled echo cancellation on channel 158
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Hungup 'Zap/158-1'
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing Cut("Zap/29-1", "C=AVAILCHAN||1") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing NoOp("Zap/29-1", "Zap/158/0410471000") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing Macro("Zap/29-1", "call-ext-XXX|Zap/158/0410471000") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing NoOp("Zap/29-1", "Number:Zap/158/0410471000") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing NoOp("Zap/29-1", "CallerID:0282262301") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Executing Dial("Zap/29-1", "Zap/158/0410471000") in new stack
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Feb 16 11:25:22 VERBOSE[31446] logger.c:     -- Called 158/0410471000
Feb 16 11:25:22 DEBUG[5487] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 6
Feb 16 11:25:22 VERBOSE[31439] logger.c:     -- Zap/157-1 is proceeding passing it to Zap/215-1
Feb 16 11:25:22 DEBUG[31439] chan_zap.c: Requested indication 15 on channel Zap/215-1
Feb 16 11:25:22 DEBUG[31439] chan_zap.c: Received AST_CONTROL_PROCEEDING on Zap/215-1
Feb 16 11:25:26 VERBOSE[5487] logger.c:     -- Moving call from channel 158 to channel 157
Feb 16 11:25:26 WARNING[5487] chan_zap.c: Can't fix up channel from 158 to 157 because 157 is already in use
Feb 16 11:25:26 WARNING[5487] chan_zap.c: Ringing requested on channel 0/2 not in use on span 6
Feb 16 11:25:27 VERBOSE[5487] logger.c:     -- Moving call from channel 157 to channel 158
Feb 16 11:25:27 WARNING[5487] chan_zap.c: Can't fix up channel from 157 to 158 because 158 is already in use
Feb 16 11:25:27 WARNING[5487] chan_zap.c: Ringing requested on channel 0/3 not in use on span 6
Feb 16 11:25:31 DEBUG[5354] chan_sip.c: Stopping retransmission on '6ffde15400d4746d1bded2094aa79db4@202.83.176.38' of Request 102: Match Found
Feb 16 11:25:31 DEBUG[5354] chan_sip.c: Stopping retransmission on '6af219202fc3d292449ba1b01b13fb8d@202.83.176.38' of Request 102: Match Found
Feb 16 11:25:31 DEBUG[30832] channel.c: Didn't get a frame from channel: SIP/lvl09.9wentworth.com.au-eb62
Feb 16 11:25:31 DEBUG[30832] channel.c: Bridge stops bridging channels Zap/173-1 and SIP/lvl09.9wentworth.com.au-eb62
Feb 16 11:25:31 DEBUG[30832] chan_sip.c: update_call_counter(9200) - decrement call limit counter
Feb 16 11:25:31 DEBUG[30832] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Feb 16 11:25:31 VERBOSE[30832] logger.c:   == Spawn extension (macro-call-int-sip, s, 2) exited non-zero on 'Zap/173-1' in macro 'call-int-sip'
Feb 16 11:25:31 VERBOSE[30832] logger.c:   == Spawn extension (SJH-AAPT2, 88159200, 1) exited non-zero on 'Zap/173-1'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '0282243028'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '0282243028'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '88159200'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'SJH-AAPT2'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'Zap/173-1'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'SIP/lvl09.9wentworth.com.au-eb62'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'Dial'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'SIP/9200@lvl09.9wentworth.com.au|45|r'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '2006-02-16 11:21:10'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '2006-02-16 11:21:12'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '2006-02-16 11:25:31'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '261'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '259'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'ANSWERED'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is 'DOCUMENTATION'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '(null)'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '1140049270.12537'
Feb 16 11:25:31 DEBUG[30832] pbx.c: Function result is '(null)'
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/173-1
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: Hangup: channel: 173 index = 0, normal = 181, callwait = -1, thirdcall = -1
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: disabled echo cancellation on channel 173
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/173-1
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: Updated conferencing on 173, with 0 conference users
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/173-1
Feb 16 11:25:31 DEBUG[30832] chan_zap.c: disabled echo cancellation on channel 173
Feb 16 11:25:31 VERBOSE[30832] logger.c:     -- Hungup 'Zap/173-1'
Feb 16 11:25:32 VERBOSE[5487] logger.c:     -- Moving call from channel 158 to channel 157
Feb 16 11:25:32 WARNING[5487] chan_zap.c: Can't fix up channel from 158 to 157 because 157 is already in use
Feb 16 11:25:32 WARNING[5487] chan_zap.c: Answer requested on channel 0/2 not in use on span 6
Feb 16 11:25:33 VERBOSE[5487] logger.c:     -- Moving call from channel 157 to channel 158
Feb 16 11:25:33 WARNING[5487] chan_zap.c: Can't fix up channel from 157 to 158 because 158 is already in use
Feb 16 11:25:33 WARNING[5487] chan_zap.c: Answer requested on channel 0/3 not in use on span 6
Feb 16 11:25:33 DEBUG[30849] channel.c: Didn't get a frame from channel: SIP/202.83.178.2-b7729bd8
Feb 16 11:25:33 DEBUG[30849] channel.c: Bridge stops bridging channels SIP/202.83.178.2-b7729bd8 and Zap/6:168-1
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/6:168-1
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: Hangup: channel: 168 index = 0, normal = 177, callwait = -1, thirdcall = -1
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: Already hungup...  Calling hangup once, and clearing call
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: disabled echo cancellation on channel 168
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/6:168-1
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: Updated conferencing on 168, with 0 conference users
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/6:168-1
Feb 16 11:25:33 DEBUG[30849] chan_zap.c: disabled echo cancellation on channel 168
Feb 16 11:25:33 VERBOSE[30849] logger.c:     -- Hungup 'Zap/6:168-1'
Feb 16 11:25:33 DEBUG[30849] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Feb 16 11:25:33 VERBOSE[30849] logger.c:   == Spawn extension (macro-call-ext-XXX, s, 3) exited non-zero on 'SIP/202.83.178.2-b7729bd8' in macro 'call-ext-XXX'
Feb 16 11:25:33 VERBOSE[30849] logger.c:   == Spawn extension (macro-call-ext-sjh, s, 104) exited non-zero on 'SIP/202.83.178.2-b7729bd8' in macro 'call-ext-sjh'
Feb 16 11:25:33 VERBOSE[30849] logger.c:   == Spawn extension (barnet-from-ccm, 99250590, 1) exited non-zero on 'SIP/202.83.178.2-b7729bd8'
Feb 16 11:25:33 VERBOSE[30849] logger.c:     -- Executing NoOp("SIP/202.83.178.2-b7729bd8", "BYE") in new stack
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '"Margaret Hall" <0288159228>'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '0288159228'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '99250590'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'barnet-from-ccm'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'SIP/202.83.178.2-b7729bd8'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'Zap/6:168-1'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'NoOp'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'BYE'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '2006-02-16 11:21:12'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '2006-02-16 11:21:15'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '2006-02-16 11:25:33'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '261'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '258'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'ANSWERED'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is 'DOCUMENTATION'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '(null)'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '1140049272.12542'
Feb 16 11:25:33 DEBUG[30849] pbx.c: Function result is '(null)'
Feb 16 11:25:33 DEBUG[30849] chan_sip.c: update_call_counter() - decrement call limit counter
Feb 16 11:25:33 WARNING[5487] chan_zap.c: Call specified, but not found?
Feb 16 11:25:33 WARNING[5487] chan_zap.c: Hangup on bad channel 0/13 on span 6
Feb 16 11:25:33 DEBUG[5487] chan_zap.c: Echo cancellation already on
Feb 16 11:25:33 VERBOSE[31421] logger.c:     -- Zap/6:160-1 answered SIP/202.83.178.2-b771ec48
Feb 16 11:25:33 DEBUG[4977] channel.c: Avoiding initial deadlock for 'SIP/202.83.178.2-b771ec48'
Feb 16 11:25:33 DEBUG[5354] chan_sip.c: Stopping retransmission on 'ac99e280-1db1bc85-6609-2b253ca@202.83.178.2' of Response 101: Match Found
Feb 16 11:25:37 WARNING[5487] chan_zap.c: Call specified, but not found?
Feb 16 11:25:37 WARNING[5487] chan_zap.c: Hangup on bad channel 0/13 on span 6
Feb 16 11:25:38 VERBOSE[5482] logger.c:     -- Channel 0/29, span 1 got hangup request
Feb 16 11:25:38 DEBUG[31446] app_dial.c: Unable to forward frame
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/158-1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Hangup: channel: 158 index = 0, normal = 167, callwait = -1, thirdcall = -1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: disabled echo cancellation on channel 158
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/158-1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Updated conferencing on 158, with 0 conference users
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/158-1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: disabled echo cancellation on channel 158
Feb 16 11:25:38 VERBOSE[31446] logger.c:     -- Hungup 'Zap/158-1'
Feb 16 11:25:38 DEBUG[31446] app_dial.c: Exiting with DIALSTATUS=CANCEL.
Feb 16 11:25:38 VERBOSE[31446] logger.c:   == Spawn extension (macro-call-ext-XXX, s, 3) exited non-zero on 'Zap/29-1' in macro 'call-ext-XXX'
Feb 16 11:25:38 VERBOSE[31446] logger.c:   == Spawn extension (macro-call-ext-sjh, s, 104) exited non-zero on 'Zap/29-1' in macro 'call-ext-sjh'
Feb 16 11:25:38 VERBOSE[31446] logger.c:   == Spawn extension (SJH-A44001, 0410471000, 1) exited non-zero on 'Zap/29-1'
Feb 16 11:25:38 VERBOSE[31446] logger.c:     -- Executing NoOp("Zap/29-1", "BYE") in new stack
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '0282262301'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '0282262301'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '0410471000'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'SJH-A44001'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'Zap/29-1'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'Zap/158-1'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'NoOp'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'BYE'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '2006-02-16 11:25:22'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '(null)'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '2006-02-16 11:25:38'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '16'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '0'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'NO ANSWER'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is 'DOCUMENTATION'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '(null)'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '1140049522.12626'
Feb 16 11:25:38 DEBUG[31446] pbx.c: Function result is '(null)'
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/29-1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Hangup: channel: 29 index = 0, normal = 42, callwait = -1, thirdcall = -1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: disabled echo cancellation on channel 29
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/29-1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Updated conferencing on 29, with 0 conference users
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/29-1
Feb 16 11:25:38 DEBUG[31446] chan_zap.c: disabled echo cancellation on channel 29
Feb 16 11:25:38 VERBOSE[31446] logger.c:     -- Hungup 'Zap/29-1'
Feb 16 11:25:38 VERBOSE[5487] logger.c:     -- Moving call from channel 158 to channel 157
Feb 16 11:25:38 WARNING[5487] chan_zap.c: Can't fix up channel from 158 to 157 because 157 is already in use
Feb 16 11:25:38 WARNING[5487] chan_zap.c: Hangup on bad channel 0/2 on span 6
Feb 16 11:25:41 VERBOSE[5564] logger.c:     -- Channel 0/29, span 7 got hangup request
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/157-1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Hangup: channel: 157 index = 0, normal = 166, callwait = -1, thirdcall = -1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: disabled echo cancellation on channel 157
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/157-1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Updated conferencing on 157, with 0 conference users
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/157-1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: disabled echo cancellation on channel 157
Feb 16 11:25:41 VERBOSE[31439] logger.c:     -- Hungup 'Zap/157-1'
Feb 16 11:25:41 DEBUG[31439] app_dial.c: Exiting with DIALSTATUS=CANCEL.
Feb 16 11:25:41 VERBOSE[31439] logger.c:   == Spawn extension (macro-call-ext-XXX, s, 3) exited non-zero on 'Zap/215-1' in macro 'call-ext-XXX'
Feb 16 11:25:41 VERBOSE[31439] logger.c:   == Spawn extension (macro-call-ext-sjh, s, 104) exited non-zero on 'Zap/215-1' in macro 'call-ext-sjh'
Feb 16 11:25:41 VERBOSE[31439] logger.c:   == Spawn extension (SJH-A44002, 0409227633, 1) exited non-zero on 'Zap/215-1'
Feb 16 11:25:41 VERBOSE[31439] logger.c:     -- Executing NoOp("Zap/215-1", "BYE") in new stack
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '0293353047'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '0293353047'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '0409227633'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'SJH-A44002'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'Zap/215-1'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'Zap/157-1'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'NoOp'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'BYE'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '2006-02-16 11:25:20'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '(null)'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '2006-02-16 11:25:41'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '21'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '0'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'NO ANSWER'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is 'DOCUMENTATION'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '(null)'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '1140049520.12623'
Feb 16 11:25:41 DEBUG[31439] pbx.c: Function result is '(null)'
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/215-1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Hangup: channel: 215 index = 0, normal = 222, callwait = -1, thirdcall = -1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: disabled echo cancellation on channel 215
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/215-1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Updated conferencing on 215, with 0 conference users
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/215-1
Feb 16 11:25:41 DEBUG[31439] chan_zap.c: disabled echo cancellation on channel 215
Feb 16 11:25:41 VERBOSE[31439] logger.c:     -- Hungup 'Zap/215-1'
Feb 16 11:25:41 VERBOSE[5487] logger.c:     -- Moving call from channel 157 to channel 158
Feb 16 11:25:41 WARNING[5487] chan_zap.c: Whoa, there's no  owner, and we're having to fix up channel 157 to channel 158
Feb 16 11:25:41 VERBOSE[5487] logger.c:     -- B-channel 0/13 restarted on span 6
Feb 16 11:25:42 WARNING[5487] chan_zap.c: Call specified, but not found?
Feb 16 11:25:42 WARNING[5487] chan_zap.c: Hangup on bad channel 0/2 on span 6