[asterisk-dev] Asterisk&Aculab compatibility issue with Brooktrout.

Wed Sep 13 08:11:54 MST 2006

Brian Quinn wrote:

> Hi All,
>  
> We currently have been doing much testing with asterisk & aculab, 
> However there seems to be
> compatibilty issues between aculab&asterisk combination when sending 
> faxs to Brooktrout cards
> and only with brooktrout as it operates fine with several fax machines.
> Below is debug.log copy from asterisk.
>  
> From what i can see is that the general handshaking works fine between 
> transmitter & receiver. However
> the actual data being sent gets lost or cut short or something.
>  
> Maybe some of you guys could have a look at the below log & might be 
> able to recognise what the issue is.
>  
> Any help would be much appreciated as brooktrout is a leading Fax 
> hardware card that we have been
> working with for year & this is the first time there has ever been a 
> compatibility issue with it.
> Soo i think it would definetely be a good thing if asterisk could work 
> effectively with it
>  
> One more point to note is that the asterisk&aculab combination can 
> recieve faxs perfectly from Brooktrout.
>  
> The Issue is only with transmitting to Brooktrout.
>  
> Kind Regards,
> Brian.

Are you sure this log is really from sending to a Brooktrout machine? 
You might notice the log says the far end is a Brother machine.

Some people seem to have problems sending to some or all FAX machines 
with spandsp-0.0.2. This might have been addressed in spandsp-0.0.3, 
since at least one person who was having trouble has reported that the 
latest snapshot in 
http://www.soft-switch.org/downloads/snapshots/spandsp solves this 
problem for him. maybe you can try that and report back. A lot has 
changed in spandsp-0.0.3, and I have no clear idea right now which 
change might have brought the improvement.

Steve

>
> *_Asterisk Log;_*
>  
>
>     -- Executing TxFAX("Aculab/140515:2-1", 
> "/var/spool/asterisk/1157636769.2.tif|caller|debug") in new
>  
> stack
> Sep 11 11:22:52 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier up
> Sep 11 11:22:55 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier down
> Sep 11 11:22:55 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier up
> Sep 11 11:22:55 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> framing OK
> Sep 11 11:22:55 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 2 to 3
> Sep 11 11:22:55 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier up
> Sep 11 11:22:55 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> framing OK
> Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 span_message: FLOW <<< 
> NSF:Sep 11 11:22:56 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  20Sep 11 11:22:56 DEBUG[20520]: 
> app_txfax.c:69 span_message:  00Sep 11
>  
> 11:22:56 DEBUG[20520]: app_txfax.c:69 span_message:  00Sep 11 11:22:56 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  56Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 
> span_message:  55Sep 11 11:22:56
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  55Sep 11 11:22:56 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  00Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 
> span_message:  88Sep 11 11:22:56
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  90Sep 11 11:22:56 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  80Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 
> span_message:  5dSep 11 11:22:56
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  00Sep 11 11:22:56 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  00Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 
> span_message:
> Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 span_message: FLOW NSF 
> without final frame tag
> Sep 11 11:22:56 DEBUG[20520]: app_txfax.c:69 span_message: FLOW The 
> remote was made by 'Brother'
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW <<< 
> CSI:Sep 11 11:22:57 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  40Sep 11 11:22:57 DEBUG[20520]: 
> app_txfax.c:69 span_message:  32Sep 11
>  
> 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message:  30Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  31Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  20Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  20Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  20Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  20Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  20Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  20Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  20Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  20Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  20Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  20Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  20Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  20Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  20Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  20Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  20Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  20Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  20Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW CSI 
> without final frame tag
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW Remote 
> fax gave CSI as: "102"
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW <<< 
> DIS:Sep 11 11:22:57 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  80Sep 11 11:22:57 DEBUG[20520]: 
> app_txfax.c:69 span_message:  00Sep 11
>  
> 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message:  eeSep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  f8Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  c4Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  80Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  10Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW DIS 
> with final frame tag
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW In 
> state 10
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW Start 
> sending document
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 3 to 4
> Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message: FLOW >>> 
> DCS:Sep 11 11:22:57 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  83Sep 11 11:22:57 DEBUG[20520]: 
> app_txfax.c:69 span_message:  00Sep 11
>  
> 11:22:57 DEBUG[20520]: app_txfax.c:69 span_message:  86Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  80Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:  80Sep 11 11:22:57
>  
> DEBUG[20520]: app_txfax.c:69 span_message:  80Sep 11 11:22:57 
> DEBUG[20520]: app_txfax.c:69
>  
> span_message:  00Sep 11 11:22:57 DEBUG[20520]: app_txfax.c:69 
> span_message:
> Sep 11 11:22:59 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> underflow in state 3
> Sep 11 11:22:59 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 4 to 6
> Sep 11 11:23:01 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 6 to 3
> Sep 11 11:23:01 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier up
> Sep 11 11:23:01 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> framing OK
> Sep 11 11:23:02 DEBUG[20520]: app_txfax.c:69 span_message: FLOW <<< 
> CFR:Sep 11 11:23:02 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  84Sep 11 11:23:02 DEBUG[20520]: 
> app_txfax.c:69 span_message:
> Sep 11 11:23:02 DEBUG[20520]: app_txfax.c:69 span_message: FLOW CFR 
> with final frame tag
> Sep 11 11:23:02 DEBUG[20520]: app_txfax.c:69 span_message: FLOW In state 4
> Sep 11 11:23:02 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Trainability test succeeded
> Sep 11 11:23:03 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier down
> Sep 11 11:23:03 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 3 to 6
> Sep 11 11:23:09 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 6 to 4
> Sep 11 11:23:09 DEBUG[20520]: app_txfax.c:69 span_message: FLOW >>> 
> EOP:Sep 11 11:23:09 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  2fSep 11 11:23:09 DEBUG[20520]: 
> app_txfax.c:69 span_message:
> Sep 11 11:23:11 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> underflow in state 14
> Sep 11 11:23:11 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 4 to 3
> Sep 11 11:23:11 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> carrier up
> Sep 11 11:23:11 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> framing OK
> Sep 11 11:23:12 DEBUG[20520]: app_txfax.c:69 span_message: FLOW <<< 
> RTN:Sep 11 11:23:12 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  4cSep 11 11:23:12 DEBUG[20520]: 
> app_txfax.c:69 span_message:
> Sep 11 11:23:12 DEBUG[20520]: app_txfax.c:69 span_message: FLOW RTN 
> with final frame tag
> Sep 11 11:23:12 DEBUG[20520]: app_txfax.c:69 span_message: FLOW In 
> state 14
> Sep 11 11:23:12 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 3 to 4
> Sep 11 11:23:12 DEBUG[20520]: app_txfax.c:69 span_message: FLOW >>> 
> DCN:Sep 11 11:23:12 DEBUG[20520]:
>  
> app_txfax.c:69 span_message:  fbSep 11 11:23:12 DEBUG[20520]: 
> app_txfax.c:69 span_message:
> Sep 11 11:23:14 DEBUG[20520]: app_txfax.c:69 span_message: FLOW HDLC 
> underflow in state 2
> Sep 11 11:23:14 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Disconnecting
> Sep 11 11:23:14 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 4 to 7
> Sep 11 11:23:14 DEBUG[20520]: app_txfax.c:69 span_message: FLOW 
> Changed from phase 7 to 8
>     -- Executing Hangup("Aculab/140515:2-1", "") in new stack
>   == Spawn extension (ext_indial, 711, 5) exited non-zero on 
> 'Aculab/140515:2-1'
> Sep 11 11:23:14 DEBUG[20520]: cdr_addon_mysql.c:206 mysql_log: 
> cdr_mysql: inserting a CDR record.
> Sep 11 11:23:14 DEBUG[20520]: cdr_addon_mysql.c:222 mysql_log: 
> cdr_mysql: SQL command as follows:
>  
> INSERT INTO cdr
>  
> (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amafla
>  
> gs,accountcode) VALUES ('2006-09-11 11:22:49','','','711','ext_indial',
>  
> 'Aculab/140515:2-1','','Hangup','',25,0,'ANSWERED',3,'')
> Sep 11 11:23:14 DEBUG[20520]: chan_aculab.c:1232 
> aculab_stop_play_record_and_listen: stopping
>  
> record/play
> Sep 11 11:23:14 DEBUG[20520]: chan_aculab.c:731 
> aculab_service_record_data: record done
> Sep 11 11:23:14 DEBUG[20520]: chan_aculab.c:799 
> aculab_service_replay_data: replay done
> Sep 11 11:23:14 DEBUG[20520]: chan_aculab.c:1480 aculab_hangup: 
> call_disconnect on [140515:2:1]
> Sep 11 11:23:14 DEBUG[20520]: chan_aculab.c:1481 aculab_hangup: XXX 
> hangup(Aculab/140515:2-1) XXX
>     -- Hungup 'Aculab/140515:2-1'
> Sep 11 11:23:14 NOTICE[20520]: pbx_spool.c:279 attempt_thread: Call 
> completed to Aculab/2/306071000000
> Sep 11 11:23:14 DEBUG[20503]: chan_aculab.c:2151 aculab_update_state: 
> <140515:34:1-o> EV_IDLE on handle
>  
> 67441665
> Sep 11 11:23:14 DEBUG[20503]: chan_aculab.c:2186 aculab_update_state: 
> NORMALSep 11 11:23:14
>  
> DEBUG[20503]: chan_aculab.c:2221 aculab_update_state: call_release on 
> [140515:2:ts 1]
>  
>  
>  
>  
>  
>  
>
>------------------------------------------------------------------------
>
>_______________________________________________
>--Bandwidth and Colocation provided by Easynews.com --
>
>asterisk-dev mailing list
>To UNSUBSCRIBE or update options visit:
>   http://lists.digium.com/mailman/listinfo/asterisk-dev
>  
>