dell:/etc/dell# asterisk -ddddddddddddrvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf Asterisk 1.0.9-0.2.0-dell-8k, Copyright (C) 1999-2004 Digium. Written by Mark Spencer ========================================================================= Connected to Asterisk 1.0.9-0.2.0-dell-8k currently running on dell (pid = 32621) Verbosity was 0 and is now 31 Core debug was 0 and is now 12 Aug 10 11:20:29 NOTICE[32622]: chan_sip.c:6621 handle_response: Peer '6001' is now REACHABLE! dell*CLI> iax2 debug IAX2 Debugging Enabled Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00017ms SCall: 00001 DCall: 00000 [:4569] VERSION : 2 CALLED NUMBER : 6012 CALLING NUMBER : 6203 CALLING NAME : Ext 6203 LANGUAGE : en USERNAME : FORMAT : 2 CAPABILITY : 63490 ADSICPE : 2 DATE TIME : 185231961 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00013ms SCall: 00001 DCall: 00001 [:4569] AUTHMETHODS : 1 USERNAME : Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Timestamp: 00019ms SCall: 00001 DCall: 00001 [:4569] PASSWORD : -- Accepting AUTHENTICATED call from , requested format = 2, actual format = 2 Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00014ms SCall: 00001 DCall: 00001 [:4569] FORMAT : 2 -- Executing SetVar("IAX2/@:4569/1", "CALLEDID=6012") in new stack -- Executing GotoIf("IAX2/@:4569/1", "0?3:7") in new stack -- Goto (from-remote-hosts,6012,7) -- Executing Macro("IAX2/@:4569/1", "dialuser|6012|30|rF") in new stack -- Executing DBget("IAX2/@:4569/1", "temp=FM/6012") in new stack -- DBget: varname=temp, family=FM, key=6012 -- DBget: Value not found in database. -- Executing Dial("IAX2/@:4569/1", "SIP/6012|30|rF") in new stack -- Called 6012 Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00014ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00017ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00017ms SCall: 00001 DCall: 00001 [:4569] -- SIP/6012-010c is ringing Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00020ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 00020ms SCall: 00001 DCall: 00001 [:4569] -- SIP/6012-010c answered IAX2/@:4569/1 dell*CLI> show channels Channel (Context Extension Pri ) State Appl. Data SIP/6012-010c (from-ip-phones 1 ) Up Bridged Call IAX2/@:4569/1 IAX2/@:4569/1 (macro-dialuser s 102 ) Up Dial SIP/6012|30|rF 2 active channel(s) Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 002 Type: CONTROL Subclass: ANSWER Timestamp: 02150ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 02150ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 002 Type: VOICE Subclass: 2 Timestamp: 02160ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 006 Type: IAX Subclass: ACK Timestamp: 02160ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 006 Type: VOICE Subclass: 2 Timestamp: 02196ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[-01] -- OSeqno: 006 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 02196ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 003 Type: IAX Subclass: LAGRQ Timestamp: 10083ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 007 Type: IAX Subclass: LAGRP Timestamp: 10083ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[-01] -- OSeqno: 007 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 10083ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 007 Type: IAX Subclass: LAGRQ Timestamp: 10018ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 005 Type: IAX Subclass: LAGRP Timestamp: 10018ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: ACK Timestamp: 10018ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 005 Type: IAX Subclass: PING Timestamp: 20203ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 005 Type: IAX Subclass: LAGRQ Timestamp: 20206ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: PING Timestamp: 20017ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 006 Type: IAX Subclass: PONG Timestamp: 20017ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 20020ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[000] -- OSeqno: 011 ISeqno: 007 Type: IAX Subclass: LAGRP Timestamp: 20020ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 009 Type: IAX Subclass: PONG Timestamp: 20203ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 008 Type: IAX Subclass: ACK Timestamp: 20203ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 010 Type: IAX Subclass: LAGRP Timestamp: 20206ms SCall: 00001 DCall: 00001 [:4569] Tx-Frame Retry[-01] -- OSeqno: 010 ISeqno: 009 Type: IAX Subclass: ACK Timestamp: 20206ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 011 Type: IAX Subclass: ACK Timestamp: 20017ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 20020ms SCall: 00001 DCall: 00001 [:4569] -- Started music on hold, class 'default', on IAX2/@:4569/1 -- Stopped music on hold on IAX2/@:4569/1 -- Executing SetGroup("IAX2/@:4569/1", "IAX") in new stack -- Executing NoOp("IAX2/@:4569/1", "") in new stack -- Executing GotoIf("IAX2/@:4569/1", "0?4:7") in new stack -- Goto (from-ip-phones,6200,7) -- Executing SetVar("IAX2/@:4569/1", "NumToDial=6200") in new stack -- Executing ResetCDR("IAX2/@:4569/1", "") in new stack -- Executing RouteCall("IAX2/@:4569/1", "6200") in new stack > Route as SIP//200 > Set AbsoluteTimeout to 0 -- Executing Dial("IAX2/@:4569/1", "IAX2/:@/6200|300|rF") in new stack -- Called :@/6200 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00003ms SCall: 00002 DCall: 00000 [:4569] VERSION : 2 CALLED NUMBER : 6200 CALLING NUMBER : 6203 CALLING NAME : Ext 6203 LANGUAGE : fr USERNAME : FORMAT : 2 CAPABILITY : 63490 ADSICPE : 2 DATE TIME : 185227936 Tx-Frame Retry[000] -- OSeqno: 012 ISeqno: 009 Type: CONTROL Subclass: RINGING Timestamp: 22003ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00009ms SCall: 00002 DCall: 00002 [:4569] AUTHMETHODS : 1 USERNAME : Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Timestamp: 00006ms SCall: 00002 DCall: 00002 [:4569] PASSWORD : Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 013 Type: IAX Subclass: ACK Timestamp: 22003ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00014ms SCall: 00002 DCall: 00002 [:4569] FORMAT : 2 -- Call accepted by (format gsm) -- Format for call is gsm Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00014ms SCall: 00002 DCall: 00002 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00017ms SCall: 00002 DCall: 00002 [:4569] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00017ms SCall: 00002 DCall: 00002 [:4569] -- IAX2/:4569/2 is ringing Tx-Frame Retry[000] -- OSeqno: 013 ISeqno: 009 Type: CONTROL Subclass: RINGING Timestamp: 22006ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 014 Type: IAX Subclass: ACK Timestamp: 22006ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00020ms SCall: 00002 DCall: 00002 [:4569] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 00020ms SCall: 00002 DCall: 00002 [:4569] -- IAX2/:4569/2 is ringing Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 002 Type: CONTROL Subclass: ANSWER Timestamp: 04777ms SCall: 00002 DCall: 00002 [:4569] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 04777ms SCall: 00002 DCall: 00002 [:4569] -- IAX2/:4569/2 answered IAX2/@:4569/1 -- Attempting native bridge of IAX2/@:4569/1 and IAX2/:4569/2 Tx-Frame Retry[000] -- OSeqno: 014 ISeqno: 009 Type: IAX Subclass: TXREQ Timestamp: 26581ms SCall: 00001 DCall: 00001 [:4569] APPARENT ADDRES : IPV4 :4569 CALL NUMBER : 2 TRANSFER ID : 287111597 Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 005 Type: IAX Subclass: TXREQ Timestamp: 04781ms SCall: 00002 DCall: 00002 [:4569] APPARENT ADDRES : IPV4 :4569 CALL NUMBER : 1 TRANSFER ID : 287111597 Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 015 Type: IAX Subclass: ACK Timestamp: 26581ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 04781ms SCall: 00002 DCall: 00002 [:4569] Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 005 Type: VOICE Subclass: 2 Timestamp: 04662ms SCall: 00002 DCall: 00002 [:4569] Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 015 Type: IAX Subclass: TXREADY Timestamp: 26594ms SCall: 00001 DCall: 00001 [:4569] CALL NUMBER : 1 -- Channel 'IAX2/@:4569/1' ready to transfer Tx-Frame Retry[-01] -- OSeqno: 015 ISeqno: 010 Type: IAX Subclass: ACK Timestamp: 26594ms SCall: 00001 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 003 Type: IAX Subclass: TXREADY Timestamp: 04794ms SCall: 00002 DCall: 00002 [:4569] CALL NUMBER : 2 -- Channel 'IAX2/:4569/2' ready to transfer -- Releasing IAX2/:4569/2 and IAX2/@:4569/1 Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 006 Type: IAX Subclass: TXREL Timestamp: 04783ms SCall: 00002 DCall: 00002 [:4569] CALL NUMBER : 1 Tx-Frame Retry[000] -- OSeqno: 015 ISeqno: 010 Type: IAX Subclass: TXREL Timestamp: 26583ms SCall: 00001 DCall: 00001 [:4569] CALL NUMBER : 2 Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 04662ms SCall: 00002 DCall: 00002 [:4569] Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 04783ms SCall: 00002 DCall: 00002 [:4569] Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 016 Type: IAX Subclass: ACK Timestamp: 26583ms SCall: 00001 DCall: 00001 [:4569] -- Hungup 'IAX2/:4569/2' -- Executing Macro("IAX2/@:4569/1", "hangup") in new stack -- Executing Hangup("IAX2/@:4569/1", "SIP//200") in new stack -- Hungup 'IAX2/@:4569/1' dell*CLI> show channels Channel (Context Extension Pri ) State Appl. Data 0 active channel(s) == Manager 'admin' logged on from 127.0.0.1 == Manager 'admin' logged off from 127.0.0.1 Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00001ms SCall: 00003 DCall: 00000 [:4569] VERSION : 2 CALLED NUMBER : 6012 CALLING NUMBER : 6203 CALLING NAME : Ext 6203 LANGUAGE : en USERNAME : FORMAT : 2 CAPABILITY : 63490 ADSICPE : 2 DATE TIME : 185232003 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00015ms SCall: 00003 DCall: 00003 [:4569] AUTHMETHODS : 1 USERNAME : Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Timestamp: 00002ms SCall: 00003 DCall: 00003 [:4569] PASSWORD : -- Accepting AUTHENTICATED call from , requested format = 2, actual format = 2 Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00016ms SCall: 00003 DCall: 00003 [:4569] FORMAT : 2 -- Executing SetVar("IAX2/@:4569/3", "CALLEDID=6012") in new stack -- Executing GotoIf("IAX2/@:4569/3", "0?3:7") in new stack -- Goto (from-remote-hosts,6012,7) -- Executing Macro("IAX2/@:4569/3", "dialuser|6012|30|rF") in new stack -- Executing DBget("IAX2/@:4569/3", "temp=FM/6012") in new stack -- DBget: varname=temp, family=FM, key=6012 -- DBget: Value not found in database. -- Executing Dial("IAX2/@:4569/3", "SIP/6012|30|rF") in new stack -- Called 6012 Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00016ms SCall: 00003 DCall: 00003 [:4569] Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00019ms SCall: 00003 DCall: 00003 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00019ms SCall: 00003 DCall: 00003 [:4569] -- SIP/6012-2745 is ringing Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00022ms SCall: 00003 DCall: 00003 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 00022ms SCall: 00003 DCall: 00003 [:4569] -- SIP/6012-2745 answered IAX2/@:4569/3 dell*CLI> show channels Channel (Context Extension Pri ) State Appl. Data SIP/6012-2745 (from-ip-phones 1 ) Up Bridged Call IAX2/@:4569/3 IAX2/@:4569/3 (macro-dialuser s 102 ) Up Dial SIP/6012|30|rF 2 active channel(s) Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 002 Type: CONTROL Subclass: ANSWER Timestamp: 04335ms SCall: 00003 DCall: 00003 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 04335ms SCall: 00003 DCall: 00003 [:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 005 Type: IAX Subclass: TXREQ Timestamp: 04333ms SCall: 00003 DCall: 00003 [:4569] APPARENT ADDRES : IPV4 :4569 CALL NUMBER : 1 TRANSFER ID : 919829164 Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 04333ms SCall: 00003 DCall: 00003 [:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXCNT Timestamp: 04346ms SCall: 00003 DCall: 00001 [:4569] TRANSFER ID : 919829164 Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXCNT Timestamp: 54553ms SCall: 00001 DCall: 00003 [:4569] TRANSFER ID : 919829164 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXACC Timestamp: 04347ms SCall: 00003 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: VOICE Subclass: 2 Timestamp: 04184ms SCall: 00003 DCall: 00003 [:4569] Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 04184ms SCall: 00003 DCall: 00003 [:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXACC Timestamp: 54556ms SCall: 00001 DCall: 00003 [:4569] Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: TXREADY Timestamp: 04350ms SCall: 00003 DCall: 00003 [:4569] CALL NUMBER : 3 Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 006 Type: IAX Subclass: TXREL Timestamp: 04334ms SCall: 00003 DCall: 00003 [:4569] CALL NUMBER : 1 Tx-Frame Retry[-01] -- OSeqno: 006 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 04334ms SCall: 00003 DCall: 00003 [:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: VOICE Subclass: 2 Timestamp: 00000ms SCall: 00003 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00001 DCall: 00003 [:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: VOICE Subclass: 2 Timestamp: 4294967018ms SCall: 00001 DCall: 00003 [:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 4294967018ms SCall: 00003 DCall: 00001 [:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: VOICE Subclass: 2 Timestamp: 00002ms SCall: 00001 DCall: 00003 [:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00002ms SCall: 00003 DCall: 00001 [:4569] Rx-Frame Retry[Yes] -- OSeqno: 004 ISeqno: 006 Type: IAX Subclass: TXREL Timestamp: 04334ms SCall: 00003 DCall: 00003 [:4569] CALL NUMBER : 1 Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 006 Type: IAX Subclass: VNAK Timestamp: 06338ms SCall: 00003 DCall: 00003 [:4569] -- Executing Macro("IAX2/@:4569/3", "hangup") in new stack -- Executing Hangup("IAX2/@:4569/3", "") in new stack -- Hungup 'IAX2/@:4569/3' dell*CLI>