Nov 6 15:12:25 DEBUG[4179] chan_sip.c: Checking SIP call limits for device 207
Nov 6 15:12:25 DEBUG[4179] chan_sip.c: build_route: Contact hop: <sip:207@10.25.3.83>
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Macro("SIP/207-08930548", "dialout-trunk|2|93299557||") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "DIAL_TRUNK=2") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "DIAL_NUMBER=93299557") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "ROUTE_PASSWD=") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '1'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "1?noauth") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Goto (macro-dialout-trunk,s,6)
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "GROUP()=OUT_2") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Macro("SIP/207-08930548", "user-callerid") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '0'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "0?report") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Not taking any branch
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '0'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "0?start") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Not taking any branch
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is '207'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "REALCALLERIDNUM=207") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing NoOp("SIP/207-08930548", "REALCALLERIDNUM is 207") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is '207'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "AMPUSER=207") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is 'Peter Zhu'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "AMPUSERCIDNAME=Peter Zhu") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '0'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "0?report") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Not taking any branch
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "CALLERID(all)=Peter Zhu <207>") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is '"Peter Zhu" <207>'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing NoOp("SIP/207-08930548", "Using CallerID "Peter Zhu" <207>") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is '207'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Macro("SIP/207-08930548", "record-enable|207|OUT") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is '0'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "0 > 0?2:4") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Goto (macro-record-enable,s,4)
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing DeadAGI("SIP/207-08930548", "recordingcheck|20061106-151225|1162786345.97") in new
stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Nov 6 15:12:25 VERBOSE[4776] logger.c: recordingcheck|20061106-151225|1162786345.97: Outbound recording not enabled
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- AGI Script recordingcheck completed, returning 0
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing NoOp("SIP/207-08930548", "No recording needed") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Macro("SIP/207-08930548", "outbound-callerid|2") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '1'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "1?start") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Goto (macro-outbound-callerid,s,3)
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing NoOp("SIP/207-08930548", "REALCALLERIDNUM is 207") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is ''
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "USEROUTCID=") in new stack
Nov 6 15:12:25 DEBUG[4776] db.c: Unable to find key '207/emergency_cid' in family 'DEVICE'
Nov 6 15:12:25 DEBUG[4776] func_db.c: DB: DEVICE/207/emergency_cid not found in database.
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is ''
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "EMERGENCYCID=") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "TRUNKOUTCID="Uniware Pty Ltd" <0388040804>") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '1'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "1?trunkcid") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Goto (macro-outbound-callerid,s,11)
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '0'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "0?usercid") in new stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Not taking any branch
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "CALLERID(all)="Uniware Pty Ltd" <0388040804>") in new sta
ck
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '1'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "1?report") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Goto (macro-outbound-callerid,s,15)
Nov 6 15:12:25 DEBUG[4776] pbx.c: Function result is '"Uniware Pty Ltd" <0388040804>'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing NoOp("SIP/207-08930548", "CallerID set to "Uniware Pty Ltd" <0388040804>") in new
stack
Nov 6 15:12:25 DEBUG[4776] pbx.c: Expression result is '1'
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "1?nomax") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Goto (macro-dialout-trunk,s,12)
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Executing AGI("SIP/207-08930548", "fixlocalprefix") in new stack
Nov 6 15:12:25 VERBOSE[4776] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "OUTNUM=93299557") in new stack
Nov 6 15:12:26 DEBUG[4776] pbx.c: Function result is 'ZAP/g1'
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Executing Set("SIP/207-08930548", "custom=ZAP/g1") in new stack
Nov 6 15:12:26 DEBUG[4776] pbx.c: Expression result is '0'
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Executing GotoIf("SIP/207-08930548", "0?customtrunk") in new stack
Nov 6 15:12:26 DEBUG[4776] pbx.c: Not taking any branch
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Executing Dial("SIP/207-08930548", "ZAP/g1/93299557|120|r") in new stack
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Requested transfer capability: 0x00 - SPEECH
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Called g1/93299557
Nov 6 15:12:26 DEBUG[4175] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
Nov 6 15:12:26 VERBOSE[4776] logger.c: -- Zap/1-1 is proceeding passing it to SIP/207-08930548
Nov 6 15:12:27 DEBUG[4175] chan_zap.c: Enabled echo cancellation on channel 1
Nov 6 15:12:27 VERBOSE[4776] logger.c: -- Zap/1-1 is ringing
Nov 6 15:12:41 DEBUG[4186] manager.c: Manager received command 'Command'
Nov 6 15:12:41 DEBUG[4186] manager.c: Manager received command 'Command'
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.68 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '2917d9bf093f6a2456c610561bac2809@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.63 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.87 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.46 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.42 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.47 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.65 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.53 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.59 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.88 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.99 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.100 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.60 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.96 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.61 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.64 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.83 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.55 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.52 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.62 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.57 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.58 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: chan_sip: ast_sched_runq ran 21 all at once
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '3d0fa8e271fa0e0c4840f05869ca6422@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '60eded694ef72ab851bac7b34dfa2adc@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '7b1503390102af5b07fda46a7d3d8225@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '57b2eef60f89e3ac21783cf57cdf0306@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '34a58e4f0c959e8d3363be4e6a4c6b97@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '336c5b293bc7fd351a5ad638380d6216@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '1dec41f0209af1630c2de62922a27d8d@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '090ecf0d3123a17a25a0af9435d6d163@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '623bdf512506f96a7ea4653e7667a074@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '7c0b1fc617f9ecfa1d47e0dc0fe47133@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '34ddecc21ab4b3ab4527e6980488e75f@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '653d658065af5bc5136282305daf332c@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '21c7ab6b03b590a914bcbe6527448d61@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '4562be6e12886eb61379d14c3c9c9406@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '2279914e58b3d47c10a728451ad10666@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '3b50cf2255d738d56a4481f26a92b884@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '5aac3c306dd8027538be815f449f2312@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '44fa20a04d27b42d31535121727fa6d5@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '2135fcd2662a7b8409e800d119042b45@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '591030dd457b6bdc3f45a9591566140d@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '43887f69626edd637f8beb8a3ffa25ea@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:12:46 DEBUG[4182] chan_iax2.c: Peer lastms 25, historicms 25, maxms 2000
Nov 6 15:12:46 DEBUG[4182] chan_iax2.c: Peer lastms 54, historicms 54, maxms 2000
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 220.240.129.91 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Target address 220.240.129.91 is not local, substituting externip
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 220.240.81.246 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Target address 220.240.81.246 is not local, substituting externip
Nov 6 15:12:46 DEBUG[4179] acl.c: ##### Testing 220.240.82.174 with 10.25.3.0
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Target address 220.240.82.174 is not local, substituting externip
Nov 6 15:12:46 DEBUG[4182] chan_iax2.c: Peer lastms 95, historicms 95, maxms 2000
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '0f8a3f1e7b70ad496e53e49077bdc89f@220.240.241.114' of Request 102: Match
Found
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '5c6df71744adeefd03fb16e0561d6bf6@220.240.241.114' of Request 102: Match
Found
Nov 6 15:12:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '47df7aa166e2478558416ab93c97d5a2@220.240.241.114' of Request 102: Match
Found
Nov 6 15:12:48 DEBUG[4176] chan_zap.c: disabled echo cancellation on channel 32
Nov 6 15:12:51 DEBUG[4182] chan_iax2.c: Received VNAK: resending outstanding frames
Nov 6 15:13:19 VERBOSE[4783] logger.c: -- Starting simple switch on 'Zap/32-1'
Nov 6 15:13:20 DEBUG[4783] chan_zap.c: DTMF digit: 9 on Zap/32-1
Nov 6 15:13:20 DEBUG[4783] chan_zap.c: DTMF digit: 2 on Zap/32-1
Nov 6 15:13:20 DEBUG[4783] chan_zap.c: DTMF digit: 8 on Zap/32-1
Nov 6 15:13:20 DEBUG[4783] chan_zap.c: DTMF digit: 7 on Zap/32-1
Nov 6 15:13:20 DEBUG[4783] chan_zap.c: DTMF digit: 9 on Zap/32-1
Nov 6 15:13:21 DEBUG[4783] chan_zap.c: DTMF digit: 8 on Zap/32-1
Nov 6 15:13:21 DEBUG[4783] chan_zap.c: DTMF digit: 9 on Zap/32-1
Nov 6 15:13:21 DEBUG[4783] chan_zap.c: DTMF digit: 0 on Zap/32-1
Nov 6 15:13:21 DEBUG[4783] chan_zap.c: Enabled echo cancellation on channel 32
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Macro("Zap/32-1", "dialout-trunk|2|92879890||") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "DIAL_TRUNK=2") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "DIAL_NUMBER=92879890") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "ROUTE_PASSWD=") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '1'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "1?noauth") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Goto (macro-dialout-trunk,s,6)
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "GROUP()=OUT_2") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Macro("Zap/32-1", "user-callerid") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '0'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "0?report") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Not taking any branch
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '0'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "0?start") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Not taking any branch
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "REALCALLERIDNUM=") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing NoOp("Zap/32-1", "REALCALLERIDNUM is ") in new stack
Nov 6 15:13:21 DEBUG[4783] db.c: Unable to find key '/user' in family 'DEVICE'
Nov 6 15:13:21 DEBUG[4783] func_db.c: DB: DEVICE//user not found in database.
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "AMPUSER=") in new stack
Nov 6 15:13:21 DEBUG[4783] db.c: Unable to find key '/cidname' in family 'AMPUSER'
Nov 6 15:13:21 DEBUG[4783] func_db.c: DB: AMPUSER//cidname not found in database.
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "AMPUSERCIDNAME=") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '1'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "1?report") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Goto (macro-user-callerid,s,9)
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is '"" <>'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing NoOp("Zap/32-1", "Using CallerID "" <>") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Macro("Zap/32-1", "record-enable||OUT") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is '0'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "0 > 0?2:4") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Goto (macro-record-enable,s,4)
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing DeadAGI("Zap/32-1", "recordingcheck|20061106-151321|1162786399.99") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Nov 6 15:13:21 DEBUG[4783] db.c: Unable to find key '/recording' in family 'AMPUSER'
Nov 6 15:13:21 VERBOSE[4783] logger.c: recordingcheck|20061106-151321|1162786399.99: Outbound recording not enabled
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- AGI Script recordingcheck completed, returning 0
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing NoOp("Zap/32-1", "No recording needed") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Macro("Zap/32-1", "outbound-callerid|2") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '0'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "0?start") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Not taking any branch
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "REALCALLERIDNUM=") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing NoOp("Zap/32-1", "REALCALLERIDNUM is ") in new stack
Nov 6 15:13:21 DEBUG[4783] db.c: Unable to find key '/outboundcid' in family 'AMPUSER'
Nov 6 15:13:21 DEBUG[4783] func_db.c: DB: AMPUSER//outboundcid not found in database.
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "USEROUTCID=") in new stack
Nov 6 15:13:21 DEBUG[4783] db.c: Unable to find key '/emergency_cid' in family 'DEVICE'
Nov 6 15:13:21 DEBUG[4783] func_db.c: DB: DEVICE//emergency_cid not found in database.
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is ''
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "EMERGENCYCID=") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "TRUNKOUTCID="Uniware Pty Ltd" <0388040804>") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '1'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "1?trunkcid") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Goto (macro-outbound-callerid,s,11)
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '0'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "0?usercid") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Not taking any branch
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "CALLERID(all)="Uniware Pty Ltd" <0388040804>") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '1'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "1?report") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Goto (macro-outbound-callerid,s,15)
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is '"Uniware Pty Ltd" <0388040804>'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing NoOp("Zap/32-1", "CallerID set to "Uniware Pty Ltd" <0388040804>") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '1'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "1?nomax") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Goto (macro-dialout-trunk,s,12)
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing AGI("Zap/32-1", "fixlocalprefix") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "OUTNUM=92879890") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Function result is 'ZAP/g1'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Set("Zap/32-1", "custom=ZAP/g1") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Expression result is '0'
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing GotoIf("Zap/32-1", "0?customtrunk") in new stack
Nov 6 15:13:21 DEBUG[4783] pbx.c: Not taking any branch
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Executing Dial("Zap/32-1", "ZAP/g1/92879890|120|r") in new stack
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Requested transfer capability: 0x00 - SPEECH
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Called g1/92879890
Nov 6 15:13:21 DEBUG[4783] chan_zap.c: Requested indication 3 on channel Zap/32-1
Nov 6 15:13:21 DEBUG[4175] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 1
Nov 6 15:13:21 VERBOSE[4783] logger.c: -- Zap/2-1 is proceeding passing it to Zap/32-1
Nov 6 15:13:22 DEBUG[4175] chan_zap.c: Enabled echo cancellation on channel 2
Nov 6 15:13:22 VERBOSE[4783] logger.c: -- Zap/2-1 is ringing
Nov 6 15:13:33 DEBUG[4175] chan_zap.c: Echo cancellation already on
Nov 6 15:13:33 VERBOSE[4783] logger.c: -- Zap/2-1 answered Zap/32-1
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Requested indication -1 on channel Zap/32-1
Nov 6 15:13:33 VERBOSE[4783] logger.c: -- Attempting native bridge of Zap/32-1 and Zap/2-1
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: master: 32, slave: 2, nothingok: 0
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Stopping tones on 32/0 talking to 2/0
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Stopping tones on 2/0 talking to 32/0
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Making 2 slave to master 32 at 0
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Added 12 to conference 3848/1023
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Added 41 to conference 3848/1023
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Updated conferencing on 32, with 2 conference users
Nov 6 15:13:33 DEBUG[4783] chan_zap.c: Updated conferencing on 2, with 0 conference users
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.68 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '01f9e7761aa4412b3bb52d120a33a494@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.63 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.42 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.87 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.47 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.46 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.65 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.53 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.88 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.59 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.99 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.100 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.60 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.96 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.83 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.61 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.52 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.55 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.62 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.57 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.58 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: chan_sip: ast_sched_runq ran 20 all at once
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 10.25.3.64 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '483d6f9a0f2028c90cf03eef72d15386@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '4356dca65cee505176c2435f28cca2cc@10.25.3.132' of Request 102: Match Fou
nd
ov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '6383735e38f337d472f2ba4b77315687@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '40aed90479d56f6e2942c06669ab7f96@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '0a329ad92a4609414f9cbbda7dec434d@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '2b18c3b92fdcd0a951aad7436936ac04@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '02a408a812f9581c503a12a64ceea6fb@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '71f7f1e2585c080675db602f2ce4d7c2@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '6cbe299e6cf5bd355e65a0fd2ee5d76b@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '4c0100ad15f25ead70e2f7fe29fa80cf@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '5904240a602b9b3c2679da6d12195d22@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '795d975e526f261948b1bc2931fc9264@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '6e798a736b7de4bb11b1f26626911f52@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '0a790adf030d359b0a187350067d0b83@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '110b74302840560e04323976698f0d67@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '6e0bf83f609842801db03da93ff82ae4@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '44a279632e038e3a1d8b753c70287ebb@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '5f3a89c17bcb702a7788d51f4de365e8@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '40854df32db4550b7addd65621d74143@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '711e16fc1e103b282e1895f830575035@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '6fa03e734bf227337e8a5eab405c1cae@10.25.3.132' of Request 102: Match Fou
nd
Nov 6 15:13:46 DEBUG[4182] chan_iax2.c: Peer lastms 25, historicms 25, maxms 2000
Nov 6 15:13:46 DEBUG[4182] chan_iax2.c: Peer lastms 52, historicms 52, maxms 2000
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 220.240.129.91 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Target address 220.240.129.91 is not local, substituting externip
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 220.240.81.246 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Target address 220.240.81.246 is not local, substituting externip
Nov 6 15:13:46 DEBUG[4182] chan_iax2.c: Peer lastms 98, historicms 98, maxms 2000
Nov 6 15:13:46 DEBUG[4179] acl.c: ##### Testing 220.240.82.174 with 10.25.3.0
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Target address 220.240.82.174 is not local, substituting externip
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '4818a5dc18950ec93d436ffb6069d2b2@220.240.241.114' of Request 102: Match
Found
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '526dadbc5592e50146fdec60568b9481@220.240.241.114' of Request 102: Match
Found
Nov 6 15:13:46 DEBUG[4179] chan_sip.c: Stopping retransmission on '3373ca74653073430a4a668d7b1dac58@220.240.241.114' of Request 102: Match
Found
Nov 6 15:13:57 DEBUG[4175] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1
Nov 6 15:13:57 VERBOSE[4776] logger.c: -- Zap/1-1 is making progress passing it to SIP/207-08930548
Nov 6 15:13:58 VERBOSE[4175] logger.c: -- Channel 0/2, span 1 got hangup request
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Echo cancellation already on
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Echo cancellation already on
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Unlinking slave 2 from 32
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Removed 12 from conference 3848/1023
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Removed 41 from conference 3848/1023
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Updated conferencing on 32, with 0 conference users
Nov 6 15:13:58 DEBUG[4783] channel.c: Returning from native bridge, channels: Zap/32-1, Zap/2-1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Hangup: channel: 2 index = 0, normal = 12, callwait = -1, thirdcall = -1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: disabled echo cancellation on channel 2
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Updated conferencing on 2, with 0 conference users
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: disabled echo cancellation on channel 2
Nov 6 15:13:58 VERBOSE[4783] logger.c: -- Hungup 'Zap/2-1'
Nov 6 15:13:58 DEBUG[4783] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Nov 6 15:13:58 VERBOSE[4783] logger.c: == Spawn extension (macro-dialout-trunk, s, 16) exited non-zero on 'Zap/32-1' in macro 'dialout-t
runk'
Nov 6 15:13:58 VERBOSE[4783] logger.c: == Spawn extension (macro-dialout-trunk, s, 16) exited non-zero on 'Zap/32-1'
Nov 6 15:13:58 DEBUG[4783] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Nov 6 15:13:58 DEBUG[4783] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,d
stchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-11-06 15:13:21','\"Uniware Pty Ltd\" <
0388040804>','0388040804','92879890','from-internal', 'Zap/32-1','Zap/2-1','Dial','ZAP/g1/92879890|120|r',37,25,'ANSWERED',3,'','1162786399
.99')
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '"Uniware Pty Ltd" <0388040804>'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '0388040804'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '92879890'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'from-internal'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'Zap/32-1'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'Zap/2-1'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'Dial'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'ZAP/g1/92879890|120|r'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '2006-11-06 15:13:21'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '2006-11-06 15:13:33'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '2006-11-06 15:13:58'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '37'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '25'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'ANSWERED'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is 'DOCUMENTATION'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '(null)'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '1162786399.99'
Nov 6 15:13:58 DEBUG[4783] pbx.c: Function result is '(null)'
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Hangup: channel: 32 index = 0, normal = 41, callwait = -1, thirdcall = -1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: disabled echo cancellation on channel 32
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/32-1
Nov 6 15:13:58 DEBUG[4783] chan_zap.c: Updated conferencing on 32, with 0 conference users
Nov 6 15:13:58 VERBOSE[4783] logger.c: -- Hungup 'Zap/32-1'
Nov 6 15:14:27 VERBOSE[4175] logger.c: -- Channel 0/1, span 1 got hangup request
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: Hangup: channel: 1 index = 0, normal = 11, callwait = -1, thirdcall = -1
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: disabled echo cancellation on channel 1
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: Updated conferencing on 1, with 0 conference users
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
Nov 6 15:14:27 DEBUG[4776] chan_zap.c: disabled echo cancellation on channel 1
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Hungup 'Zap/1-1'
Nov 6 15:14:27 VERBOSE[4776] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
Nov 6 15:14:27 DEBUG[4776] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Executing Goto("SIP/207-08930548", "s-CHANUNAVAIL|1") in new stack
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Executing NoOp("SIP/207-08930548", "Dial failed due to CHANUNAVAIL - failing through to othe
r trunks") in new stack
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Executing Macro("SIP/207-08930548", "outisbusy|") in new stack
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Executing Playback("SIP/207-08930548", "all-circuits-busy-now") in new stack
Nov 6 15:14:27 DEBUG[4776] channel.c: Scheduling timer at 160 sample intervals
Nov 6 15:14:27 VERBOSE[4776] logger.c: -- Playing 'all-circuits-busy-now' (language 'en')
Nov 6 15:14:27 DEBUG[4179] chan_sip.c: Stopping retransmission on 'c919f271d59382ce@10.25.3.83' of Response 15497: Match Found
Nov 6 15:14:29 DEBUG[4776] channel.c: Scheduling timer at 0 sample intervals
Nov 6 15:14:29 DEBUG[4776] channel.c: Scheduling timer at 0 sample intervals
Nov 6 15:14:29 VERBOSE[4776] logger.c: -- Executing Playback("SIP/207-08930548", "pls-try-call-later") in new stack
Nov 6 15:14:29 DEBUG[4776] channel.c: Scheduling timer at 160 sample intervals
Nov 6 15:14:29 VERBOSE[4776] logger.c: -- Playing 'pls-try-call-later' (language 'en')
Nov 6 15:14:31 DEBUG[4776] channel.c: Scheduling timer at 0 sample intervals
Nov 6 15:14:31 DEBUG[4776] channel.c: Scheduling timer at 0 sample intervals
Nov 6 15:14:31 VERBOSE[4776] logger.c: -- Executing Macro("SIP/207-08930548", "hangupcall") in new stack
Nov 6 15:14:31 VERBOSE[4776] logger.c: -- Executing ResetCDR("SIP/207-08930548", "w") in new stack
Nov 6 15:14:31 DEBUG[4776] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Nov 6 15:14:31 DEBUG[4776] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,d
stchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-11-06 15:12:25','\"Uniware Pty Ltd\" <
0388040804>','0388040804','93299557','from-internal', 'SIP/207-08930548','Zap/1-1','ResetCDR','w',126,4,'ANSWERED',3,'','1162786345.97')
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '"Uniware Pty Ltd" <0388040804>'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '0388040804'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '93299557'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'from-internal'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'SIP/207-08930548'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'Zap/1-1'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'ResetCDR'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'w'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '2006-11-06 15:12:25'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '2006-11-06 15:14:27'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '2006-11-06 15:14:31'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '126'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '4'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'ANSWERED'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is 'DOCUMENTATION'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '(null)'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '1162786345.97'
Nov 6 15:14:31 DEBUG[4776] pbx.c: Function result is '(null)'
Nov 6 15:14:31 VERBOSE[4776] logger.c: -- Executing NoCDR("SIP/207-08930548", "") in new stack
Nov 6 15:14:31 WARNING[4776] cdr.c: CDR on channel 'SIP/207-08930548' not posted
Nov 6 15:14:31 WARNING[4776] cdr.c: CDR on channel 'SIP/207-08930548' lacks end
Nov 6 15:14:31 VERBOSE[4776] logger.c: -- Executing Wait("SIP/207-08930548", "5") in new stack
Nov 6 15:14:32 VERBOSE[4776] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/207-08930548' in macro 'hangu
pcall'
Nov 6 15:14:32 VERBOSE[4776] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/207-08930548' in macro 'outis
busy'
Nov 6 15:14:32 VERBOSE[4776] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/207-08930548'
Nov 6 15:14:32 DEBUG[4776] chan_sip.c: update_call_counter(207) - decrement call limit counter