Sep 7 11:11:19 VERBOSE[17453] logger.c: -- Starting simple switch on 'Zap/3-1' Sep 7 11:11:20 VERBOSE[17453] logger.c: -- Executing Wait("Zap/3-1", "1") in new stack Sep 7 11:11:20 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:11:20 DEBUG[17453] chan_zap.c: Got event Polarity Reversal(17) on channel 3 (index 0) Sep 7 11:11:20 DEBUG[17453] chan_zap.c: Ignore switch to REVERSED Polarity on channel 3, state 4 Sep 7 11:11:21 DEBUG[17453] pbx.c: Expression result is '0' Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing GotoIf("Zap/3-1", "0?7") in new stack Sep 7 11:11:21 DEBUG[17453] pbx.c: Not taking any branch Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing LDAPget("Zap/3-1", "CIDNAME=cidname/5104894067|5104894067") in new stack Sep 7 11:11:21 VERBOSE[17453] logger.c: -- LDAPget: varname=CIDNAME, config-section=cidname, keys=5104894067|5104894067 Sep 7 11:11:21 VERBOSE[17453] logger.c: == Parsing '/etc/asterisk/ldap.conf': Sep 7 11:11:21 VERBOSE[17453] logger.c: == Parsing '/etc/asterisk/ldap.conf': Found Sep 7 11:11:21 VERBOSE[17453] logger.c: -- LDAPget: ldap://localhost/dc=localdomain?cn?sub?(&(objectClass=person)(|(telephoneNumber=%s)(mobile=%s))) Sep 7 11:11:21 VERBOSE[17453] logger.c: -- LDAPget: (&(objectClass=person)(|(telephoneNumber=%s)(mobile=%s))) Sep 7 11:11:21 VERBOSE[17453] logger.c: -- LDAPget: ldap://localhost/dc=localdomain?cn?sub?(&(objectClass=person)(|(telephoneNumber=5104894067)(mobile=5104894067))) Sep 7 11:11:21 VERBOSE[17453] logger.c: -- LDAPget: bind to localhost anonymously Sep 7 11:11:21 VERBOSE[17453] logger.c: -- LDAPget: Value not found in directory. Sep 7 11:11:21 DEBUG[17453] pbx.c: Expression result is '1' Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing GotoIf("Zap/3-1", "1?6") in new stack Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Goto (home-incoming,s,6) Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing Set("Zap/3-1", "CALLERID(number)=915104894067") in new stack Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing Dial("Zap/3-1", "Local/1@two-rings&Local/2@two-rings") in new stack Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called 1@two-rings Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called 2@two-rings Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing Dial("Local/1@two-rings-6cfc,2", "SIP/wilson-polycom&SIP/scott-polycom&Zap/1&SIP/kitchen-polycom&IAX2/wilson-diax|20|t") in new stack Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Outgoing Call for wilson-polycom Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called wilson-polycom Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Outgoing Call for scott-polycom Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called scott-polycom Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called 1 Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Outgoing Call for kitchen-polycom Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called kitchen-polycom Sep 7 11:11:21 NOTICE[17453] app_dial.c: Unable to create channel of type 'IAX2' (cause 3 - No route to destination) Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing Set("Local/2@two-rings-1f1c,2", "_ALERT_INFO="Visual"") in new stack Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Executing Dial("Local/2@two-rings-1f1c,2", "SIP/theater-polycom|20|tT") in new stack Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:11:21 DEBUG[17453] chan_sip.c: Outgoing Call for theater-polycom Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Called theater-polycom Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Zap/1-1 is ringing Sep 7 11:11:21 VERBOSE[17453] logger.c: -- Local/1@two-rings-6cfc,1 is ringing Sep 7 11:11:21 DEBUG[17453] chan_zap.c: Requested indication 3 on channel Zap/3-1 Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '462e22cb70958a0c519dc91207138cdc@10.1.1.48' Request 102: Found Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2bfbe6883eeb43f3330245561404e337@10.1.1.48' Request 102: Found Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2ae2ea735e80513609b749943e19b7ea@10.1.1.48' Request 102: Found Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3453b887422c508929d483d37bda41e1@10.1.1.48' Request 102: Found Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '462e22cb70958a0c519dc91207138cdc@10.1.1.48' Request 102: Found Sep 7 11:11:22 VERBOSE[17453] logger.c: -- SIP/scott-polycom-0d90 is ringing Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3453b887422c508929d483d37bda41e1@10.1.1.48' Request 102: Found Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2ae2ea735e80513609b749943e19b7ea@10.1.1.48' Request 102: Found Sep 7 11:11:22 VERBOSE[17453] logger.c: -- SIP/theater-polycom-63c9 is ringing Sep 7 11:11:22 VERBOSE[17453] logger.c: -- SIP/kitchen-polycom-d6aa is ringing Sep 7 11:11:22 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2bfbe6883eeb43f3330245561404e337@10.1.1.48' Request 102: Found Sep 7 11:11:22 VERBOSE[17453] logger.c: -- Local/2@two-rings-1f1c,1 is ringing Sep 7 11:11:22 VERBOSE[17453] logger.c: -- SIP/wilson-polycom-94b6 is ringing Sep 7 11:11:23 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:11:23 DEBUG[17453] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0) Sep 7 11:11:23 DEBUG[17453] chan_zap.c: Exception on 17, channel 1 Sep 7 11:11:23 DEBUG[17453] chan_zap.c: Got event Ringer Off(11) on channel 1 (index 0) Sep 7 11:11:23 VERBOSE[17453] logger.c: -- Zap/1-1 is ringing Sep 7 11:11:25 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:11:25 DEBUG[17453] chan_zap.c: Got event Ring/Answered(2) on channel 3 (index 0) Sep 7 11:11:25 DEBUG[17453] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 1 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '2bfbe6883eeb43f3330245561404e337@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: build_route: Contact hop: Sep 7 11:11:26 DEBUG[17453] channel.c: Dropping duplicate answer! Sep 7 11:11:26 VERBOSE[17453] logger.c: -- SIP/wilson-polycom-94b6 answered Local/1@two-rings-6cfc,2 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: update_call_counter(kitchen-polycom) - decrement call limit counter Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '2ae2ea735e80513609b749943e19b7ea@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '2ae2ea735e80513609b749943e19b7ea@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Hangup: channel: 1 index = 0, normal = 17, callwait = -1, thirdcall = -1 Sep 7 11:11:26 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 1 Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1 Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Updated conferencing on 1, with 0 conference users Sep 7 11:11:26 VERBOSE[17453] logger.c: -- Hungup 'Zap/1-1' Sep 7 11:11:26 DEBUG[17453] chan_sip.c: update_call_counter(scott-polycom) - decrement call limit counter Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '462e22cb70958a0c519dc91207138cdc@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '462e22cb70958a0c519dc91207138cdc@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 VERBOSE[17453] logger.c: -- Local/1@two-rings-6cfc,1 stopped sounds Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Requested indication -1 on channel Zap/3-1 Sep 7 11:11:26 VERBOSE[17453] logger.c: -- Local/1@two-rings-6cfc,1 answered Zap/3-1 Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Took Zap/3-1 off hook Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Enabled echo cancellation on channel 3 Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Engaged echo training on channel 3 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: update_call_counter(theater-polycom) - decrement call limit counter Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '3453b887422c508929d483d37bda41e1@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '3453b887422c508929d483d37bda41e1@10.1.1.48' of Request 102: Match Found Sep 7 11:11:26 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=CANCEL. Sep 7 11:11:26 VERBOSE[17453] logger.c: == Spawn extension (two-rings, 2, 2) exited non-zero on 'Local/2@two-rings-1f1c,2' Sep 7 11:11:26 DEBUG[17453] channel.c: Planning to masquerade channel Zap/3-1 into the structure of Local/1@two-rings-6cfc,2 Sep 7 11:11:26 DEBUG[17453] channel.c: Done planning to masquerade channel Zap/3-1 into the structure of Local/1@two-rings-6cfc,2 Sep 7 11:11:26 DEBUG[17453] chan_local.c: Not posting to queue since already masked on 'Local/1@two-rings-6cfc,1' Sep 7 11:11:26 DEBUG[17453] channel.c: Got clone lock for masquerade on 'Zap/3-1' at 0x9e12d34 Sep 7 11:11:26 DEBUG[17453] channel.c: Putting channel Zap/3-1 in 64/64 formats Sep 7 11:11:26 DEBUG[17453] chan_zap.c: New owner for channel 3 is Zap/3-1 Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Updated conferencing on 3, with 0 conference users Sep 7 11:11:26 DEBUG[17453] chan_zap.c: Updated conferencing on 3, with 0 conference users Sep 7 11:11:26 DEBUG[17453] channel.c: Released clone lock on 'Local/1@two-rings-6cfc,2' Sep 7 11:11:26 DEBUG[17453] channel.c: Done Masquerading Zap/3-1 (6) Sep 7 11:11:26 DEBUG[17453] channel.c: Didn't get a frame from channel: Local/1@two-rings-6cfc,1 Sep 7 11:11:26 DEBUG[17453] channel.c: Bridge stops bridging channels Local/1@two-rings-6cfc,2 and Local/1@two-rings-6cfc,1 Sep 7 11:11:26 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 7 11:11:26 VERBOSE[17453] logger.c: == Spawn extension (home-incoming, s, 7) exited non-zero on 'Local/1@two-rings-6cfc,2' Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '2ae2ea735e80513609b749943e19b7ea@10.1.1.48' of Request 102: Match Not Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '462e22cb70958a0c519dc91207138cdc@10.1.1.48' of Request 102: Match Not Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '3453b887422c508929d483d37bda41e1@10.1.1.48' of Request 102: Match Not Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '3453b887422c508929d483d37bda41e1@10.1.1.48' of Request 102: Match Not Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '2ae2ea735e80513609b749943e19b7ea@10.1.1.48' of Request 102: Match Not Found Sep 7 11:11:26 DEBUG[17453] chan_sip.c: Stopping retransmission on '462e22cb70958a0c519dc91207138cdc@10.1.1.48' of Request 102: Match Not Found Sep 7 11:12:28 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:12:28 DEBUG[17453] chan_zap.c: Got event On hook(1) on channel 3 (index 0) Sep 7 11:12:28 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 3 Sep 7 11:12:28 DEBUG[17453] channel.c: Didn't get a frame from channel: Zap/3-1 Sep 7 11:12:28 DEBUG[17453] channel.c: Bridge stops bridging channels Zap/3-1 and SIP/wilson-polycom-94b6 Sep 7 11:12:28 DEBUG[17453] chan_sip.c: update_call_counter(wilson-polycom) - decrement call limit counter Sep 7 11:12:28 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 7 11:12:28 VERBOSE[17453] logger.c: == Spawn extension (two-rings, 1, 1) exited non-zero on 'Zap/3-1' Sep 7 11:12:28 DEBUG[17453] chan_zap.c: Hangup: channel: 3 index = 0, normal = 20, callwait = -1, thirdcall = -1 Sep 7 11:12:28 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 3 Sep 7 11:12:28 DEBUG[17453] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1 Sep 7 11:12:28 DEBUG[17453] chan_zap.c: Updated conferencing on 3, with 0 conference users Sep 7 11:12:28 VERBOSE[17453] logger.c: -- Hungup 'Zap/3-1' Sep 7 11:12:29 DEBUG[17453] chan_sip.c: Stopping retransmission on '2bfbe6883eeb43f3330245561404e337@10.1.1.48' of Request 103: Match Found Sep 7 11:12:29 VERBOSE[17453] logger.c: -- Got SIP response 500 "Internal Server Error" back from 10.1.1.200 Sep 7 11:14:52 DEBUG[17453] chan_sip.c: Stopping retransmission on 'e61172d1-dd586cdb-13fb564@10.1.1.200' of Request 137: Match Found Sep 7 11:22:42 DEBUG[17453] chan_sip.c: Stopping retransmission on '484d15dd17cff21b220ec7936ba2367d@10.1.1.48' of Request 102: Match Found Sep 7 11:22:50 DEBUG[17453] chan_sip.c: Auto destroying call '53a44b2a-7d482150-a3adcf2d@10.1.1.201' Sep 7 11:24:22 DEBUG[17453] chan_sip.c: Stopping retransmission on '2d000a793413975525b3a4e50b9768e7@10.1.1.48' of Request 102: Match Found Sep 7 11:24:26 DEBUG[17453] chan_sip.c: Auto destroying call 'a11ec3e-ebddd9d0-b7be89ed@10.1.1.200' Sep 7 11:28:58 VERBOSE[17453] logger.c: -- Starting simple switch on 'Zap/3-1' Sep 7 11:28:59 VERBOSE[17453] logger.c: -- Executing Wait("Zap/3-1", "1") in new stack Sep 7 11:28:59 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:28:59 DEBUG[17453] chan_zap.c: Got event Polarity Reversal(17) on channel 3 (index 0) Sep 7 11:28:59 DEBUG[17453] chan_zap.c: Ignore switch to REVERSED Polarity on channel 3, state 4 Sep 7 11:29:00 DEBUG[17453] pbx.c: Expression result is '0' Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing GotoIf("Zap/3-1", "0?7") in new stack Sep 7 11:29:00 DEBUG[17453] pbx.c: Not taking any branch Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing LDAPget("Zap/3-1", "CIDNAME=cidname/8479490444|8479490444") in new stack Sep 7 11:29:00 VERBOSE[17453] logger.c: -- LDAPget: varname=CIDNAME, config-section=cidname, keys=8479490444|8479490444 Sep 7 11:29:00 VERBOSE[17453] logger.c: == Parsing '/etc/asterisk/ldap.conf': Sep 7 11:29:00 VERBOSE[17453] logger.c: == Parsing '/etc/asterisk/ldap.conf': Found Sep 7 11:29:00 VERBOSE[17453] logger.c: -- LDAPget: ldap://localhost/dc=localdomain?cn?sub?(&(objectClass=person)(|(telephoneNumber=%s)(mobile=%s))) Sep 7 11:29:00 VERBOSE[17453] logger.c: -- LDAPget: (&(objectClass=person)(|(telephoneNumber=%s)(mobile=%s))) Sep 7 11:29:00 VERBOSE[17453] logger.c: -- LDAPget: ldap://localhost/dc=localdomain?cn?sub?(&(objectClass=person)(|(telephoneNumber=8479490444)(mobile=8479490444))) Sep 7 11:29:00 VERBOSE[17453] logger.c: -- LDAPget: bind to localhost anonymously Sep 7 11:29:00 VERBOSE[17453] logger.c: -- LDAPget: Value not found in directory. Sep 7 11:29:00 DEBUG[17453] pbx.c: Expression result is '1' Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing GotoIf("Zap/3-1", "1?6") in new stack Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Goto (home-incoming,s,6) Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing Set("Zap/3-1", "CALLERID(number)=918479490444") in new stack Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing Dial("Zap/3-1", "Local/1@two-rings&Local/2@two-rings") in new stack Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called 1@two-rings Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called 2@two-rings Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing Dial("Local/1@two-rings-404c,2", "SIP/wilson-polycom&SIP/scott-polycom&Zap/1&SIP/kitchen-polycom&IAX2/wilson-diax|20|t") in new stack Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Outgoing Call for wilson-polycom Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing Set("Local/2@two-rings-13ba,2", "_ALERT_INFO="Visual"") in new stack Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called wilson-polycom Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Outgoing Call for scott-polycom Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called scott-polycom Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called 1 Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Outgoing Call for kitchen-polycom Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called kitchen-polycom Sep 7 11:29:00 NOTICE[17453] app_dial.c: Unable to create channel of type 'IAX2' (cause 3 - No route to destination) Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Executing Dial("Local/2@two-rings-13ba,2", "SIP/theater-polycom|20|tT") in new stack Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:29:00 DEBUG[17453] chan_sip.c: Outgoing Call for theater-polycom Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Called theater-polycom Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Zap/1-1 is ringing Sep 7 11:29:00 VERBOSE[17453] logger.c: -- Local/1@two-rings-404c,1 is ringing Sep 7 11:29:00 DEBUG[17453] chan_zap.c: Requested indication 3 on channel Zap/3-1 Sep 7 11:29:00 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2d6b3afb6b84f2e545efc99025ada640@10.1.1.48' Request 102: Found Sep 7 11:29:00 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '45bf9c694199de4a3a5e4213233c3d9c@10.1.1.48' Request 102: Found Sep 7 11:29:00 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2563ed27583101cd49e95a676ea3f856@10.1.1.48' Request 102: Found Sep 7 11:29:00 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '29915c20145e137b6fc054e21cc05f5b@10.1.1.48' Request 102: Found Sep 7 11:29:01 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2563ed27583101cd49e95a676ea3f856@10.1.1.48' Request 102: Found Sep 7 11:29:01 VERBOSE[17453] logger.c: -- SIP/kitchen-polycom-797d is ringing Sep 7 11:29:01 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '45bf9c694199de4a3a5e4213233c3d9c@10.1.1.48' Request 102: Found Sep 7 11:29:01 VERBOSE[17453] logger.c: -- SIP/scott-polycom-f29c is ringing Sep 7 11:29:01 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2d6b3afb6b84f2e545efc99025ada640@10.1.1.48' Request 102: Found Sep 7 11:29:01 VERBOSE[17453] logger.c: -- SIP/wilson-polycom-de5c is ringing Sep 7 11:29:01 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '29915c20145e137b6fc054e21cc05f5b@10.1.1.48' Request 102: Found Sep 7 11:29:01 VERBOSE[17453] logger.c: -- SIP/theater-polycom-f6b0 is ringing Sep 7 11:29:01 VERBOSE[17453] logger.c: -- Local/2@two-rings-13ba,1 is ringing Sep 7 11:29:02 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:29:02 DEBUG[17453] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0) Sep 7 11:29:02 DEBUG[17453] chan_zap.c: Exception on 17, channel 1 Sep 7 11:29:02 DEBUG[17453] chan_zap.c: Got event Ringer Off(11) on channel 1 (index 0) Sep 7 11:29:02 VERBOSE[17453] logger.c: -- Zap/1-1 is ringing Sep 7 11:29:04 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:29:04 DEBUG[17453] chan_zap.c: Got event Ring/Answered(2) on channel 3 (index 0) Sep 7 11:29:04 DEBUG[17453] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 1 Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '45bf9c694199de4a3a5e4213233c3d9c@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: build_route: Contact hop: Sep 7 11:29:06 VERBOSE[17453] logger.c: -- SIP/scott-polycom-f29c answered Local/1@two-rings-404c,2 Sep 7 11:29:06 DEBUG[17453] chan_sip.c: update_call_counter(kitchen-polycom) - decrement call limit counter Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2563ed27583101cd49e95a676ea3f856@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2563ed27583101cd49e95a676ea3f856@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Hangup: channel: 1 index = 0, normal = 17, callwait = -1, thirdcall = -1 Sep 7 11:29:06 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 1 Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1 Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Updated conferencing on 1, with 0 conference users Sep 7 11:29:06 VERBOSE[17453] logger.c: -- Hungup 'Zap/1-1' Sep 7 11:29:06 DEBUG[17453] chan_sip.c: update_call_counter(wilson-polycom) - decrement call limit counter Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2d6b3afb6b84f2e545efc99025ada640@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2d6b3afb6b84f2e545efc99025ada640@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] channel.c: Dropping duplicate answer! Sep 7 11:29:06 VERBOSE[17453] logger.c: -- Local/1@two-rings-404c,1 stopped sounds Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Requested indication -1 on channel Zap/3-1 Sep 7 11:29:06 VERBOSE[17453] logger.c: -- Local/1@two-rings-404c,1 answered Zap/3-1 Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Took Zap/3-1 off hook Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Enabled echo cancellation on channel 3 Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Engaged echo training on channel 3 Sep 7 11:29:06 DEBUG[17453] channel.c: Planning to masquerade channel Zap/3-1 into the structure of Local/1@two-rings-404c,2 Sep 7 11:29:06 DEBUG[17453] channel.c: Done planning to masquerade channel Zap/3-1 into the structure of Local/1@two-rings-404c,2 Sep 7 11:29:06 DEBUG[17453] chan_local.c: Not posting to queue since already masked on 'Local/1@two-rings-404c,1' Sep 7 11:29:06 DEBUG[17453] channel.c: Got clone lock for masquerade on 'Zap/3-1' at 0x9e6ea8c Sep 7 11:29:06 DEBUG[17453] channel.c: Putting channel Zap/3-1 in 64/64 formats Sep 7 11:29:06 DEBUG[17453] chan_zap.c: New owner for channel 3 is Zap/3-1 Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Updated conferencing on 3, with 0 conference users Sep 7 11:29:06 DEBUG[17453] chan_zap.c: Updated conferencing on 3, with 0 conference users Sep 7 11:29:06 DEBUG[17453] channel.c: Released clone lock on 'Local/1@two-rings-404c,2' Sep 7 11:29:06 DEBUG[17453] channel.c: Done Masquerading Zap/3-1 (6) Sep 7 11:29:06 DEBUG[17453] chan_sip.c: update_call_counter(theater-polycom) - decrement call limit counter Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '29915c20145e137b6fc054e21cc05f5b@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '29915c20145e137b6fc054e21cc05f5b@10.1.1.48' of Request 102: Match Found Sep 7 11:29:06 DEBUG[17453] channel.c: Didn't get a frame from channel: Local/1@two-rings-404c,1 Sep 7 11:29:06 DEBUG[17453] channel.c: Bridge stops bridging channels Local/1@two-rings-404c,2 and Local/1@two-rings-404c,1 Sep 7 11:29:06 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 7 11:29:06 VERBOSE[17453] logger.c: == Spawn extension (home-incoming, s, 7) exited non-zero on 'Local/1@two-rings-404c,2' Sep 7 11:29:06 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=CANCEL. Sep 7 11:29:06 VERBOSE[17453] logger.c: == Spawn extension (two-rings, 2, 2) exited non-zero on 'Local/2@two-rings-13ba,2' Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2563ed27583101cd49e95a676ea3f856@10.1.1.48' of Request 102: Match Not Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2d6b3afb6b84f2e545efc99025ada640@10.1.1.48' of Request 102: Match Not Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '29915c20145e137b6fc054e21cc05f5b@10.1.1.48' of Request 102: Match Not Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '29915c20145e137b6fc054e21cc05f5b@10.1.1.48' of Request 102: Match Not Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2563ed27583101cd49e95a676ea3f856@10.1.1.48' of Request 102: Match Not Found Sep 7 11:29:06 DEBUG[17453] chan_sip.c: Stopping retransmission on '2d6b3afb6b84f2e545efc99025ada640@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:01 VERBOSE[17453] logger.c: -- Starting simple switch on 'Zap/4-1' Sep 7 11:31:02 VERBOSE[17453] logger.c: -- Executing Wait("Zap/4-1", "1") in new stack Sep 7 11:31:02 DEBUG[17453] chan_zap.c: Exception on 21, channel 4 Sep 7 11:31:02 DEBUG[17453] chan_zap.c: Got event Polarity Reversal(17) on channel 4 (index 0) Sep 7 11:31:02 DEBUG[17453] chan_zap.c: Ignore switch to REVERSED Polarity on channel 4, state 4 Sep 7 11:31:03 DEBUG[17453] pbx.c: Expression result is '0' Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing GotoIf("Zap/4-1", "0?7") in new stack Sep 7 11:31:03 DEBUG[17453] pbx.c: Not taking any branch Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing LDAPget("Zap/4-1", "CIDNAME=cidname/6509066629|6509066629") in new stack Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: varname=CIDNAME, config-section=cidname, keys=6509066629|6509066629 Sep 7 11:31:03 VERBOSE[17453] logger.c: == Parsing '/etc/asterisk/ldap.conf': Sep 7 11:31:03 VERBOSE[17453] logger.c: == Parsing '/etc/asterisk/ldap.conf': Found Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: ldap://localhost/dc=localdomain?cn?sub?(&(objectClass=person)(|(telephoneNumber=%s)(mobile=%s))) Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: (&(objectClass=person)(|(telephoneNumber=%s)(mobile=%s))) Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: ldap://localhost/dc=localdomain?cn?sub?(&(objectClass=person)(|(telephoneNumber=6509066629)(mobile=6509066629))) Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: bind to localhost anonymously Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: cn=Christine Ying Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: convert: UTF-8 -> ISO-8859-1 Sep 7 11:31:03 VERBOSE[17453] logger.c: -- LDAPget: set CIDNAME='Christine Ying' Sep 7 11:31:03 DEBUG[17453] pbx.c: Expression result is '0' Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing GotoIf("Zap/4-1", "0?6") in new stack Sep 7 11:31:03 DEBUG[17453] pbx.c: Not taking any branch Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing Set("Zap/4-1", "CALLERID(name)=Christine Ying") in new stack Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing Set("Zap/4-1", "CALLERID(number)=916509066629") in new stack Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing Dial("Zap/4-1", "Local/1@two-rings&Local/2@two-rings") in new stack Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called 1@two-rings Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called 2@two-rings Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing Dial("Local/1@two-rings-44c9,2", "SIP/wilson-polycom&SIP/scott-polycom&Zap/1&SIP/kitchen-polycom&IAX2/wilson-diax|20|t") in new stack Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Outgoing Call for wilson-polycom Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called wilson-polycom Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Outgoing Call for scott-polycom Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called scott-polycom Sep 7 11:31:03 DEBUG[17453] channel.c: Avoiding initial deadlock for 'Zap/1-1' Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called 1 Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Outgoing Call for kitchen-polycom Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called kitchen-polycom Sep 7 11:31:03 NOTICE[17453] app_dial.c: Unable to create channel of type 'IAX2' (cause 3 - No route to destination) Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing Set("Local/2@two-rings-5883,2", "_ALERT_INFO="Visual"") in new stack Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Executing Dial("Local/2@two-rings-5883,2", "SIP/theater-polycom|20|tT") in new stack Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Setting NAT on RTP to 0 Sep 7 11:31:03 DEBUG[17453] chan_sip.c: Outgoing Call for theater-polycom Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Called theater-polycom Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Zap/1-1 is ringing Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Local/1@two-rings-44c9,1 is ringing Sep 7 11:31:03 DEBUG[17453] chan_zap.c: Requested indication 3 on channel Zap/4-1 Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2a78246215fd17475bece0d7019a8717@10.1.1.48' Request 102: Found Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '6a6168c342926a900c7bc4f77b1fbd4e@10.1.1.48' Request 102: Found Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '114cc29b25aad616156b2af8322b8a53@10.1.1.48' Request 102: Found Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '49e8c72f0b82a57945e0e78d691d2529@10.1.1.48' Request 102: Found Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '6a6168c342926a900c7bc4f77b1fbd4e@10.1.1.48' Request 102: Found Sep 7 11:31:03 VERBOSE[17453] logger.c: -- SIP/kitchen-polycom-36cb is ringing Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '114cc29b25aad616156b2af8322b8a53@10.1.1.48' Request 102: Found Sep 7 11:31:03 VERBOSE[17453] logger.c: -- SIP/theater-polycom-8103 is ringing Sep 7 11:31:03 VERBOSE[17453] logger.c: -- Local/2@two-rings-5883,1 is ringing Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2a78246215fd17475bece0d7019a8717@10.1.1.48' Request 102: Found Sep 7 11:31:03 VERBOSE[17453] logger.c: -- SIP/wilson-polycom-6573 is ringing Sep 7 11:31:03 DEBUG[17453] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '49e8c72f0b82a57945e0e78d691d2529@10.1.1.48' Request 102: Found Sep 7 11:31:03 VERBOSE[17453] logger.c: -- SIP/scott-polycom-17c6 is ringing Sep 7 11:31:04 DEBUG[17453] chan_zap.c: Exception on 21, channel 4 Sep 7 11:31:04 DEBUG[17453] chan_zap.c: Got event Ring Begin(18) on channel 4 (index 0) Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Exception on 17, channel 1 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Got event Ringer Off(11) on channel 1 (index 0) Sep 7 11:31:05 VERBOSE[17453] logger.c: -- Zap/1-1 is ringing Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '2a78246215fd17475bece0d7019a8717@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: build_route: Contact hop: Sep 7 11:31:05 VERBOSE[17453] logger.c: -- SIP/wilson-polycom-6573 answered Local/1@two-rings-44c9,2 Sep 7 11:31:05 DEBUG[17453] chan_sip.c: update_call_counter(kitchen-polycom) - decrement call limit counter Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '6a6168c342926a900c7bc4f77b1fbd4e@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '6a6168c342926a900c7bc4f77b1fbd4e@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Hangup: channel: 1 index = 0, normal = 17, callwait = -1, thirdcall = -1 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 1 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Updated conferencing on 1, with 0 conference users Sep 7 11:31:05 VERBOSE[17453] logger.c: -- Hungup 'Zap/1-1' Sep 7 11:31:05 DEBUG[17453] chan_sip.c: update_call_counter(scott-polycom) - decrement call limit counter Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '49e8c72f0b82a57945e0e78d691d2529@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '49e8c72f0b82a57945e0e78d691d2529@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] channel.c: Dropping duplicate answer! Sep 7 11:31:05 VERBOSE[17453] logger.c: -- Local/1@two-rings-44c9,1 stopped sounds Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Requested indication -1 on channel Zap/4-1 Sep 7 11:31:05 VERBOSE[17453] logger.c: -- Local/1@two-rings-44c9,1 answered Zap/4-1 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Took Zap/4-1 off hook Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Enabled echo cancellation on channel 4 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Engaged echo training on channel 4 Sep 7 11:31:05 DEBUG[17453] chan_sip.c: update_call_counter(theater-polycom) - decrement call limit counter Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Acked pending invite 102 Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '114cc29b25aad616156b2af8322b8a53@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '114cc29b25aad616156b2af8322b8a53@10.1.1.48' of Request 102: Match Found Sep 7 11:31:05 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=CANCEL. Sep 7 11:31:05 VERBOSE[17453] logger.c: == Spawn extension (two-rings, 2, 2) exited non-zero on 'Local/2@two-rings-5883,2' Sep 7 11:31:05 DEBUG[17453] channel.c: Planning to masquerade channel Zap/4-1 into the structure of Local/1@two-rings-44c9,2 Sep 7 11:31:05 DEBUG[17453] channel.c: Done planning to masquerade channel Zap/4-1 into the structure of Local/1@two-rings-44c9,2 Sep 7 11:31:05 DEBUG[17453] chan_local.c: Not posting to queue since already masked on 'Local/1@two-rings-44c9,1' Sep 7 11:31:05 DEBUG[17453] channel.c: Got clone lock for masquerade on 'Zap/4-1' at 0x9e6ea8c Sep 7 11:31:05 DEBUG[17453] channel.c: Putting channel Zap/4-1 in 64/64 formats Sep 7 11:31:05 DEBUG[17453] chan_zap.c: New owner for channel 4 is Zap/4-1 Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Updated conferencing on 4, with 0 conference users Sep 7 11:31:05 DEBUG[17453] chan_zap.c: Updated conferencing on 4, with 0 conference users Sep 7 11:31:05 DEBUG[17453] channel.c: Released clone lock on 'Local/1@two-rings-44c9,2' Sep 7 11:31:05 DEBUG[17453] channel.c: Done Masquerading Zap/4-1 (6) Sep 7 11:31:05 DEBUG[17453] channel.c: Didn't get a frame from channel: Local/1@two-rings-44c9,1 Sep 7 11:31:05 DEBUG[17453] channel.c: Bridge stops bridging channels Local/1@two-rings-44c9,2 and Local/1@two-rings-44c9,1 Sep 7 11:31:05 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 7 11:31:05 VERBOSE[17453] logger.c: == Spawn extension (home-incoming, s, 7) exited non-zero on 'Local/1@two-rings-44c9,2' Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '6a6168c342926a900c7bc4f77b1fbd4e@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '114cc29b25aad616156b2af8322b8a53@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '49e8c72f0b82a57945e0e78d691d2529@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '6a6168c342926a900c7bc4f77b1fbd4e@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '114cc29b25aad616156b2af8322b8a53@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:05 DEBUG[17453] chan_sip.c: Stopping retransmission on '49e8c72f0b82a57945e0e78d691d2529@10.1.1.48' of Request 102: Match Not Found Sep 7 11:31:26 DEBUG[17453] channel.c: Didn't get a frame from channel: SIP/wilson-polycom-6573 Sep 7 11:31:26 DEBUG[17453] channel.c: Bridge stops bridging channels Zap/4-1 and SIP/wilson-polycom-6573 Sep 7 11:31:26 DEBUG[17453] chan_sip.c: update_call_counter(wilson-polycom) - decrement call limit counter Sep 7 11:31:26 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 7 11:31:26 VERBOSE[17453] logger.c: == Spawn extension (two-rings, 1, 1) exited non-zero on 'Zap/4-1' Sep 7 11:31:26 DEBUG[17453] chan_zap.c: Hangup: channel: 4 index = 0, normal = 21, callwait = -1, thirdcall = -1 Sep 7 11:31:26 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 4 Sep 7 11:31:26 DEBUG[17453] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/4-1 Sep 7 11:31:26 DEBUG[17453] chan_zap.c: Updated conferencing on 4, with 0 conference users Sep 7 11:31:26 VERBOSE[17453] logger.c: -- Hungup 'Zap/4-1' Sep 7 11:31:31 DEBUG[17453] chan_zap.c: Exception on 20, channel 3 Sep 7 11:31:31 DEBUG[17453] chan_zap.c: Got event On hook(1) on channel 3 (index 0) Sep 7 11:31:31 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 3 Sep 7 11:31:31 DEBUG[17453] channel.c: Didn't get a frame from channel: Zap/3-1 Sep 7 11:31:31 DEBUG[17453] channel.c: Bridge stops bridging channels Zap/3-1 and SIP/scott-polycom-f29c Sep 7 11:31:31 DEBUG[17453] chan_sip.c: update_call_counter(scott-polycom) - decrement call limit counter Sep 7 11:31:31 DEBUG[17453] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 7 11:31:31 VERBOSE[17453] logger.c: == Spawn extension (two-rings, 1, 1) exited non-zero on 'Zap/3-1' Sep 7 11:31:31 DEBUG[17453] chan_zap.c: Hangup: channel: 3 index = 0, normal = 20, callwait = -1, thirdcall = -1 Sep 7 11:31:31 DEBUG[17453] chan_zap.c: disabled echo cancellation on channel 3 Sep 7 11:31:31 DEBUG[17453] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1 Sep 7 11:31:31 DEBUG[17453] chan_zap.c: Updated conferencing on 3, with 0 conference users Sep 7 11:31:31 VERBOSE[17453] logger.c: -- Hungup 'Zap/3-1' Sep 7 11:31:31 DEBUG[17453] chan_sip.c: Stopping retransmission on '45bf9c694199de4a3a5e4213233c3d9c@10.1.1.48' of Request 103: Match Found Sep 7 11:31:32 DEBUG[17453] chan_sip.c: Auto destroying call 'd1897748-f95ebf6e-ec4abb25@10.1.1.204' Sep 7 11:31:40 DEBUG[17453] chan_sip.c: Auto destroying call '3b501911-4c048fff-3eeaecc6@10.1.1.202' Sep 7 11:31:44 DEBUG[17453] chan_sip.c: Auto destroying call '235aca78-dd4310ca-9b394431@10.1.1.203'