Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Allocating new SIP dialog for 0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4 - INVITE (With RTP) Sep 20 14:24:53 DEBUG[30042] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Sep 20 14:24:53 DEBUG[30042] chan_sip.c: * SIP extension value: 17 for call 0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Setting NAT on RTP to 0 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Setting NAT on VRTP to 0 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4 Their Tag 0009b7da07e989ed7009ae66-728a7107 Our tag: as5440ae23 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Stopping retransmission on '0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4' of Response 101: Match Found Sep 20 14:24:53 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4 Their Tag 0009b7da07e989ed7009ae66-728a7107 Our tag: as5440ae23 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Setting NAT on RTP to 0 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Setting NAT on VRTP to 0 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Checking SIP call limits for device X88381 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: Updating call counter for incoming call Sep 20 14:24:53 DEBUG[30042] chan_sip.c: build_route: Contact hop: Sep 20 14:24:53 DEBUG[30056] pbx.c: Launching 'Dial' Sep 20 14:24:53 DEBUG[30056] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Sep 20 14:24:53 DEBUG[30056] chan_sip.c: Setting NAT on RTP to 0 Sep 20 14:24:53 DEBUG[30056] chan_sip.c: Setting NAT on VRTP to 0 Sep 20 14:24:53 DEBUG[30033] chan_sip.c: Checking device state for peer X88381 Sep 20 14:24:53 DEBUG[30033] devicestate.c: Changing state for SIP/X88381 - state 2 (In use) Sep 20 14:24:53 DEBUG[30056] channel.c: Not copying variable STACK-SuperUsers-87762-1. Sep 20 14:24:53 DEBUG[30056] channel.c: Not copying variable SIPCALLID. Sep 20 14:24:53 DEBUG[30056] channel.c: Not copying variable SIPUSERAGENT. Sep 20 14:24:53 DEBUG[30056] channel.c: Not copying variable SIPDOMAIN. Sep 20 14:24:53 DEBUG[30056] channel.c: Not copying variable SIPURI. Sep 20 14:24:53 DEBUG[30057] app_queue.c: Device 'SIP/X88381' changed to state '2' (In use) but we don't care because they're not a member of any queue. Sep 20 14:24:53 DEBUG[30056] chan_sip.c: Outgoing Call for 87762 Sep 20 14:24:53 DEBUG[30056] chan_sip.c: Updating call counter for outgoing call Sep 20 14:24:53 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0e91e5cc4731316a2c049125234f34d8@AsteriskServer Their Tag Our tag: as43771e75 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0e91e5cc4731316a2c049125234f34d8@AsteriskServer' Request 102: Found Sep 20 14:24:53 DEBUG[30042] chan_sip.c: SIP response 100 to standard invite Sep 20 14:24:53 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0e91e5cc4731316a2c049125234f34d8@AsteriskServer Their Tag 16958068 Our tag: as43771e75 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0e91e5cc4731316a2c049125234f34d8@AsteriskServer' Request 102: Found Sep 20 14:24:53 DEBUG[30042] chan_sip.c: SIP response 180 to standard invite Sep 20 14:24:53 DEBUG[30033] chan_sip.c: Checking device state for peer ccm-gw Sep 20 14:24:53 DEBUG[30033] devicestate.c: Changing state for SIP/ccm-gw - state 6 (Ringing) Sep 20 14:24:53 DEBUG[30058] app_queue.c: Device 'SIP/ccm-gw' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. Sep 20 14:24:53 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0e91e5cc4731316a2c049125234f34d8@AsteriskServer Their Tag 16958068 Our tag: as43771e75 Sep 20 14:24:53 DEBUG[30042] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0e91e5cc4731316a2c049125234f34d8@AsteriskServer' Request 102: Found Sep 20 14:24:53 DEBUG[30042] chan_sip.c: SIP response 183 to standard invite Sep 20 14:24:53 DEBUG[30056] rtp.c: Ooh, format changed from unknown to ulaw Sep 20 14:24:53 DEBUG[30056] rtp.c: Ooh, format changed from unknown to ulaw Sep 20 14:24:55 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0e91e5cc4731316a2c049125234f34d8@AsteriskServer Their Tag 16958068 Our tag: as43771e75 Sep 20 14:24:55 DEBUG[30042] chan_sip.c: Acked pending invite 102 Sep 20 14:24:55 DEBUG[30042] chan_sip.c: Stopping retransmission on '0e91e5cc4731316a2c049125234f34d8@AsteriskServer' of Request 102: Match Found Sep 20 14:24:55 DEBUG[30042] chan_sip.c: SIP response 200 to standard invite Sep 20 14:24:55 DEBUG[30042] chan_sip.c: build_route: Contact hop: Sep 20 14:24:55 DEBUG[30033] chan_sip.c: Checking device state for peer ccm-gw Sep 20 14:24:55 DEBUG[30033] devicestate.c: Changing state for SIP/ccm-gw - state 2 (In use) Sep 20 14:24:55 DEBUG[30059] app_queue.c: Device 'SIP/ccm-gw' changed to state '2' (In use) but we don't care because they're not a member of any queue. Sep 20 14:24:55 DEBUG[30056] chan_sip.c: sip_answer(SIP/X88381-09c41980) Sep 20 14:24:55 DEBUG[30033] chan_sip.c: Checking device state for peer X88381 Sep 20 14:24:55 DEBUG[30033] devicestate.c: Changing state for SIP/X88381 - state 2 (In use) Sep 20 14:24:55 DEBUG[30060] app_queue.c: Device 'SIP/X88381' changed to state '2' (In use) but we don't care because they're not a member of any queue. Sep 20 14:24:55 DEBUG[30042] chan_sip.c: = No match Their Call ID: 0e91e5cc4731316a2c049125234f34d8@AsteriskServer Their Tag 16958068 Our tag: as43771e75 Sep 20 14:24:55 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4 Their Tag 0009b7da07e989ed7009ae66-728a7107 Our tag: as6b354d1e Sep 20 14:24:55 DEBUG[30042] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Sep 20 14:24:55 DEBUG[30042] chan_sip.c: Stopping retransmission on '0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4' of Response 102: Match Found Sep 20 14:25:01 DEBUG[30056] rtp.c: Sending dtmf: 35 (#), at 1.2.3.4 Sep 20 14:25:01 DEBUG[30056] channel.c: Got DTMF on channel (SIP/X88381-09c41980) Sep 20 14:25:01 DEBUG[30056] channel.c: Bridge stops bridging channels SIP/X88381-09c41980 and SIP/ccm-gw-09c49fd8 Sep 20 14:25:01 DEBUG[30056] res_features.c: Feature interpret: chan=SIP/X88381-09c41980, peer=SIP/ccm-gw-09c49fd8, sense=1, features=18 Sep 20 14:25:01 DEBUG[30056] res_features.c: Set time limit to 500 Sep 20 14:25:09 DEBUG[30042] chan_sip.c: = Found Their Call ID: 0e91e5cc4731316a2c049125234f34d8@AsteriskServer Their Tag 16958068 Our tag: as43771e75 Sep 20 14:25:09 DEBUG[30042] chan_sip.c: **** Received BYE (8) - Command in SIP BYE Sep 20 14:25:09 DEBUG[30056] channel.c: Bridge stops because we're zombie or need a soft hangup: c0=SIP/X88381-09c41980, c1=SIP/ccm-gw-09c49fd8, flags: No,No,No,Yes Sep 20 14:25:09 DEBUG[30056] channel.c: Bridge stops bridging channels SIP/X88381-09c41980 and SIP/ccm-gw-09c49fd8 Sep 20 14:25:09 DEBUG[30056] res_features.c: Timed out for feature! Sep 20 14:25:09 DEBUG[30056] channel.c: Hanging up channel 'SIP/ccm-gw-09c49fd8' Sep 20 14:25:09 DEBUG[30056] chan_sip.c: Hangup call SIP/ccm-gw-09c49fd8, SIP callid 0e91e5cc4731316a2c049125234f34d8@AsteriskServer) Sep 20 14:25:09 DEBUG[30056] chan_sip.c: update_call_counter(87762) - decrement call limit counter Sep 20 14:25:09 DEBUG[30056] chan_sip.c: Updating call counter for outgoing call Sep 20 14:25:09 DEBUG[30033] chan_sip.c: Checking device state for peer ccm-gw Sep 20 14:25:09 DEBUG[30033] devicestate.c: Changing state for SIP/ccm-gw - state 1 (Not in use) Sep 20 14:25:09 DEBUG[30062] app_queue.c: Device 'SIP/ccm-gw' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Sep 20 14:25:09 DEBUG[30056] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 20 14:25:09 DEBUG[30056] pbx.c: Spawn extension (SuperUsers,87762,1) exited non-zero on 'SIP/X88381-09c41980' Sep 20 14:25:09 DEBUG[30056] channel.c: Hanging up channel 'SIP/X88381-09c41980' Sep 20 14:25:09 DEBUG[30056] chan_sip.c: Hangup call SIP/X88381-09c41980, SIP callid 0009b7da-07e9000d-5929d1cc-3d78c76f@1.2.3.4)