ASTERISK CONFIGURATION ----------------------- [default] exten => _X-.,1,Noop(>>> AGISTATUS1:${AGISTATUS}); exten => _X-.,n,AGI(agistatus-test.php); exten => _X-.,n,Noop(>>> AGISTATUS2:${AGISTATUS}); exten => h,1,NoOp(); exten => h,n,Noop(>>> AGISTATUS3:${AGISTATUS}); exten => h,n,AGI(agistatus-test.php); exten => h,n,Noop(>>> AGISTATUS4:${AGISTATUS}); CONSOLE ------- [May 25 17:50:29] DEBUG[29404]: chan_sip.c:7382 find_call: = Looking for Call ID: 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 (Checking From) --From tag c08lqtgqvc --To-tag [May 25 17:50:29] DEBUG[29404]: acl.c:506 ast_ouraddrfor: Found IP address for this socket [May 25 17:50:29] DEBUG[29404]: chan_sip.c:3677 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 77.240.60.105:5060 == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 [May 25 17:50:29] DEBUG[29404]: chan_sip.c:5000 do_setnat: Setting NAT on RTP to Off [May 25 17:50:29] DEBUG[29404]: chan_sip.c:7286 sip_alloc: Allocating new SIP dialog for 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 - INVITE (With RTP) [May 25 17:50:29] DEBUG[29404]: chan_sip.c:21650 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [May 25 17:50:29] DEBUG[29404]: chan_sip.c:5000 do_setnat: Setting NAT on RTP to On [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8191 process_sdp: Processing session-level SDP v=0... UNSUPPORTED. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8191 process_sdp: Processing session-level SDP o=root 1938503950 1938503950 IN IP4 192.168.246.148... UNSUPPORTED. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8191 process_sdp: Processing session-level SDP s=call... UNSUPPORTED. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8191 process_sdp: Processing session-level SDP c=IN IP4 192.168.246.148... OK. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8191 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8355 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 pcma/8000... OK. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8355 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8355 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8355 process_sdp: Processing media-level (audio) SDP a=ptime:20... OK. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8355 process_sdp: Processing media-level (audio) SDP a=sendrecv... OK. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:8540 process_sdp: We're settling with these formats: 0x8 (alaw) [May 25 17:50:29] DEBUG[29404]: chan_sip.c:20034 handle_request_invite: Checking SIP call limits for device test [May 25 17:50:29] DEBUG[29404]: chan_sip.c:5634 update_call_counter: Updating call counter for incoming call [May 25 17:50:29] DEBUG[29404]: chan_sip.c:6653 sip_new: *** Our native formats are 0x8 (alaw) [May 25 17:50:29] DEBUG[29404]: chan_sip.c:6654 sip_new: *** Joint capabilities are 0x8 (alaw) [May 25 17:50:29] DEBUG[29404]: chan_sip.c:6655 sip_new: *** Our capabilities are 0xfff (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|ilbc|g726aal2) [May 25 17:50:29] DEBUG[29404]: chan_sip.c:6656 sip_new: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [May 25 17:50:29] DEBUG[29404]: chan_sip.c:6686 sip_new: This channel will not be able to handle video. [May 25 17:50:29] DEBUG[29404]: chan_sip.c:12514 build_route: build_route: Contact hop: [May 25 17:50:29] DEBUG[29404]: chan_sip.c:20109 handle_request_invite: Incoming INVITE with 'timer' option enabled [May 25 17:50:29] DEBUG[29404]: chan_sip.c:22703 parse_session_expires: Session-Expires: 3600 [May 25 17:50:29] DEBUG[29404]: chan_sip.c:20293 handle_request_invite: SIP/test-00000003: New call is still down.... Trying... [May 25 17:50:29] DEBUG[29404]: chan_sip.c:3556 __sip_xmit: Trying to put 'SIP/2.0 100' onto UDP socket destined for 77.240.56.22:5060 [May 25 17:50:29] DEBUG[29387]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - test [May 25 17:50:29] DEBUG[29387]: chan_sip.c:23011 sip_devicestate: Checking device state for peer test [May 25 17:50:29] DEBUG[29387]: devicestate.c:460 do_state_change: Changing state for SIP/test - state 1 (Not in use) [May 25 17:50:29] DEBUG[29387]: devicestate.c:440 devstate_event: device 'SIP/test' state '1' [May 25 17:50:29] DEBUG[29976]: pbx.c:3692 pbx_extension_helper: Launching 'NoOp' -- Executing [02070433578@default:1] NoOp("SIP/test-00000003", ">>> AGISTATUS1:") in new stack [May 25 17:50:29] DEBUG[29976]: pbx.c:3692 pbx_extension_helper: Launching 'AGI' -- Executing [02070433578@default:2] AGI("SIP/test-00000003", "agistatus-test.php") in new stack -- Launched AGI Script /asterisk/5/var/lib/asterisk/agi-bin/agistatus-test.php [May 25 17:50:32] DEBUG[29404]: chan_sip.c:7382 find_call: = Looking for Call ID: 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 (Checking From) --From tag c08lqtgqvc --To-tag [May 25 17:50:32] DEBUG[29404]: chan_sip.c:7320 find_call_cb: = Found Their Call ID: 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 Their Tag c08lqtgqvc Our tag: as518d3fc7 [May 25 17:50:32] DEBUG[29404]: chan_sip.c:21650 handle_incoming: **** Received CANCEL (14) - Command in SIP CANCEL [May 25 17:50:32] DEBUG[29404]: chan_sip.c:3281 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 [May 25 17:50:32] DEBUG[29404]: chan_sip.c:3556 __sip_xmit: Trying to put 'SIP/2.0 487' onto UDP socket destined for 77.240.56.22:5060 [May 25 17:50:32] DEBUG[29404]: chan_sip.c:3556 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 77.240.56.22:5060 [May 25 17:50:32] DEBUG[29976]: res_agi.c:2902 run_agi: SIP/test-00000003 hungup -- AGI Script agistatus-test.php completed, returning 4 [May 25 17:50:32] DEBUG[29976]: pbx.c:4306 __ast_pbx_run: Spawn extension (default,02070433578,2) exited non-zero on 'SIP/test-00000003' == Spawn extension (default, 02070433578, 2) exited non-zero on 'SIP/test-00000003' [May 25 17:50:32] DEBUG[29976]: channel.c:1655 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/test-00000003' [May 25 17:50:32] DEBUG[29976]: pbx.c:3692 pbx_extension_helper: Launching 'NoOp' -- Executing [h@default:1] NoOp("SIP/test-00000003", "") in new stack [May 25 17:50:32] DEBUG[29976]: pbx.c:3692 pbx_extension_helper: Launching 'NoOp' -- Executing [h@default:2] NoOp("SIP/test-00000003", ">>> AGISTATUS3:HANGUP") in new stack [May 25 17:50:32] DEBUG[29976]: pbx.c:3692 pbx_extension_helper: Launching 'AGI' -- Executing [h@default:3] AGI("SIP/test-00000003", "agistatus-test.php") in new stack [May 25 17:50:32] DEBUG[29976]: res_agi.c:3227 agi_exec_full: Hungup channel detected, running agi in dead mode. -- Launched AGI Script /asterisk/5/var/lib/asterisk/agi-bin/agistatus-test.php [May 25 17:50:32] DEBUG[29404]: chan_sip.c:7382 find_call: = Looking for Call ID: 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 (Checking From) --From tag c08lqtgqvc --To-tag as518d3fc7 [May 25 17:50:32] DEBUG[29404]: chan_sip.c:7320 find_call_cb: = Found Their Call ID: 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 Their Tag c08lqtgqvc Our tag: as518d3fc7 [May 25 17:50:32] DEBUG[29404]: chan_sip.c:21650 handle_incoming: **** Received ACK (6) - Command in SIP ACK [May 25 17:50:32] DEBUG[29404]: chan_sip.c:4092 __sip_ack: Stopping retransmission on '3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148' of Response 1: Match Found -- AGI Script agistatus-test.php completed, returning 0 [May 25 17:50:42] DEBUG[29976]: pbx.c:3692 pbx_extension_helper: Launching 'NoOp' -- Executing [h@default:4] NoOp("SIP/test-00000003", ">>> AGISTATUS4:SUCCESS") in new stack [May 25 17:50:42] DEBUG[29976]: channel.c:1760 ast_hangup: Hanging up channel 'SIP/test-00000003' [May 25 17:50:42] DEBUG[29976]: chan_sip.c:5998 sip_hangup: Hangup call SIP/test-00000003, SIP callid 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148 [May 25 17:50:42] DEBUG[29976]: cdr.c:1244 ast_cdr_detach: Dropping CDR ! [May 25 17:50:42] DEBUG[29387]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - test [May 25 17:50:42] DEBUG[29387]: chan_sip.c:23011 sip_devicestate: Checking device state for peer test [May 25 17:50:42] DEBUG[29387]: devicestate.c:460 do_state_change: Changing state for SIP/test - state 1 (Not in use) [May 25 17:50:42] DEBUG[29387]: devicestate.c:440 devstate_event: device 'SIP/test' state '1' [May 25 17:50:42] DEBUG[29404]: chan_sip.c:5781 sip_destroy: Destroying SIP dialog 3c3f83e5ae7a-xdpogmrmvyyu@192-168-246-148