asterisk users - Jan 2008 - Help needed for Fax2Email with Welltech FXO 3804

I have this in my extension.conf:

[incoming_28345474]
; 8862100 is the hotline number of the Welltech 3804
;
exten => 8862100,1,NoOp(${CALLERID(num)})
exten => 8862100,2,Wait(1)
exten => 8862100,3,Set(CALLERID(num)=${CALLERID(num)})
include => fax2emailstart

[fax2emailstart]
exten => 3000,1,SetVar(CALLEDFAX=${EXTEN})    ; me
exten => 3000,2,Answer
exten => 3000,3,Macro(fax2emailservice)

exten => 3001,1,SetVar(CALLEDFAX=${EXTEN})    ; dave
exten => 3001,2,Answer
exten => 3001,3,Macro(fax2emailservice)

exten => h,1,System(/var/lib/asterisk/scripts/fax2emailservice
"${CALLERIDNUM}" "${CALLEDFAX}" "${EXTNAME}"
"${EXTEMAIL}" "${FAXFILE}"
"${EXTCOMPANY}")

[macro-fax2emailservice]
 exten =>
s,1,SetVar(FAXFILE=/var/spool/asterisk/fax/${CALLEDFAX}/${UNIQUEID})
; exten => s,2,DBGet(EXTEMAIL=${MACRO_EXTEN}/xEmail)
 exten => s,2,Set(EXTEMAIL=${DB(MACRO_EXTEN/xEmail)})
 exten => s,3,NoOP()
 exten => s,4,Set(EXTNAME=${DB(MACRO_EXTEN/xName)})
 exten => s,5,NoOP()
 exten => s,6,Set(EXTCOMPANY=${DB(MACRO_EXTEN/xCompany)})
 exten => s,7,rxfax(${FAXFILE}.tif)
 exten => s,103,SetVar(EXTMAIL=ronald at gmail.com)
 exten => s,104,Goto(7)
 exten => s,105,SetVar(EXTNAME=Ronald)
 exten => s,106,Goto(7)
 exten => s,107,SetVar(EXTCOMPANY=Boss)
 exten => s,108,Goto(7)


CLI shows:

[Jan 14 22:58:51]     -- Zap/3-1 answered SIP/601-006c3610
[Jan 14 22:58:54]     -- Executing NoOp("SIP/88621001-007263d0",
"88621001") in new stack
[Jan 14 22:58:54]     -- Executing Wait("SIP/88621001-007263d0",
"1") in
new stack
[Jan 14 22:58:55]     -- Executing Set("SIP/88621001-007263d0",
"CALLERID(num)=88621001") in new stack
[Jan 14 22:59:05] WARNING[20366]: pbx.c:2415 __ast_pbx_run: Timeout, but
no rule 't' in context 'incoming_28345474'
[Jan 14 22:59:05]     -- Executing System("SIP/88621001-007263d0",
"/var/lib/asterisk/scripts/fax2emailservice "88621001"
"" "" "" "" """)
in new stack
[Jan 14 22:59:09]     -- Executing NoOp("SIP/88621001-006f8ea0",
"88621001") in new stack
[Jan 14 22:59:09]     -- Executing Wait("SIP/88621001-006f8ea0",
"1") in
new stack
[Jan 14 22:59:10]     -- Executing Set("SIP/88621001-006f8ea0",
"CALLERID(num)=88621001") in new stack
[Jan 14 22:59:20] WARNING[20389]: pbx.c:2415 __ast_pbx_run: Timeout, but
no rule 't' in context 'incoming_28345474'
[Jan 14 22:59:20]     -- Executing System("SIP/88621001-006f8ea0",
"/var/lib/asterisk/scripts/fax2emailservice "88621001"
"" "" "" "" """)
in new stack
[Jan 14 22:59:20]     -- Got SIP response 486 "Busy Here" back from
192.168.250.244
[Jan 14 22:59:21]     -- Got SIP response 486 "Busy Here" back from
192.168.250.244
[Jan 14 22:59:21]     -- Got SIP response 486 "Busy Here" back from
192.168.250.244
[Jan 14 22:59:22]     -- Got SIP response 486 "Busy Here" back from
192.168.250.244
[Jan 14 22:59:23]     -- Hungup 'Zap/3-1'
[Jan 14 22:59:24]     -- Executing NoOp("SIP/88621001-006f3160",
"88621001") in new stack
[Jan 14 22:59:24]     -- Executing Wait("SIP/88621001-006f3160",
"1") in
new stack
[Jan 14 22:59:25]     -- Executing Set("SIP/88621001-006f3160",
"CALLERID(num)=88621001") in new stack
[Jan 14 22:59:30]     -- Executing System("SIP/88621001-006f3160",
"/var/lib/asterisk/scripts/fax2emailservice "88621001"
"" "" "" "" """)
in new stack


I dial the number 28345474 and as soon the dialtone is to hear I dial
3000, but that is not shown in CLI. What am I missing?

bye

Ronald