Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:7023 check_user_full: Setting NAT on RTP to 0 Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on 'fe7daf2e-76e4f0b0@192.168.21.54' of Response 101: Match Found Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:7023 check_user_full: Setting NAT on RTP to 0 Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:10335 handle_request_invite: Checking SIP call limits for device 204 Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:6033 build_route: build_route: Contact hop: 204 -- Executing MacroIf("SIP/204-5b35", "1?dodial|SIP/202") in new stack -- Executing Dial("SIP/204-5b35", "SIP/202") in new stack Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:1864 create_addr_from_peer: Setting NAT on RTP to 0 Mar 17 21:23:21 DEBUG[38720]: chan_sip.c:2058 sip_call: Outgoing Call for 202 -- Called 202 Mar 17 21:23:22 DEBUG[38720]: chan_sip.c:1447 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '51fe4ca30e598b6318ce17f10285dbf0@192.168.21.9' Request 102: Found Mar 17 21:23:22 DEBUG[38720]: chan_sip.c:1447 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '51fe4ca30e598b6318ce17f10285dbf0@192.168.21.9' Request 102: Found -- SIP/202-6adb is ringing Mar 17 21:23:24 DEBUG[38720]: chan_sip.c:1372 __sip_ack: Acked pending invite 102 Mar 17 21:23:24 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '51fe4ca30e598b6318ce17f10285dbf0@192.168.21.9' of Request 102: Match Found Mar 17 21:23:24 DEBUG[38720]: chan_sip.c:6033 build_route: build_route: Contact hop: -- SIP/202-6adb answered SIP/204-5b35 -- Attempting native bridge of SIP/204-5b35 and SIP/202-6adb Mar 17 21:23:24 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on 'fe7daf2e-76e4f0b0@192.168.21.54' of Response 102: Match Found Mar 17 21:23:25 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '51ce954051a8c6b51f8dbef512f3d702@192.168.21.9' of Request 102: Match Found Mar 17 21:23:26 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '7eeec6706e26242a0a1079e53fb2ae8c@192.168.21.9' of Request 102: Match Found Mar 17 21:23:26 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '0f50125f51e622d35bf874a614b26d76@192.168.21.9' of Request 102: Match Found -- Started music on hold, class 'default', on channel 'SIP/204-5b35' Mar 17 21:23:29 DEBUG[38720]: channel.c:1984 ast_read: Auto-deactivating generator -- Stopped music on hold on SIP/204-5b35 Mar 17 21:23:29 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '51fe4ca30e598b6318ce17f10285dbf0@192.168.21.9' of Response 1: Match Found Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:6747 get_refer_info: Unsupervised transfer to (Refer-To): 204 Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:6749 get_refer_info: Transferred by (Referred-by: ) 202 Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:6750 get_refer_info: Transfer Contact Info (REFER_CONTACT) Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:10521 handle_request_refer: 202 Accepted (blind) Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:10526 handle_request_refer: Got SIP blind transfer, applying to 'SIP/204-5b35' Mar 17 21:23:32 DEBUG[38720]: channel.c:3248 ast_generic_bridge: Nobody there, continuing... Mar 17 21:23:32 DEBUG[38720]: channel.c:3264 ast_generic_bridge: Didn't get a frame from channel: SIP/204-5b35 Mar 17 21:23:32 DEBUG[38720]: channel.c:3539 ast_channel_bridge: Bridge stops bridging channels SIP/204-5b35 and SIP/202-6adb Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:2416 sip_hangup: update_call_counter(202) - decrement call limit counter Mar 17 21:23:32 DEBUG[38720]: app_dial.c:1587 dial_exec_full: Exiting with DIALSTATUS=ANSWER. == Spawn extension (OriginateInternal, 204, 0) exited non-zero on 'SIP/204-5b35' in macro 'dodial' Mar 17 21:23:32 DEBUG[38720]: pbx.c:2329 __ast_pbx_run: Extension 204, priority 0 returned normally even though call was hung up Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '"Kitchen Phone" <204>' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '204' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '202' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'OriginateInternal' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'SIP/204-5b35' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'SIP/202-6adb' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'Dial' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'SIP/202' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '2006-03-17 21:23:21' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '2006-03-17 21:23:24' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '2006-03-17 21:23:32' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '11' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '8' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'ANSWERED' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '(null)' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '1142652201.0' Mar 17 21:23:32 DEBUG[38720]: pbx.c:1510 pbx_substitute_variables_helper_full: Function result is '(null)' Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:2416 sip_hangup: update_call_counter(204) - decrement call limit counter Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on 'fe7daf2e-76e4f0b0@192.168.21.54' of Request 102: Match Found Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '51fe4ca30e598b6318ce17f10285dbf0@192.168.21.9' of Request 103: Match Found Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '51fe4ca30e598b6318ce17f10285dbf0@192.168.21.9' of Request 104: Match Found -- Incoming call: Got SIP response 500 "Internal Server Error" back from 192.168.21.52 Mar 17 21:23:32 DEBUG[38720]: chan_sip.c:10923 handle_request: That's odd... Got a response on a call we dont know about. Cseq 104 Cmd SIP/2.0