(continued log)..
Now as you see from the
above log there are errors in the app_txfax app with regard "Unable to find a codec translation path from
"
and with "Unable to restore read format " But these warning were also
thrown when sending to several fax machines & the image was recieved
correctly
In an attempt to rectify
these warnings i even tried to hardcode the codec types in the
txfax_exec app
with the following
modifications
res = ast_set_read_format(chan,AST_FORMAT_SLINEAR);
// res = ast_set_read_format(chan,
original_read_fmt);
..............
..............
..............
..............
res = ast_set_write_format(chan,AST_FORMAT_ULAW);
res = ast_set_write_format(chan,AST_FORMAT_ULAW);
// res = ast_set_write_format(chan,
original_write_fmt);
Then rebuild the
app_txfax.so in the modules directory and below is the resulting
log
-- Executing Wait("Aculab/140515:2-1", "1") in new stack
-- Executing TxFAX("Aculab/140515:2-1", "/var/spool/asterisk/1157636769.2.tif|caller|debug") in new
stack
Sep 14 11:26:28 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
-- Saved useragent "Sipura/SPA1001-2.0.13(SEg)" for peer 101
Sep 14 11:26:31 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier down
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 2 to 3
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< CSI:Sep 14 11:26:33 DEBUG[9295]:
Sep 14 11:26:28 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
-- Saved useragent "Sipura/SPA1001-2.0.13(SEg)" for peer 101
Sep 14 11:26:31 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier down
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 2 to 3
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:32 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< CSI:Sep 14 11:26:33 DEBUG[9295]:
app_txfax.c:69 span_message:
40Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: 20Sep 14
11:26:33 DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33
DEBUG[9295]: app_txfax.c:69 span_message: 20Sep 14 11:26:33
DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69
span_message:
20Sep 14 11:26:33 DEBUG[9295]:
app_txfax.c:69 span_message: 20Sep 14 11:26:33 DEBUG[9295]:
app_txfax.c:69 span_message:
20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: 20Sep 14
11:26:33 DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33
DEBUG[9295]: app_txfax.c:69 span_message: 20Sep 14 11:26:33
DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69
span_message:
20Sep 14 11:26:33 DEBUG[9295]:
app_txfax.c:69 span_message: 20Sep 14 11:26:33 DEBUG[9295]:
app_txfax.c:69 span_message:
20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: 20Sep 14
11:26:33 DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69
span_message: 20Sep 14 11:26:33
DEBUG[9295]: app_txfax.c:69 span_message: 20Sep 14 11:26:33
DEBUG[9295]: app_txfax.c:69
span_message:
Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: FLOW CSI without final frame tag
Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Remote fax gave CSI as: ""
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< DIS:Sep 14 11:26:34 DEBUG[9295]:
Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: FLOW CSI without final frame tag
Sep 14 11:26:33 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Remote fax gave CSI as: ""
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< DIS:Sep 14 11:26:34 DEBUG[9295]:
app_txfax.c:69 span_message:
80Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: 00Sep 14
11:26:34 DEBUG[9295]: app_txfax.c:69
span_message: eeSep 14 11:26:34 DEBUG[9295]: app_txfax.c:69
span_message: f8Sep 14 11:26:34
DEBUG[9295]: app_txfax.c:69 span_message: c4Sep 14 11:26:34
DEBUG[9295]: app_txfax.c:69
span_message: 80Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69
span_message:
10Sep 14 11:26:34 DEBUG[9295]:
app_txfax.c:69 span_message:
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW DIS with final frame tag
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW In state 10
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Start sending document
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 3 to 4
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW >>> DCS:Sep 14 11:26:34 DEBUG[9295]:
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW DIS with final frame tag
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW In state 10
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Start sending document
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 3 to 4
Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: FLOW >>> DCS:Sep 14 11:26:34 DEBUG[9295]:
app_txfax.c:69 span_message:
83Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69 span_message: 00Sep 14
11:26:34 DEBUG[9295]: app_txfax.c:69
span_message: 86Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69
span_message: 80Sep 14 11:26:34
DEBUG[9295]: app_txfax.c:69 span_message: 80Sep 14 11:26:34
DEBUG[9295]: app_txfax.c:69
span_message: 80Sep 14 11:26:34 DEBUG[9295]: app_txfax.c:69
span_message:
00Sep 14 11:26:34 DEBUG[9295]:
app_txfax.c:69 span_message:
Sep 14 11:26:35 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC underflow in state 3
Sep 14 11:26:35 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 4 to 6
Sep 14 11:26:37 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 6 to 3
Sep 14 11:26:37 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:37 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< CFR:Sep 14 11:26:39 DEBUG[9295]:
Sep 14 11:26:35 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC underflow in state 3
Sep 14 11:26:35 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 4 to 6
Sep 14 11:26:37 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 6 to 3
Sep 14 11:26:37 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:37 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< CFR:Sep 14 11:26:39 DEBUG[9295]:
app_txfax.c:69 span_message:
84Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message:
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW CFR with final frame tag
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW In state 4
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Trainability test succeeded
Sep 14 11:26:39 DEBUG[9288]: chan_sip.c:1410 __sip_ack: Stopping retransmission on
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW CFR with final frame tag
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW In state 4
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Trainability test succeeded
Sep 14 11:26:39 DEBUG[9288]: chan_sip.c:1410 __sip_ack: Stopping retransmission on
'[EMAIL PROTECTED]' of Request 102: Match Found
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier down
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 3 to 6
Sep 14 11:26:44 DEBUG[9288]: chan_sip.c:1332 __sip_autodestruct: Auto destroying call
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier down
Sep 14 11:26:39 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 3 to 6
Sep 14 11:26:44 DEBUG[9288]: chan_sip.c:1332 __sip_autodestruct: Auto destroying call
'[EMAIL PROTECTED]'
Sep 14 11:26:46 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 6 to 4
Sep 14 11:26:46 DEBUG[9295]: app_txfax.c:69 span_message: FLOW >>> EOP:Sep 14 11:26:46 DEBUG[9295]:
Sep 14 11:26:46 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 6 to 4
Sep 14 11:26:46 DEBUG[9295]: app_txfax.c:69 span_message: FLOW >>> EOP:Sep 14 11:26:46 DEBUG[9295]:
app_txfax.c:69 span_message:
2fSep 14 11:26:46 DEBUG[9295]: app_txfax.c:69 span_message:
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC underflow in state 14
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 4 to 3
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< RTN:Sep 14 11:26:49 DEBUG[9295]:
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC underflow in state 14
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 4 to 3
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC carrier up
Sep 14 11:26:47 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC framing OK
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW <<< RTN:Sep 14 11:26:49 DEBUG[9295]:
app_txfax.c:69 span_message:
4cSep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message:
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW RTN with final frame tag
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW In state 14
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 3 to 4
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW >>> DCN:Sep 14 11:26:49 DEBUG[9295]:
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW RTN with final frame tag
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW In state 14
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 3 to 4
Sep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message: FLOW >>> DCN:Sep 14 11:26:49 DEBUG[9295]:
app_txfax.c:69 span_message:
fbSep 14 11:26:49 DEBUG[9295]: app_txfax.c:69 span_message:
Sep 14 11:26:50 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC underflow in state 2
Sep 14 11:26:50 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Disconnecting
Sep 14 11:26:50 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 4 to 7
Sep 14 11:26:50 DEBUG[9295]: 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, 717, 5) exited non-zero on 'Aculab/140515:2-1'
Sep 14 11:26:50 DEBUG[9295]: cdr_addon_mysql.c:206 mysql_log: cdr_mysql: inserting a CDR record.
Sep 14 11:26:50 DEBUG[9295]: cdr_addon_mysql.c:222 mysql_log: cdr_mysql: SQL command as follows: INSERT
Sep 14 11:26:50 DEBUG[9295]: app_txfax.c:69 span_message: FLOW HDLC underflow in state 2
Sep 14 11:26:50 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Disconnecting
Sep 14 11:26:50 DEBUG[9295]: app_txfax.c:69 span_message: FLOW Changed from phase 4 to 7
Sep 14 11:26:50 DEBUG[9295]: 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, 717, 5) exited non-zero on 'Aculab/140515:2-1'
Sep 14 11:26:50 DEBUG[9295]: cdr_addon_mysql.c:206 mysql_log: cdr_mysql: inserting a CDR record.
Sep 14 11:26:50 DEBUG[9295]: 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-14
11:26:26','','','717','ext_indial',
'Aculab/140515:2-1','','Hangup','',24,0,'ANSWERED',3,'')
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:1232 aculab_stop_play_record_and_listen: stopping
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:1232 aculab_stop_play_record_and_listen: stopping
record/play
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2136 aculab_update_state: <140515:34:1-o>
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2136 aculab_update_state: <140515:34:1-o>
EV_REMOTE_DISCONNECT on handle
33886209
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:731 aculab_service_record_data: record done
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:799 aculab_service_replay_data: replay done
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:1480 aculab_hangup: call_disconnect on [140515:2:1]
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:1481 aculab_hangup: XXX hangup(Aculab/140515:2-1) XXX
-- Hungup 'Aculab/140515:2-1'
Sep 14 11:26:50 NOTICE[9295]: pbx_spool.c:279 attempt_thread: Call completed to Aculab/2/506071000000
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2151 aculab_update_state: <140515:34:1-o> EV_IDLE on handle
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:731 aculab_service_record_data: record done
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:799 aculab_service_replay_data: replay done
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:1480 aculab_hangup: call_disconnect on [140515:2:1]
Sep 14 11:26:50 DEBUG[9295]: chan_aculab.c:1481 aculab_hangup: XXX hangup(Aculab/140515:2-1) XXX
-- Hungup 'Aculab/140515:2-1'
Sep 14 11:26:50 NOTICE[9295]: pbx_spool.c:279 attempt_thread: Call completed to Aculab/2/506071000000
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2151 aculab_update_state: <140515:34:1-o> EV_IDLE on handle
33886209
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2168 aculab_update_state: 140515-0:0 --> DISABLE
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2186 aculab_update_state: NORMALSep 14 11:26:50 DEBUG[9287]:
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2168 aculab_update_state: 140515-0:0 --> DISABLE
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2186 aculab_update_state: NORMALSep 14 11:26:50 DEBUG[9287]:
chan_aculab.c:2221
aculab_update_state: call_release on [140515:2:ts 1]
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2251 aculab_update_state: idle from outbound call fixing
Sep 14 11:26:50 DEBUG[9287]: chan_aculab.c:2251 aculab_update_state: idle from outbound call fixing
inbound handle
As you can see the
warnings are now gone and it is still sending fine to the brother fax machine
but still not to the brooktrout.
As i said
earlier as far as i can see all the negotiating is done fine
but just bad data is received for the tif. Hope some of these new logs
are
a bit clearer and that maybe ye might be able to
see any issues alot easier if any are
apparent.
Any
ideas from anybody would be great, definitely would be a nice thing to get
working.
Kind Regards,
Brian.
Brian.
_______________________________________________ --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
