Connected to Asterisk 1.2.14 svn rev 48468 currently running on asterisk1 (pid = 9406)
Verbosity is at least 40
    -- Starting simple switch on 'Zap/22-1'
    -- Executing Macro("Zap/22-1", "exten-vm|4215|4215") in new stack
    -- Executing Macro("Zap/22-1", "user-callerid") in new stack
    -- Executing NoOp("Zap/22-1", "user-callerid:  ") in new stack
    -- Executing GotoIf("Zap/22-1", "0?report") in new stack
    -- Executing GotoIf("Zap/22-1", "0?start") in new stack
    -- Executing Set("Zap/22-1", "REALCALLERIDNUM=") in new stack
    -- Executing NoOp("Zap/22-1", "REALCALLERIDNUM is ") in new stack
    -- Executing Set("Zap/22-1", "AMPUSER=") in new stack
    -- Executing Set("Zap/22-1", "AMPUSERCIDNAME=") in new stack
    -- Executing GotoIf("Zap/22-1", "1?report") in new stack
    -- Goto (macro-user-callerid,s,11)
    -- Executing NoOp("Zap/22-1", "TTL:  ARG1: 4215") in new stack
    -- Executing GotoIf("Zap/22-1", "0?continue") in new stack
    -- Executing Set("Zap/22-1", "_TTL=64") in new stack
    -- Executing GotoIf("Zap/22-1", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("Zap/22-1", "Using CallerID "" <>") in new stack
    -- Executing Set("Zap/22-1", "FROMCONTEXT=exten-vm") in new stack
    -- Executing Set("Zap/22-1", "VMBOX=4215") in new stack
    -- Executing Set("Zap/22-1", "EXTTOCALL=4215") in new stack
    -- Executing Set("Zap/22-1", "CFUEXT=") in new stack
    -- Executing Set("Zap/22-1", "CFBEXT=") in new stack
    -- Executing Set("Zap/22-1", "RT=15") in new stack
    -- Executing Macro("Zap/22-1", "record-enable|4215|IN") in new stack
    -- Executing GotoIf("Zap/22-1", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing DeadAGI("Zap/22-1", "recordingcheck|20070307-094714|1173282432.68") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20070307-094714|1173282432.68: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("Zap/22-1", "No recording needed") in new stack
    -- Executing Macro("Zap/22-1", "dial|15|trw|4215") in new stack
    -- Executing DeadAGI("Zap/22-1", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  dialparties.agi: priority is 1
  dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
  dialparties.agi: Methodology of ring is  'none'
       >  dialparties.agi: USE_CONFIRMATION:  'FALSE'
       >  dialparties.agi: RINGGROUP_INDEX:   ''
    --  dialparties.agi: Added extension 4215 to extension map
    --  dialparties.agi: Extension 4215 cf is disabled
    --  dialparties.agi: Extension 4215 do not disturb is disabled
       >  dialparties.agi: extnum: 4215
       >  dialparties.agi: exthascw: 1
       >  dialparties.agi: exthascfb: 0
       >  dialparties.agi: extcfb:
       >  dialparties.agi: exthascfu: 0
       >  dialparties.agi: extcfu:
    --  dialparties.agi: DbDel CALLTRACE/4215 - Caller ID is not defined
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("Zap/22-1", "SIP/4215|15|trw") in new stack
    -- Called 4215
    -- SIP/4215-086ed4c8 is ringing
    -- SIP/4215-086ed4c8 answered Zap/22-1