Mine

Jul  9 16:24:36 VERBOSE[27951] logger.c:        > Channel SIP/aglow-6e31 was answered.
Jul  9 16:24:36 DEBUG[27951] pbx.c: Expression result is '0'
Jul  9 16:24:36 DEBUG[27951] pbx.c: Function result is 'pintest'
Jul  9 16:24:36 VERBOSE[27951] logger.c:     -- Executing Set("SIP/aglow-6e31", "DID=pintest") in new stack
Jul  9 16:24:36 VERBOSE[27951] logger.c:     -- Executing Goto("SIP/aglow-6e31", "s|1") in new stack
Jul  9 16:24:36 VERBOSE[27951] logger.c:     -- Goto (from-sip-external,s,1)
Jul  9 16:24:36 DEBUG[27951] pbx.c: Expression result is '1'
Jul  9 16:24:36 VERBOSE[27951] logger.c:     -- Executing GotoIf("SIP/aglow-6e31", "1?from-trunk|pintest|1") in new stack
Jul  9 16:24:36 VERBOSE[27951] logger.c:     -- Goto (from-trunk,pintest,1)
Jul  9 16:24:36 WARNING[27951] pbx.c: Channel 'SIP/aglow-6e31' sent into invalid extension 'pintest' in context 'from-trunk', but no invalid handler
Jul  9 16:24:36 DEBUG[2845] channel.c: Avoiding initial deadlock for 'SIP/aglow-6e31'
Jul  9 16:24:36 DEBUG[27951] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jul  9 16:24:36 DEBUG[27951] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2006-07-09 16:24:36','','','pintest','from-trunk', 'SIP/aglow-6e31','','GotoIf','1?from-trunk|pintest|1',0,0,'ANSWERED',3,'')
Jul  9 16:24:36 DEBUG[27951] chan_sip.c: update_call_counter(91886260) - decrement call limit counter
Jul  9 16:24:36 NOTICE[27951] pbx_spool.c: Call completed to SIP/aglow/91886260