Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW
   Timestamp: 00015ms  SCall: 00003  DCall: 00000 [217.14.132.185:4569]
   VERSION         : 2
   CALLED NUMBER   : 02830898196
   CODEC_PREFS     : (ilbc|gsm|ulaw|alaw|g729)
   CALLING NUMBER  : 07734287429
   CALLING PRESNTN : 0
   CALLING TYPEOFN : 0
   CALLING TRANSIT : 0
   CALLING NAME    : 07734287429
   LANGUAGE        : en
   USERNAME        : 02830898196
   FORMAT          : 8
   CAPABILITY      : 64782
   ADSICPE         : 2
   DATE TIME       : 2007-11-03  19:47:12

    -- Accepting UNAUTHENTICATED call from 217.14.132.185:
       > requested format = alaw,
       > requested prefs = (ilbc|gsm|ulaw|alaw|g729),
       > actual format = ulaw,
       > host prefs = (ulaw|alaw|gsm),
       > priority = mine
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACCEPT
   Timestamp: 00013ms  SCall: 00003  DCall: 00003 [217.14.132.185:4569]
   FORMAT          : 4

    -- Executing Goto("IAX2/217.14.132.185:4569-3", "from-internal|145|1") in new stack
    -- Goto (from-internal,145,1)
    -- Executing Macro("IAX2/217.14.132.185:4569-3", "exten-vm|novm|145") in new stack
    -- Executing Macro("IAX2/217.14.132.185:4569-3", "user-callerid") in new stack
    -- Executing NoOp("IAX2/217.14.132.185:4569-3", "user-callerid: device 02830898196") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "AMPUSER=02830898196") in new stack
    -- Executing GotoIf("IAX2/217.14.132.185:4569-3", "0?report") in new stack
    -- Executing GotoIf("IAX2/217.14.132.185:4569-3", "1?start") in new stack
    -- Goto (macro-user-callerid,s,6)
    -- Executing NoOp("IAX2/217.14.132.185:4569-3", "REALCALLERIDNUM is 02830898196") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "AMPUSER=02830898196") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "AMPUSERCIDNAME=") in new stack
    -- Executing GotoIf("IAX2/217.14.132.185:4569-3", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing NoOp("IAX2/217.14.132.185:4569-3", "TTL:  ARG1: novm") in new stack
    -- Executing GotoIf("IAX2/217.14.132.185:4569-3", "0?continue") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "__TTL=64") in new stack
    -- Executing GotoIf("IAX2/217.14.132.185:4569-3", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing NoOp("IAX2/217.14.132.185:4569-3", "Using CallerID "device" <02830898196>") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "FROMCONTEXT=exten-vm") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "VMBOX=novm") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "EXTTOCALL=145") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "CFUEXT=") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "CFBEXT=") in new stack
    -- Executing Set("IAX2/217.14.132.185:4569-3", "RT=") in new stack
    -- Executing Macro("IAX2/217.14.132.185:4569-3", "record-enable|145|IN") in new stack
    -- Executing GotoIf("IAX2/217.14.132.185:4569-3", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing AGI("IAX2/217.14.132.185:4569-3", "recordingcheck|20071103-194555|1194119155.302") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: INVAL
   Timestamp: 00000ms  SCall: 00003  DCall: 00003 [217.14.132.185:4569]
  == Spawn extension (macro-record-enable, s, 4) exited non-zero on 'IAX2/217.14.132.185:4569-3' in macro 'record-enable'
  == Spawn extension (macro-record-enable, s, 4) exited non-zero on 'IAX2/217.14.132.185:4569-3' in macro 'exten-vm'
  == Spawn extension (macro-record-enable, s, 4) exited non-zero on 'IAX2/217.14.132.185:4569-3'
    -- Hungup 'IAX2/217.14.132.185:4569-3'