Connected to Asterisk 1.2.13 currently running on test-pbx (pid = 27428)
Verbosity is at least 3
    -- Accepting AUTHENTICATED call from 172.16.5.112:
       > requested format = gsm,
       > requested prefs = (),
       > actual format = ulaw,
       > host prefs = (ulaw|alaw|gsm),
       > priority = mine
    -- Executing Macro("IAX2/8223-3", "user-callerid|") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0?report") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0?start") in new stack
    -- Executing Set("IAX2/8223-3", "REALCALLERIDNUM=") in new stack
    -- Executing NoOp("IAX2/8223-3", "REALCALLERIDNUM is ") in new stack
    -- Executing Set("IAX2/8223-3", "AMPUSER=") in new stack
    -- Executing Set("IAX2/8223-3", "AMPUSERCIDNAME=") in new stack
    -- Executing GotoIf("IAX2/8223-3", "1?report") in new stack
    -- Goto (macro-user-callerid,s,10)
    -- Executing NoOp("IAX2/8223-3", "Using CallerID "" <>") in new stack
    -- Executing GotoIf("IAX2/8223-3", "1?REPCID") in new stack
    -- Goto (from-internal,1003,6)
    -- Executing NoOp("IAX2/8223-3", "CALLERID(name) is ") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0 ?skipsimple") in new stack
    -- Executing Macro("IAX2/8223-3", "simple-dial|1003|5") in new stack
    -- Executing Macro("IAX2/8223-3", "user-callerid") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0?report") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0?start") in new stack
    -- Executing Set("IAX2/8223-3", "REALCALLERIDNUM=") in new stack
    -- Executing NoOp("IAX2/8223-3", "REALCALLERIDNUM is ") in new stack
    -- Executing Set("IAX2/8223-3", "AMPUSER=") in new stack
    -- Executing Set("IAX2/8223-3", "AMPUSERCIDNAME=") in new stack
    -- Executing GotoIf("IAX2/8223-3", "1?report") in new stack
    -- Goto (macro-user-callerid,s,10)
    -- Executing NoOp("IAX2/8223-3", "Using CallerID "" <>") in new stack
    -- Executing Set("IAX2/8223-3", "FROMCONTEXT=exten-vm") in new stack
    -- Executing Set("IAX2/8223-3", "EXTTOCALL=1003") in new stack
    -- Executing Set("IAX2/8223-3", "RT=5") in new stack
    -- Executing Set("IAX2/8223-3", "CFUEXT=") in new stack
    -- Executing Macro("IAX2/8223-3", "record-enable|1003|IN") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0 > 0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing DeadAGI("IAX2/8223-3", "recordingcheck|20061122-155657|1164211017.115") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20061122-155657|1164211017.115: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("IAX2/8223-3", "No recording needed") in new stack
    -- Executing GotoIf("IAX2/8223-3", "0?dolocaldial|1") in new stack
    -- Executing Macro("IAX2/8223-3", "dial|5|tr|1003") in new stack
    -- Executing DeadAGI("IAX2/8223-3", "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: Added extension 1003 to extension map
    --  dialparties.agi: Extension 1003 cf is disabled
    --  dialparties.agi: Extension 1003 do not disturb is disabled
  == Parsing '/etc/asterisk/manager.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  == Manager 'admin' logged off from 127.0.0.1
  dialparties.agi: Extension 1003 has ExtensionState: 0
    --  dialparties.agi: Checking CW and CFB status for extension 1003
    --  dialparties.agi: DbDel CALLTRACE/1003 - Caller ID is not defined
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("IAX2/8223-3", "IAX2/1003|5|tr") in new stack
    -- Called 1003
    -- Call accepted by 172.16.5.112 (format ulaw)
    -- Format for call is ulaw
    -- IAX2/1003-9 is ringing
    -- IAX2/1003-9 answered IAX2/8223-3
    -- Hungup 'IAX2/1003-9'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'IAX2/8223-3' in macro 'dial'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'IAX2/8223-3' in macro 'simple-dial'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'IAX2/8223-3'
    -- Executing Macro("IAX2/8223-3", "hangupcall") in new stack
    -- Executing ResetCDR("IAX2/8223-3", "w") in new stack
    -- Executing NoCDR("IAX2/8223-3", "") in new stack
    -- Executing GotoIf("IAX2/8223-3", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing Wait("IAX2/8223-3", "5") in new stack
    -- Executing Hangup("IAX2/8223-3", "") in new stack
  == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'IAX2/8223-3' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'IAX2/8223-3'