[root@asterisk04 ~]# asterisk -rvvvvvvvvvvvvvvvvvvvvvvdddddddddddddddddddddddd Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf == Parsing '/etc/asterisk/asterisk.conf': Found == Parsing '/etc/asterisk/extconfig.conf': Found Asterisk 1.2.17, Copyright (C) 1999 - 2006 Digium, Inc. and others. Created by Mark Spencer Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type 'show license' for details. ========================================================================= Connected to Asterisk 1.2.17 currently running on asterisk04 (pid = 15208) Verbosity was 3 and is now 22 Core debug was 0 and is now 24 Apr 6 22:53:00 WARNING[15258]: chan_oh323.c:4250 oh323_gk_check: Failed to register with gatekeeper 'XXX.XXX.XXX.242'. -- Retrying gatekeeper registration. Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 344890ff281bae7b3e384d36086e4a80@127.0.0.1 Their Tag Our tag: as4a7f868b Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 6defb13f715680ae17367f0d787ad8d9@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as20c98505 Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for 220147137@XXX.XXX.XXX.154 - INVITE (With RTP) Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:11320 handle_request: **** Received INVITE (5) - Command in SIP INVITE Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:10671 handle_request_invite: Checking SIP call limits for device Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:2228 update_call_counter: Updating call counter for incoming call Apr 6 22:53:08 DEBUG[15250]: chan_sip.c:6269 build_route: build_route: Contact hop: Apr 6 22:53:08 WARNING[15250]: chan_sip.c:4299 __transmit_response: 100 Trying Apr 6 22:53:08 DEBUG[15228]: chan_sip.c:11868 sip_devicestate: Checking device state for peer 5060 Apr 6 22:53:08 DEBUG[15228]: devicestate.c:187 do_state_change: Changing state for SIP/5060 - state 4 (Invalid) Apr 6 22:53:08 DEBUG[15279]: pbx.c:1697 pbx_extension_helper: Launching 'Wait' -- Executing Wait("SIP/5060-098fe228", "2") in new stack Apr 6 22:53:08 DEBUG[15280]: app_queue.c:500 changethread: Device 'SIP/5060' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. Apr 6 22:53:10 DEBUG[15279]: pbx.c:1697 pbx_extension_helper: Launching 'Dial' -- Executing Dial("SIP/5060-098fe228", "SIP/33XXXXXXXXX@XXX.XXX.XXX.154") in new stack Apr 6 22:53:10 DEBUG[15279]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Apr 6 22:53:10 DEBUG[15279]: channel.c:2908 ast_channel_inherit_variables: Not copying variable STACK-from-sip-external-7676-2. Apr 6 22:53:10 DEBUG[15279]: channel.c:2908 ast_channel_inherit_variables: Not copying variable STACK-from-sip-external-7676-1. Apr 6 22:53:10 DEBUG[15279]: channel.c:2908 ast_channel_inherit_variables: Not copying variable SIPCALLID. Apr 6 22:53:10 DEBUG[15279]: channel.c:2908 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. Apr 6 22:53:10 DEBUG[15279]: channel.c:2908 ast_channel_inherit_variables: Not copying variable SIPURI. Apr 6 22:53:10 DEBUG[15279]: chan_sip.c:2083 sip_call: Outgoing Call for 33XXXXXXXXX Apr 6 22:53:10 DEBUG[15279]: chan_sip.c:2228 update_call_counter: Updating call counter for outgoing call -- Called 33XXXXXXXXX@XXX.XXX.XXX.154 Apr 6 22:53:10 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag Our tag: as34928163 Apr 6 22:53:10 DEBUG[15250]: chan_sip.c:1468 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' Request 102: Found Apr 6 22:53:10 DEBUG[15250]: chan_sip.c:9722 handle_response_invite: SIP response 100 to standard invite Apr 6 22:53:11 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:11 DEBUG[15250]: chan_sip.c:1468 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' Request 102: Found Apr 6 22:53:11 DEBUG[15250]: chan_sip.c:9722 handle_response_invite: SIP response 183 to standard invite -- SIP/XXX.XXX.XXX.154-09955928 is making progress passing it to SIP/5060-098fe228 Apr 6 22:53:11 DEBUG[15279]: rtp.c:1361 ast_rtp_write: Ooh, format changed from unknown to g723 Apr 6 22:53:12 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 40 bytes Apr 6 22:53:13 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 44 bytes Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:1392 __sip_ack: Acked pending invite 102 Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' of Request 102: Match Found Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:9722 handle_response_invite: SIP response 200 to standard invite Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:6269 build_route: build_route: Contact hop: "Verso C5CM" -- SIP/XXX.XXX.XXX.154-09955928 answered SIP/5060-098fe228 Apr 6 22:53:14 DEBUG[15279]: chan_sip.c:2570 sip_answer: sip_answer(SIP/5060-098fe228) -- Attempting native bridge of SIP/5060-098fe228 and SIP/XXX.XXX.XXX.154-09955928 Apr 6 22:53:14 DEBUG[15279]: chan_sip.c:13135 sip_set_rtp_peer: Deferring reinvite on SIP '220147137@XXX.XXX.XXX.154' - It's audio will be redirected to IP XXX.XXX.XXX.100 Apr 6 22:53:14 DEBUG[15279]: chan_sip.c:13129 sip_set_rtp_peer: Sending reinvite on SIP '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' - It's audio soon redirected to IP XXX.XXX.XXX.161 Apr 6 22:53:14 DEBUG[15228]: chan_sip.c:11868 sip_devicestate: Checking device state for peer XXX.XXX.XXX.154 Apr 6 22:53:14 DEBUG[15228]: devicestate.c:187 do_state_change: Changing state for SIP/XXX.XXX.XXX.154 - state 2 (In use) Apr 6 22:53:14 DEBUG[15228]: chan_sip.c:11868 sip_devicestate: Checking device state for peer 5060 Apr 6 22:53:14 DEBUG[15228]: devicestate.c:187 do_state_change: Changing state for SIP/5060 - state 4 (Invalid) Apr 6 22:53:14 DEBUG[15281]: app_queue.c:500 changethread: Device 'SIP/XXX.XXX.XXX.154' changed to state '2' (In use) but we don't care because they're not a member of any queue. Apr 6 22:53:14 DEBUG[15282]: app_queue.c:500 changethread: Device 'SIP/5060' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 220147137@XXX.XXX.XXX.154 Their Tag GR52RWG346-34 Our tag: as4c38080a Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:11320 handle_request: **** Received ACK (6) - Command in SIP ACK Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '220147137@XXX.XXX.XXX.154' of Response 1: Match Found Apr 6 22:53:14 DEBUG[15250]: chan_sip.c:9705 check_pendings: Sending pending reinvite on '220147137@XXX.XXX.XXX.154' Apr 6 22:53:15 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 40 bytes Apr 6 22:53:15 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #16)) Apr 6 22:53:15 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #17)) Apr 6 22:53:16 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #16)) Apr 6 22:53:16 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #17)) Apr 6 22:53:16 DEBUG[15250]: chan_sip.c:1336 __sip_autodestruct: Auto destroying call '344890ff281bae7b3e384d36086e4a80@127.0.0.1' Apr 6 22:53:17 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:17 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 220147137@XXX.XXX.XXX.154 Their Tag GR52RWG346-34 Our tag: as4c38080a Apr 6 22:53:17 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 6defb13f715680ae17367f0d787ad8d9@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as20c98505 Apr 6 22:53:17 DEBUG[15250]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for 3cddca671933debd18b6ef510a39ee4b@83.98.215.12 - OPTIONS (No RTP) Apr 6 22:53:17 DEBUG[15250]: chan_sip.c:11320 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS Apr 6 22:53:17 DEBUG[15250]: chan_sip.c:11524 sipsock_read: SIP message could not be handled, bad request: 3cddca671933debd18b6ef510a39ee4b@83.98.215.12 Apr 6 22:53:18 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #16)) Apr 6 22:53:18 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #17)) Apr 6 22:53:20 DEBUG[15250]: chan_sip.c:1336 __sip_autodestruct: Auto destroying call '6defb13f715680ae17367f0d787ad8d9@XXX.XXX.XXX.242' Apr 6 22:53:20 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 44 bytes Apr 6 22:53:21 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 40 bytes Apr 6 22:53:22 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 5 to 8000 ms (t1 500 ms (Retrans id #16)) Apr 6 22:53:22 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 5 to 8000 ms (t1 500 ms (Retrans id #17)) Apr 6 22:53:24 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 44 bytes Apr 6 22:53:25 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 40 bytes Apr 6 22:53:28 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 44 bytes Apr 6 22:53:30 WARNING[15258]: chan_oh323.c:4250 oh323_gk_check: Failed to register with gatekeeper 'XXX.XXX.XXX.242'. -- Retrying gatekeeper registration. Apr 6 22:53:30 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 6 to 16000 ms (t1 500 ms (Retrans id #16)) Apr 6 22:53:30 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 6 to 16000 ms (t1 500 ms (Retrans id #17)) Apr 6 22:53:32 DEBUG[15279]: rtp.c:411 ast_rtcp_read: Got RTCP report of 40 bytes Apr 6 22:53:32 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:32 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 220147137@XXX.XXX.XXX.154 Their Tag GR52RWG346-34 Our tag: as4c38080a Apr 6 22:53:32 DEBUG[15250]: chan_sip.c:11320 handle_request: **** Received BYE (8) - Command in SIP BYE Apr 6 22:53:32 DEBUG[15250]: chan_sip.c:10960 handle_request_bye: Received bye, issuing owner hangup Apr 6 22:53:32 WARNING[15250]: chan_sip.c:4299 __transmit_response: 200 OK Apr 6 22:53:32 DEBUG[15279]: rtp.c:1722 ast_rtp_bridge: Oooh, got a hangup Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:13135 sip_set_rtp_peer: Deferring reinvite on SIP '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' - It's audio will be redirected to IP XXX.XXX.XXX.242 Apr 6 22:53:32 DEBUG[15279]: channel.c:3613 ast_channel_bridge: Returning from native bridge, channels: SIP/5060-098fe228, SIP/XXX.XXX.XXX.154-09955928 Apr 6 22:53:32 DEBUG[15279]: channel.c:1371 ast_hangup: Hanging up channel 'SIP/XXX.XXX.XXX.154-09955928' Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:2440 sip_hangup: Hangup call SIP/XXX.XXX.XXX.154-09955928, SIP callid 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242) Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:2448 sip_hangup: update_call_counter(33XXXXXXXXX) - decrement call limit counter Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:2228 update_call_counter: Updating call counter for outgoing call Apr 6 22:53:32 DEBUG[15279]: app_dial.c:1644 dial_exec_full: Exiting with DIALSTATUS=ANSWER. Apr 6 22:53:32 DEBUG[15279]: pbx.c:2336 __ast_pbx_run: Spawn extension (from-sip-external,7676,2) exited non-zero on 'SIP/5060-098fe228' == Spawn extension (from-sip-external, 7676, 2) exited non-zero on 'SIP/5060-098fe228' Apr 6 22:53:32 DEBUG[15279]: pbx.c:1697 pbx_extension_helper: Launching 'Hangup' -- Executing Hangup("SIP/5060-098fe228", "") in new stack Apr 6 22:53:32 DEBUG[15279]: pbx.c:2474 __ast_pbx_run: Spawn extension (from-sip-external,h,1) exited non-zero on 'SIP/5060-098fe228' == Spawn extension (from-sip-external, h, 1) exited non-zero on 'SIP/5060-098fe228' Apr 6 22:53:32 DEBUG[15228]: chan_sip.c:11868 sip_devicestate: Checking device state for peer XXX.XXX.XXX.154 Apr 6 22:53:32 DEBUG[15228]: devicestate.c:187 do_state_change: Changing state for SIP/XXX.XXX.XXX.154 - state 1 (Not in use) Apr 6 22:53:32 DEBUG[15279]: cdr_addon_mysql.c:206 mysql_log: cdr_mysql: inserting a CDR record. Apr 6 22:53:32 DEBUG[15279]: cdr_addon_mysql.c:222 mysql_log: 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-04-06 22:53:08','\"XXXXXX127\" ','XXXXXX127','7676','from-sip-external', 'SIP/5060-098fe228','SIP/XXX.XXX.XXX.154-09955928','Hangup','',24,18,'ANSWERED',3,'','1175892788.2') Apr 6 22:53:32 DEBUG[15279]: channel.c:1371 ast_hangup: Hanging up channel 'SIP/5060-098fe228' Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:2440 sip_hangup: Hangup call SIP/5060-098fe228, SIP callid 220147137@XXX.XXX.XXX.154) Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:2448 sip_hangup: update_call_counter() - decrement call limit counter Apr 6 22:53:32 DEBUG[15279]: chan_sip.c:2228 update_call_counter: Updating call counter for outgoing call Apr 6 22:53:32 DEBUG[15228]: chan_sip.c:11868 sip_devicestate: Checking device state for peer 5060 Apr 6 22:53:32 DEBUG[15228]: devicestate.c:187 do_state_change: Changing state for SIP/5060 - state 4 (Invalid) Apr 6 22:53:32 DEBUG[15285]: app_queue.c:500 changethread: Device 'SIP/XXX.XXX.XXX.154' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Apr 6 22:53:32 DEBUG[15286]: app_queue.c:500 changethread: Device 'SIP/5060' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. Apr 6 22:53:43 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:43 DEBUG[15250]: chan_sip.c:11320 handle_request: **** Received BYE (8) - Command in SIP BYE Apr 6 22:53:43 DEBUG[15250]: chan_sip.c:10964 handle_request_bye: Received bye, no owner, selfdestruct soon. Apr 6 22:53:43 WARNING[15250]: chan_sip.c:4299 __transmit_response: 200 OK Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 7 to 32000 ms (t1 500 ms (Retrans id #16)) Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 7 to 32000 ms (t1 500 ms (Retrans id #17)) Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:3239 find_call: = No match Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 220147137@XXX.XXX.XXX.154 Their Tag GR52RWG346-34 Our tag: as4c38080a Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:1392 __sip_ack: Acked pending invite 102 Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '220147137@XXX.XXX.XXX.154' of Request 102: Match Found -- Got SIP response 500 "Internal Server Error" back from XXX.XXX.XXX.154 Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:1468 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' Request 103: Found Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:9722 handle_response_invite: SIP response 100 to standard invite Apr 6 22:53:46 DEBUG[15250]: chan_sip.c:9726 handle_response_invite: Got response on call that is already terminated: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 (ignoring) Apr 6 22:53:47 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:47 DEBUG[15250]: chan_sip.c:1468 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' Request 103: Found Apr 6 22:53:47 DEBUG[15250]: chan_sip.c:9722 handle_response_invite: SIP response 183 to standard invite Apr 6 22:53:47 DEBUG[15250]: chan_sip.c:9726 handle_response_invite: Got response on call that is already terminated: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 (ignoring) Apr 6 22:53:51 DEBUG[15250]: chan_sip.c:3239 find_call: = Found Their Call ID: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 Their Tag GR52RWG346-34 Our tag: as34928163 Apr 6 22:53:51 DEBUG[15250]: chan_sip.c:1392 __sip_ack: Acked pending invite 103 Apr 6 22:53:51 DEBUG[15250]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242' of Request 103: Match Found Apr 6 22:53:51 DEBUG[15250]: chan_sip.c:9722 handle_response_invite: SIP response 200 to standard invite Apr 6 22:53:51 DEBUG[15250]: chan_sip.c:9726 handle_response_invite: Got response on call that is already terminated: 5e3d084e6f9afc892f484e19649ac105@XXX.XXX.XXX.242 (ignoring) asterisk04*CLI> [root@asterisk04 ~]#