-- Executing [030445448@from-winternal-ba:1] Macro("SIP/2332-b43fa428", "outside-voip|421230445448") in new stack
    -- Executing [s@macro-outside-voip:1] GotoIf("SIP/2332-b43fa428", "0?alreadyagent") in new stack
    -- Executing [s@macro-outside-voip:2] Set("SIP/2332-b43fa428", "AGENTID=") in new stack
    -- Executing [s@macro-outside-voip:3] Goto("SIP/2332-b43fa428", "startcall") in new stack
    -- Goto (macro-outside-voip,s,5)
    -- Executing [s@macro-outside-voip:5] AGI("SIP/2332-b43fa428", "rewrite_cli.agi|2333|421230445448") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/rewrite_cli.agi
    --  rewrite_cli.agi|2333|421230445448: Rewriting cli ... cli = 2333, cld = 421230445448
    --  rewrite_cli.agi|2333|421230445448: ... cli matched condition, rewrited to 421233111333
    -- AGI Script rewrite_cli.agi completed, returning 0
    -- Executing [s@macro-outside-voip:6] GotoIf("SIP/2332-b43fa428", "1?notagent") in new stack
    -- Goto (macro-outside-voip,s,10)
    -- Executing [s@macro-outside-voip:10] Dial("SIP/2332-b43fa428", "IAX2/isp/421230445448||W") in new stack
    -- Called isp/421230445448
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 00001  DCall: 00000 [192.168.11.10:4569]
   VERSION         : 2
   CALLED NUMBER   : 421230445448
   CODEC_PREFS     : (alaw|ulaw|gsm)
   CALLING NUMBER  : 421233111333
   CALLING PRESNTN : 0
   CALLING TYPEOFN : 0
   CALLING TRANSIT : 0
   CALLING NAME    : 
   LANGUAGE        : en
   USERNAME        : company
   FORMAT          : 8
   CAPABILITY      : 57358
   ADSICPE         : 2
   DATE TIME       : 2008-10-07  23:48:52

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACK    
   Timestamp: 00003ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: AUTHREQ
   Timestamp: 00004ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 549165136
   USERNAME        : company

Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: AUTHREP
   Timestamp: 00005ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
   MD5 RESULT      : 568d5eb7652a4bf64460a2e74b449e8b
company-m*CLI> 
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00005ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACCEPT 
   Timestamp: 00005ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
   FORMAT          : 8

    -- Call accepted by 192.168.11.10 (format alaw)
    -- Format for call is alaw
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00005ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: PROCDNG
   Timestamp: 00008ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: ACK    
   Timestamp: 00008ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
    -- IAX2/isp-1 is proceeding passing it to SIP/2332-b43fa428
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: VOICE   Subclass: 8
   Timestamp: 00100ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 004 Type: IAX     Subclass: ACK    
   Timestamp: 00100ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 004 Type: VOICE   Subclass: 8
   Timestamp: 00124ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 003 Type: IAX     Subclass: ACK    
   Timestamp: 00124ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 004 Type: DTMF_B  Subclass: 5
   Timestamp: 00267ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 004 Type: IAX     Subclass: ACK    
   Timestamp: 00267ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 004 Type: DTMF_E  Subclass: 5
   Timestamp: 00270ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 005 Type: IAX     Subclass: ACK    
   Timestamp: 00270ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]

- 10s delay

Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: LAGRQ  
   Timestamp: 10004ms  SCall: 00001  DCall: 14158 [192.168.11.10:4569]
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: INVAL  
   Timestamp: 00000ms  SCall: 14158  DCall: 00001 [192.168.11.10:4569]
    -- Hungup 'IAX2/isp-1'
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-outside-voip:11] NoOp("SIP/2332-b43fa428", "DIALSTATUS is CHANUNAVAIL| HANGUPCAUSE is 0") in new stack
    -- Executing [s@macro-outside-voip:12] Goto("SIP/2332-b43fa428", "s-CHANUNAVAIL|1") in new stack
    -- Goto (macro-outside-voip,s-CHANUNAVAIL,1)
    -- Executing [s-CHANUNAVAIL@macro-outside-voip:1] Congestion("SIP/2332-b43fa428", "") in new stack
  == Spawn extension (macro-outside-voip, s-CHANUNAVAIL, 1) exited non-zero on 'SIP/2332-b43fa428' in macro 'outside-voip'
  == Spawn extension (macro-outside-voip, s-CHANUNAVAIL, 1) exited non-zero on 'SIP/2332-b43fa428'