May 18 17:18:04 VERBOSE[5481] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Zap/8-1' in macro 'hangupcall'
May 18 17:18:04 VERBOSE[5481] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Zap/8-1'
May 18 17:18:04 DEBUG[5481] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/8-1
May 18 17:18:04 DEBUG[5481] chan_zap.c: Hangup: channel: 8 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 18 17:18:04 DEBUG[5481] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
May 18 17:18:04 DEBUG[5481] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/8-1
May 18 17:18:04 DEBUG[5481] chan_zap.c: Updated conferencing on 8, with 0 conference users
May 18 17:18:04 DEBUG[5481] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/8-1
May 18 17:18:04 VERBOSE[5481] logger.c: -- Hungup 'Zap/8-1'
May 18 17:18:04 DEBUG[2541] chan_sip.c: Stopping retransmission on '173f064f7a0646b7635d82bc22e672b5@192.168.1.250' of Request 103: Match Found
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 3, historicms 3, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2540] chan_iax2.c: Peer lastms 1, historicms 1, maxms 2000
May 18 17:18:06 DEBUG[2541] acl.c: ##### Testing 192.168.1.95 with 192.168.1.0
May 18 17:18:06 DEBUG[2541] chan_sip.c: Stopping retransmission on '332af3fa0ec761e1604c16ee5b0bcdb1@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.142 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.98 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.139 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.127 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.70 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.130 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.148 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '506e0a511e34535f1d02612d511c7d06@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '36a47f5017045e31535c149329bba056@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.124 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.137 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '78fc772530746c7d2a537e3220fa6567@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '0685eb2b30f9e3d86e8d328805cc38d8@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.138 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.134 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '733780ad281c21595bcded770ed5d5f6@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '6648da371162d42e70dcb003128ea3af@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '28817ede4b56a8b76f04fec1507b17c8@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.131 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.129 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.141 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.140 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '39821e5b355317502d3ae4ac3feaddb5@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] acl.c: ##### Testing 192.168.1.126 with 192.168.1.0
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '3d84b3b61df73af66dfbf26a3b57eafb@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '07323f940704bf246a34c170704b3671@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '03dd4266450168133ca7d1c0770f2d4f@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '2d7c64f87cb3bfa12f73c77c61c59d67@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '3173dc37790a6f42711074b0251b76eb@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '3ecf1fb332c46d55610f8db971f297eb@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '100bd28223840abd700ac85b0d07b577@192.168.1.250' of Request 102: Match Found
May 18 17:18:08 DEBUG[2541] chan_sip.c: Stopping retransmission on '47357c8c0e7d9907316d891706e34a91@192.168.1.250' of Request 102: Match Found
May 18 17:18:10 DEBUG[2541] acl.c: ##### Testing 192.168.1.105 with 192.168.1.0
May 18 17:18:10 DEBUG[2541] acl.c: ##### Testing 192.168.1.129 with 192.168.1.0
May 18 17:18:10 DEBUG[2541] chan_sip.c: Stopping retransmission on '4fa69a400f2d241a631919b6626ed947@192.168.1.250' of Request 102: Match Found
May 18 17:18:10 DEBUG[2541] chan_sip.c: Stopping retransmission on '00bd1a1560cae7215d0247e3042a9e3e@192.168.1.250' of Request 102: Match Found
May 18 17:18:11 DEBUG[2541] acl.c: ##### Testing 192.168.1.126 with 192.168.1.0
May 18 17:18:11 DEBUG[2541] chan_sip.c: Setting NAT on RTP to 524288
May 18 17:18:11 DEBUG[2541] chan_sip.c: Stopping retransmission on 'ff0b6bf348e40c1620ba8fde3e9a3ebd@192.168.1.126' of Response 151378509: Match Found
May 18 17:18:11 DEBUG[2541] chan_sip.c: Setting NAT on RTP to 524288
May 18 17:18:11 DEBUG[2541] chan_sip.c: Checking SIP call limits for device 6643
May 18 17:18:11 DEBUG[2541] chan_sip.c: build_route: Contact hop: sip:6643@192.168.1.126
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Macro("SIP/6643-09c3b518", "dialout-trunk|1|7910062|") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "DIAL_TRUNK=1") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "_NODEST=") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "DIAL_NUMBER=7910062") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "ROUTE_PASSWD=") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "DIAL_TRUNK_OPTIONS=trwW") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?noauth") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Goto (macro-dialout-trunk,s,8)
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "GROUP()=OUT_1") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Macro("SIP/6643-09c3b518", "user-callerid|SKIPTTL") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is 'device'
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '6643'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "user-callerid: device 6643") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Expression result is '0'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0?report") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Not taking any branch
May 18 17:18:11 DEBUG[5492] pbx.c: Expression result is '0'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0?start") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Not taking any branch
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '6643'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "REALCALLERIDNUM=6643") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "REALCALLERIDNUM is 6643") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '6643'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "AMPUSER=6643") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is 'Jason Balicki'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "AMPUSERCIDNAME=Jason Balicki") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Expression result is '0'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0?report") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Not taking any branch
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "CALLERID(all)=Jason Balicki <6643>") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '6643'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "REALCALLERIDNUM=6643") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "TTL: ARG1: SKIPTTL") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?continue") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Goto (macro-user-callerid,s,21)
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '"Jason Balicki" <6643>'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "Using CallerID "Jason Balicki" <6643>") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '6643'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing Macro("SIP/6643-09c3b518", "record-enable|6643|OUT") in new stack
May 18 17:18:11 DEBUG[5492] pbx.c: Function result is '0'
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0 > 0?2:4") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Goto (macro-record-enable,s,4)
May 18 17:18:11 VERBOSE[5492] logger.c: -- Executing DeadAGI("SIP/6643-09c3b518", "recordingcheck|20070518-171811|1179526691.63") in new stack
May 18 17:18:11 VERBOSE[5492] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 18 17:18:12 VERBOSE[5492] logger.c: recordingcheck|20070518-171811|1179526691.63: Outbound recording not enabled
May 18 17:18:12 VERBOSE[5492] logger.c: -- AGI Script recordingcheck completed, returning 0
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "No recording needed") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '0'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0?skipoutcid") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Not taking any branch
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "DIAL_TRUNK_OPTIONS=wW") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Macro("SIP/6643-09c3b518", "outbound-callerid|1") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?start") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Goto (macro-outbound-callerid,s,3)
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "REALCALLERIDNUM is 6643") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?normcid") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Goto (macro-outbound-callerid,s,9)
May 18 17:18:12 DEBUG[5492] pbx.c: Function result is '"Jason Balicki" <3143926643>'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "USEROUTCID="Jason Balicki" <3143926643>") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Function result is '"Frontier Mortgage" <3142052900>'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "EMERGENCYCID="Frontier Mortgage" <3142052900>") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "TRUNKOUTCID=") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?trunkcid") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Goto (macro-outbound-callerid,s,16)
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?usercid") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Goto (macro-outbound-callerid,s,18)
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '0'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0?report") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Not taking any branch
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "CALLERID(all)="Jason Balicki" <3143926643>") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Function result is 'Jason Balicki'
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?report:hidecid") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Goto (macro-outbound-callerid,s,22)
May 18 17:18:12 DEBUG[5492] pbx.c: Function result is '"Jason Balicki" <3143926643>'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing NoOp("SIP/6643-09c3b518", "CallerID set to "Jason Balicki" <3143926643>") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?nomax") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Goto (macro-dialout-trunk,s,16)
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing DeadAGI("SIP/6643-09c3b518", "fixlocalprefix") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 18 17:18:12 VERBOSE[5492] logger.c: -- AGI Script fixlocalprefix completed, returning 0
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "OUTNUM=7910062") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Function result is 'ZAP/g0'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Set("SIP/6643-09c3b518", "custom=ZAP/g0") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Expression result is '0'
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "0?customtrunk") in new stack
May 18 17:18:12 DEBUG[5492] pbx.c: Not taking any branch
May 18 17:18:12 VERBOSE[5492] logger.c: -- Executing Dial("SIP/6643-09c3b518", "ZAP/g0/7910062|300|wW") in new stack
May 18 17:18:12 VERBOSE[5492] logger.c: -- Requested transfer capability: 0x00 - SPEECH
May 18 17:18:12 VERBOSE[5492] logger.c: -- Called g0/7910062
May 18 17:18:12 DEBUG[2537] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/8 span 1
May 18 17:18:12 VERBOSE[5492] logger.c: -- Zap/8-1 is proceeding passing it to SIP/6643-09c3b518
May 18 17:18:13 DEBUG[2541] acl.c: ##### Testing 192.168.17.53 with 192.168.1.0
May 18 17:18:13 DEBUG[2541] chan_sip.c: Target address 192.168.17.53 is not local, substituting externip
May 18 17:18:13 DEBUG[2541] acl.c: ##### Testing 192.168.17.59 with 192.168.1.0
May 18 17:18:13 DEBUG[2541] chan_sip.c: Target address 192.168.17.59 is not local, substituting externip
May 18 17:18:13 DEBUG[2541] chan_sip.c: Stopping retransmission on '16e8465e15cc885c549ba80f07601564@65.23.24.2' of Request 102: Match Found
May 18 17:18:13 DEBUG[2541] chan_sip.c: Stopping retransmission on '3afc15480faffccf1fbbcf51433fda0f@65.23.24.2' of Request 102: Match Found
May 18 17:18:13 DEBUG[2577] manager.c: Manager received command 'Command'
May 18 17:18:13 DEBUG[2577] manager.c: Manager received command 'Command'
May 18 17:18:13 DEBUG[2577] manager.c: Manager received command 'Command'
May 18 17:18:13 DEBUG[2537] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/8 span 1
May 18 17:18:13 VERBOSE[5492] logger.c: -- Zap/8-1 is making progress passing it to SIP/6643-09c3b518
May 18 17:18:17 DEBUG[2541] chan_sip.c: Auto destroying call '4e90f2d4d74df2e8be5d4f1ae215d6db@192.168.1.129'
May 18 17:18:17 DEBUG[2541] acl.c: ##### Testing 192.168.1.106 with 192.168.1.0
May 18 17:18:18 DEBUG[2537] chan_zap.c: No echo cancellation requested
May 18 17:18:18 VERBOSE[5492] logger.c: -- Zap/8-1 answered SIP/6643-09c3b518
May 18 17:18:18 DEBUG[2541] chan_sip.c: Stopping retransmission on 'ff0b6bf348e40c1620ba8fde3e9a3ebd@192.168.1.126' of Response 151378510: Match Found
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1208' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1200' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1211' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1205' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1202' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1203' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1201' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1214' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1210' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1209' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1213' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1215' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1212' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1207' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1206' to 60 seconds (requested 300)
May 18 17:18:23 NOTICE[2540] chan_iax2.c: Restricting registration for peer '1204' to 60 seconds (requested 300)
May 18 17:18:29 DEBUG[2541] acl.c: ##### Testing 192.168.1.142 with 192.168.1.0
May 18 17:18:32 DEBUG[2541] acl.c: ##### Testing 192.168.1.142 with 192.168.1.0
May 18 17:18:33 DEBUG[2541] chan_sip.c: Auto destroying call 'b5fe39a03cd5087acab59ccf96842183@192.168.1.106'
May 18 17:18:33 DEBUG[2541] chan_sip.c: Stopping retransmission on '28c44a4c16a54726664be16675790580@192.168.1.250' of Request 102: Match Found
May 18 17:18:35 VERBOSE[2537] logger.c: -- Channel 0/8, span 1 got hangup request
May 18 17:18:35 DEBUG[5492] channel.c: Didn't get a frame from channel: Zap/8-1
May 18 17:18:35 DEBUG[5492] channel.c: Bridge stops bridging channels SIP/6643-09c3b518 and Zap/8-1
May 18 17:18:35 DEBUG[5492] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/8-1
May 18 17:18:35 DEBUG[5492] chan_zap.c: Hangup: channel: 8 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 18 17:18:35 DEBUG[5492] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
May 18 17:18:35 DEBUG[5492] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/8-1
May 18 17:18:35 DEBUG[5492] chan_zap.c: Updated conferencing on 8, with 0 conference users
May 18 17:18:35 DEBUG[5492] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/8-1
May 18 17:18:35 VERBOSE[5492] logger.c: -- Hungup 'Zap/8-1'
May 18 17:18:35 DEBUG[5492] app_dial.c: Exiting with DIALSTATUS=ANSWER.
May 18 17:18:35 VERBOSE[5492] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/6643-09c3b518' in macro 'dialout-trunk'
May 18 17:18:35 VERBOSE[5492] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/6643-09c3b518'
May 18 17:18:35 VERBOSE[5492] logger.c: -- Executing Macro("SIP/6643-09c3b518", "hangupcall") in new stack
May 18 17:18:35 VERBOSE[5492] logger.c: -- Executing ResetCDR("SIP/6643-09c3b518", "w") in new stack
May 18 17:18:35 DEBUG[5492] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 18 17:18:35 DEBUG[5492] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2007-05-18 17:18:11','\"Jason Balicki\" <3143926643>','3143926643','97910062','from-internal', 'SIP/6643-09c3b518','Zap/8-1','ResetCDR','w',24,17,'ANSWERED',3,'','1179526691.63')
May 18 17:18:35 VERBOSE[5492] logger.c: -- Executing NoCDR("SIP/6643-09c3b518", "") in new stack
May 18 17:18:35 NOTICE[5492] cdr.c: CDR on channel 'SIP/6643-09c3b518' not posted
May 18 17:18:35 NOTICE[5492] cdr.c: CDR on channel 'SIP/6643-09c3b518' lacks end
May 18 17:18:35 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:35 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?skiprg") in new stack
May 18 17:18:35 VERBOSE[5492] logger.c: -- Goto (macro-hangupcall,s,6)
May 18 17:18:35 DEBUG[5492] pbx.c: Expression result is '1'
May 18 17:18:35 VERBOSE[5492] logger.c: -- Executing GotoIf("SIP/6643-09c3b518", "1?theend") in new stack
May 18 17:18:35 VERBOSE[5492] logger.c: -- Goto (macro-hangupcall,s,9)
May 18 17:18:35 VERBOSE[5492] logger.c: -- Executing Wait("SIP/6643-09c3b518", "5") in new stack
May 18 17:18:35 VERBOSE[5492] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/6643-09c3b518' in macro 'hangupcall'
May 18 17:18:35 VERBOSE[5492] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/6643-09c3b518'
May 18 17:18:35 DEBUG[5492] chan_sip.c: update_call_counter(6643) - decrement call limit counter
May 18 17:18:44 VERBOSE[2537] logger.c: -- Accepting call from '3147910062' to '6643' on channel 0/8, span 1
May 18 17:18:44 DEBUG[2537] chan_zap.c: No echo cancellation requested
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Macro("Zap/8-1", "exten-vm|6643|6643") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Macro("Zap/8-1", "user-callerid") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is 'LADUE, MO'
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is '3147910062'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing NoOp("Zap/8-1", "user-callerid: LADUE, MO 3147910062") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '0'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "0?report") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Not taking any branch
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '0'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "0?start") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Not taking any branch
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is '3147910062'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "REALCALLERIDNUM=3147910062") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing NoOp("Zap/8-1", "REALCALLERIDNUM is 3147910062") in new stack
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '3147910062/user' in family 'DEVICE'
May 18 17:18:44 DEBUG[5536] func_db.c: DB: DEVICE/3147910062/user not found in database.
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is ''
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "AMPUSER=") in new stack
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '/cidname' in family 'AMPUSER'
May 18 17:18:44 DEBUG[5536] func_db.c: DB: AMPUSER//cidname not found in database.
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is ''
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "AMPUSERCIDNAME=") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "1?report") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Goto (macro-user-callerid,s,11)
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing NoOp("Zap/8-1", "TTL: ARG1: 6643") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '0'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "0?continue") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Not taking any branch
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '-1'
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is '64'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "_TTL=64") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "1?continue") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Goto (macro-user-callerid,s,21)
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is '"LADUE, MO" <3147910062>'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing NoOp("Zap/8-1", "Using CallerID "LADUE, MO" <3147910062>") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "FROMCONTEXT=exten-vm") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "VMBOX=6643") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "EXTTOCALL=6643") in new stack
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '6643' in family 'CFU'
May 18 17:18:44 DEBUG[5536] func_db.c: DB: CFU/6643 not found in database.
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is ''
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "CFUEXT=") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '0'
May 18 17:18:44 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is '15'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Set("Zap/8-1", "RT=15") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Macro("Zap/8-1", "record-enable|6643|IN") in new stack
May 18 17:18:44 DEBUG[5536] pbx.c: Function result is '0'
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "0 > 0?2:4") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Goto (macro-record-enable,s,4)
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing DeadAGI("Zap/8-1", "recordingcheck|20070518-171844|1179526724.65") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 18 17:18:44 VERBOSE[5536] logger.c: recordingcheck|20070518-171844|1179526724.65: Inbound recording not enabled
May 18 17:18:44 VERBOSE[5536] logger.c: -- AGI Script recordingcheck completed, returning 0
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing NoOp("Zap/8-1", "No recording needed") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Macro("Zap/8-1", "dial|15|trwW|6643") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing DeadAGI("Zap/8-1", "dialparties.agi") in new stack
May 18 17:18:44 VERBOSE[5536] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
May 18 17:18:44 VERBOSE[5536] logger.c: dialparties.agi: Starting New Dialparties.agi
May 18 17:18:44 VERBOSE[5536] logger.c: dialparties.agi: priority is 1
May 18 17:18:44 VERBOSE[5536] logger.c: dialparties.agi: Caller ID name is 'LADUE, MO' number is '3147910062'
May 18 17:18:44 VERBOSE[5536] logger.c: dialparties.agi: Methodology of ring is 'none'
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: RINGGROUP_INDEX: ''
May 18 17:18:44 VERBOSE[5536] logger.c: -- dialparties.agi: Added extension 6643 to extension map
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '6643' in family 'CF'
May 18 17:18:44 VERBOSE[5536] logger.c: -- dialparties.agi: Extension 6643 cf is disabled
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '6643' in family 'DND'
May 18 17:18:44 VERBOSE[5536] logger.c: -- dialparties.agi: Extension 6643 do not disturb is disabled
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '6643' in family 'CFB'
May 18 17:18:44 DEBUG[5536] db.c: Unable to find key '6643' in family 'CFU'
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: extnum: 6643
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: exthascw: 1
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: exthascfb: 0
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: extcfb:
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: exthascfu: 0
May 18 17:18:44 VERBOSE[5536] logger.c: > dialparties.agi: extcfu:
May 18 17:18:44 VERBOSE[5536] logger.c: -- dialparties.agi: dbset CALLTRACE/6643 to 3147910062
May 18 17:18:44 VERBOSE[5536] logger.c: -- AGI Script dialparties.agi completed, returning 0
May 18 17:18:44 VERBOSE[5536] logger.c: -- Executing Dial("Zap/8-1", "SIP/6643|15|trwW") in new stack
May 18 17:18:44 DEBUG[5536] chan_sip.c: Setting NAT on RTP to 524288
May 18 17:18:44 DEBUG[5536] acl.c: ##### Testing 192.168.1.126 with 192.168.1.0
May 18 17:18:44 DEBUG[5536] chan_sip.c: Outgoing Call for 6643
May 18 17:18:44 VERBOSE[5536] logger.c: -- Called 6643
May 18 17:18:44 DEBUG[5536] chan_zap.c: Requested indication 3 on channel Zap/8-1
May 18 17:18:44 DEBUG[2541] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '165e156732f8c4517e9eb96544b334cb@192.168.1.250' Request 102: Found
May 18 17:18:44 DEBUG[2541] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '165e156732f8c4517e9eb96544b334cb@192.168.1.250' Request 102: Found
May 18 17:18:44 DEBUG[2541] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '165e156732f8c4517e9eb96544b334cb@192.168.1.250' Request 102: Found
May 18 17:18:44 VERBOSE[5536] logger.c: -- SIP/6643-09c3b518 is ringing
May 18 17:18:44 DEBUG[2541] chan_sip.c: Auto destroying call '7df4ef251ed553906fb1dc524c3dfec3@192.168.1.142'
May 18 17:18:45 DEBUG[2541] acl.c: ##### Testing 192.168.1.131 with 192.168.1.0
May 18 17:18:45 DEBUG[2541] acl.c: ##### Testing 192.168.1.148 with 192.168.1.0
May 18 17:18:45 DEBUG[2541] chan_sip.c: Acked pending invite 102
May 18 17:18:45 DEBUG[2541] chan_sip.c: Stopping retransmission on '165e156732f8c4517e9eb96544b334cb@192.168.1.250' of Request 102: Match Found
May 18 17:18:45 DEBUG[2541] chan_sip.c: build_route: Contact hop: <sip:6643@192.168.1.126>
May 18 17:18:45 VERBOSE[5536] logger.c: -- SIP/6643-09c3b518 answered Zap/8-1
May 18 17:18:45 DEBUG[5536] chan_zap.c: Requested indication -1 on channel Zap/8-1
May 18 17:18:48 DEBUG[2541] acl.c: ##### Testing 192.168.1.106 with 192.168.1.0
May 18 17:18:48 DEBUG[2541] chan_sip.c: Stopping retransmission on '4fc889f96f84594a32c43359660ed5c3@192.168.1.250' of Request 102: Match Found
May 18 17:18:52 DEBUG[2541] acl.c: ##### Testing 192.168.17.59 with 192.168.1.0
May 18 17:18:52 DEBUG[2541] chan_sip.c: Target address 192.168.17.59 is not local, substituting externip
May 18 17:18:53 DEBUG[2541] acl.c: ##### Testing 192.168.1.131 with 192.168.1.0
May 18 17:18:53 DEBUG[2541] chan_sip.c: Stopping retransmission on '59390f233f174b6129beaf230a4c9808@192.168.1.250' of Request 102: Match Found
May 18 17:18:54 DEBUG[2541] acl.c: ##### Testing 192.168.1.148 with 192.168.1.0
May 18 17:18:54 DEBUG[2541] acl.c: ##### Testing 192.168.17.59 with 192.168.1.0
May 18 17:18:54 DEBUG[2541] chan_sip.c: Target address 192.168.17.59 is not local, substituting externip
May 18 17:18:54 DEBUG[2541] chan_sip.c: Stopping retransmission on '0c7982b81d91e41932d4e3b97ea7e477@192.168.1.250' of Request 102: Match Found
May 18 17:18:54 DEBUG[2541] chan_sip.c: Stopping retransmission on '66c3d8d65c3cde5734cefd8e6fcff60c@65.23.24.2' of Request 102: Match Found
May 18 17:18:55 DEBUG[2541] acl.c: ##### Testing 192.168.1.127 with 192.168.1.0
May 18 17:18:57 VERBOSE[2537] logger.c: -- Channel 0/8, span 1 got hangup request
May 18 17:18:57 DEBUG[5536] channel.c: Didn't get a frame from channel: Zap/8-1
May 18 17:18:57 DEBUG[5536] channel.c: Bridge stops bridging channels Zap/8-1 and SIP/6643-09c3b518
May 18 17:18:57 DEBUG[5536] chan_sip.c: update_call_counter(6643) - decrement call limit counter
May 18 17:18:57 DEBUG[5536] app_dial.c: Exiting with DIALSTATUS=ANSWER.
May 18 17:18:57 VERBOSE[5536] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/8-1' in macro 'dial'
May 18 17:18:57 VERBOSE[5536] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/8-1' in macro 'exten-vm'
May 18 17:18:57 VERBOSE[5536] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/8-1'
May 18 17:18:57 VERBOSE[5536] logger.c: -- Executing Macro("Zap/8-1", "hangupcall") in new stack
May 18 17:18:57 VERBOSE[5536] logger.c: -- Executing ResetCDR("Zap/8-1", "w") in new stack
May 18 17:18:57 DEBUG[5536] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 18 17:18:57 DEBUG[5536] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2007-05-18 17:18:44','\"LADUE, MO\" <3147910062>','3147910062','6643','from-pstn', 'Zap/8-1','SIP/6643-09c3b518','ResetCDR','w',13,12,'ANSWERED',3,'','1179526724.65')
May 18 17:18:57 VERBOSE[5536] logger.c: -- Executing NoCDR("Zap/8-1", "") in new stack
May 18 17:18:57 NOTICE[5536] cdr.c: CDR on channel 'Zap/8-1' not posted
May 18 17:18:57 NOTICE[5536] cdr.c: CDR on channel 'Zap/8-1' lacks end
May 18 17:18:57 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:57 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "1?skiprg") in new stack
May 18 17:18:57 VERBOSE[5536] logger.c: -- Goto (macro-hangupcall,s,6)
May 18 17:18:57 DEBUG[5536] pbx.c: Expression result is '1'
May 18 17:18:57 VERBOSE[5536] logger.c: -- Executing GotoIf("Zap/8-1", "1?theend") in new stack
May 18 17:18:57 VERBOSE[5536] logger.c: -- Goto (macro-hangupcall,s,9)
May 18 17:18:57 VERBOSE[5536] logger.c: -- Executing Wait("Zap/8-1", "5") in new stack
May 18 17:18:57 VERBOSE[5536] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Zap/8-1' in macro 'hangupcall'
May 18 17:18:57 VERBOSE[5536] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Zap/8-1'
May 18 17:18:57 DEBUG[5536] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/8-1
May 18 17:18:57 DEBUG[5536] chan_zap.c: Hangup: channel: 8 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 18 17:18:57 DEBUG[5536] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
May 18 17:18:57 DEBUG[5536] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/8-1
May 18 17:18:57 DEBUG[5536] chan_zap.c: Updated conferencing on 8, with 0 conference users
May 18 17:18:57 DEBUG[5536] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/8-1
May 18 17:18:57 VERBOSE[5536] logger.c: -- Hungup 'Zap/8-1'
May 18 17:18:57 DEBUG[2541] chan_sip.c: Stopping retransmission on '165e156732f8c4517e9eb96544b334cb@192.168.1.250' of Request 103: Match Found
May 18 17:18:59 DEBUG[2541] acl.c: ##### Testing 192.168.1.141 with 192.168.1.0
May 18 17:19:00 DEBUG[2541] acl.c: ##### Testing 192.168.1.139 with 192.168.1.0
May 18 17:19:00 DEBUG[2541] chan_sip.c: Auto destroying call '4544d9086c7efad437d391a83ed8632a@192.168.1.131'
May 18 17:19:00 DEBUG[2541] chan_sip.c: Auto destroying call '11e7fa8adc0abc289bd872f6a20fde91@192.168.1.148'
May 18 17:19:02 DEBUG[2541] acl.c: ##### Testing 192.168.1.105 with 192.168.1.0
May 18 17:19:02 DEBUG[2541] acl.c: ##### Testing 192.168.1.126 with 192.168.1.0
May 18 17:19:04 DEBUG[2541] acl.c: ##### Testing 192.168.1.141 with 192.168.1.0
May 18 17:19:04 DEBUG[2541] acl.c: ##### Testing 192.168.1.126 with 192.168.1.0
May 18 17:19:04 DEBUG[2541] acl.c: ##### Testing 192.168.1.139 with 192.168.1.0
May 18 17:19:04 DEBUG[2541] acl.c: ##### Testing 192.168.1.127 with 192.168.1.0
May 18 17:19:04 DEBUG[2541] acl.c: ##### Testing 192.168.1.105 with 192.168.1.0
May 18 17:19:04 DEBUG[2541] chan_sip.c: Stopping retransmission on '4d01c0f446fc45fd34cc28921e761ba5@192.168.1.250' of Request 102: Match Found
May 18 17:19:04 DEBUG[2541] chan_sip.c: Stopping retransmission on '20dd52bb61794fe7364fd54e0c16679d@192.168.1.250' of Request 102: Match Found
May 18 17:19:04 DEBUG[2541] chan_sip.c: Stopping retransmission on '39f9bb4e3f1633307f2ec05a4ef74a53@192.168.1.250' of Request 102: Match Found
May 18 17:19:04 DEBUG[2541] chan_sip.c: Stopping retransmission on '0a04095223bd2cbd51d6de98288e2fa6@192.168.1.250' of Request 102: Match Found
May 18 17:19:04 DEBUG[2541] chan_sip.c: Stopping retransmission on '3b86e82546e24e1074f0b8303ecd8089@192.168.1.250' of Request 102: Match Found