ASTERISK-14371: After update to 1.6.0.10 ReceiveFAX doesn't work.

[Home]

Summary: ASTERISK-14371: After update to 1.6.0.10 ReceiveFAX doesn't work.

Reporter: Badalian Vyacheslav (slavon) Labels:

Date Opened: 2009-06-25 04:04:27 Date Closed: 2011-06-07 14:00:57

Priority: Major Regression? No

Status: Closed/Complete Components: Applications/app_fax

Versions: Frequency of
Occurrence

Related
Issues:

Environment: Attachments:

Description: After update app_fax broke

Many
[Jun 25 13:00:41] WARNING[6867]: translate.c:187 framein: Out of buffer space
warnings and sometime
[Jun 25 13:00:42] ERROR[6867]: channel.c:2520 __ast_read: ast_read() called with no recorded file descriptor.
errors.

Say me please if you not have quick fix for this and i add full debug.

Thanks

Comments: By: Badalian Vyacheslav (slavon) 2009-06-25 04:08:08

Khm... sorry for summary :(

Change please to like
After update to 1.6.0.10 ReceiveFAX don't work.
By: Dmitry Andrianov (dimas) 2009-06-25 16:20:08

How about ASTERISK-13846 ?
By: Badalian Vyacheslav (slavon) 2009-06-26 02:47:43

Very Very strange!!!

After apply patch i see very strange think!

core set verbose 0 i see many and silent in line

voip*CLI>
[Jun 26 11:44:13] NOTICE[7472]: channel.c:2857 __ast_read: Dropping incompatible voice frame on SIP/87.255.0.218-08483918 of format alaw since our native format has changed to 0x4 (ulaw)
[Jun 26 11:44:13] WARNING[7472]: translate.c:187 framein: Out of buffer space
[Jun 26 11:44:13] WARNING[7472]: translate.c:187 framein: Out of buffer space
[Jun 26 11:44:13] WARNING[7472]: translate.c:187 framein: Out of buffer space

if a change core set verbose 1 i listen fax zoomer and i see this in screen:

[Jun 26 11:45:55] NOTICE[7527]: channel.c:2857 __ast_read: Dropping incompatible voice frame on SIP/87.255.0.218-08483918 of format alaw since our native format has changed to 0x4 (ulaw)
[Jun 26 11:45:55] DEBUG[7527]: app_fax.c:129 span_message: ·??·8?µ‡?µ???·

after i put line - standart message

[Jun 26 11:45:58] WARNING[7527]: app_fax.c:177 phase_e_handler: Error transmitting fax. result=49: The call dropped prematurely.
[Jun 26 11:45:58] WARNING[7527]: app_fax.c:655 transmit: Transmission error
By: Badalian Vyacheslav (slavon) 2009-06-30 01:13:13

Why if i set "core set verbose 0" and "core set debug 0"

i get many "[Jun 30 10:10:17] WARNING[26513]: translate.c:187 framein: Out of buffer space" errors and ReciveFax does not work!

If i set debug or verbose to > 0 ReciveFax work.

Its not normal...
By: Dmitry Andrianov (dimas) 2009-06-30 03:37:28

are you saying that when you say debug or verbose > 0 the WARNINGs do NOT appear anymore? Or they appear but receivefax still works?
By: Badalian Vyacheslav (slavon) 2009-06-30 04:22:19

yes. its (warning) go out and recvivefax work!
if i set to 0 i see warnings and listen silent in phone (recivefax does not work)

very strange.
By: Dmitry Andrianov (dimas) 2009-06-30 04:27:23

indeed.
can you find what exactly (core set debug 1 OR core set verbose 1) makes things work? Or you need both?
By: Badalian Vyacheslav (slavon) 2009-06-30 04:47:00

any! or verbose or debug!
By: Dmitry Andrianov (dimas) 2009-06-30 04:57:50

Have you tried patch from ASTERISK-13846 ?
By: Badalian Vyacheslav (slavon) 2009-06-30 05:31:25

Yes. Its applied
By: Dmitry Andrianov (dimas) 2009-06-30 07:09:37

It is really strange.
Honestly I have no ideas.. Actually, I have only one which probably the wrong one :) Something like one of the calls to some important function is put inside the logging call. Like:

ast_verbose(1, ".....", some_important_calls(some_important_arguments));

Which does not get executed when verbose is off. However you are saying that debug works too which is strange because ast_verbose and ast_debug uses different checks...

Can you provide console output with debug=0,verbose=0 and also a output with debug=0,verbose=1 ?
By: Badalian Vyacheslav (slavon) 2009-06-30 07:34:12

Shure
[verbose=0]
[Jun 30 16:28:28] NOTICE[5684]: channel.c:2857 __ast_read: Dropping incompatible voice frame on SIP/87.255.0.218-b502e838 of format alaw since our native format has changed to 0x4 (ulaw)
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:28] WARNING[5684]: translate.c:187 framein: Out of buffer space
................. here many repeats and i press END CALL .....
[Jun 30 16:28:49] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:50] WARNING[5684]: translate.c:187 framein: Out of buffer space
[Jun 30 16:28:50] WARNING[5684]: app_fax.c:177 phase_e_handler: Error transmitting fax. result=49: The call dropped prematurely.
[Jun 30 16:28:50] WARNING[5684]: app_fax.c:655 transmit: Transmission error

[verbose=1]
[Jun 30 16:31:04] NOTICE[5770]: channel.c:2857 __ast_read: Dropping incompatible voice frame on SIP/87.255.0.218-b50151c8 of format alaw since our native format has changed to 0x4 (ulaw)
[Jun 30 16:31:04] DEBUG[5770]: app_fax.c:129 span_message: ·??·85??‡4?????·[Jun 30 16:31:29] WARNING[5770]: app_fax.c:177 phase_e_handler: Error transmitting fax. result=48: Disconnected after permitted retries.
[Jun 30 16:31:29] WARNING[5770]: app_fax.c:658 transmit: Transmission failed

After span_message not have \n! and message all time is different!

In detail:

[Jun 30 16:31:04] DEBUG[5770]: app_fax.c:129 span_message: ·??·85??‡4?????·<-----------here fax zoomer play and when without \n a get: ------------->[Jun 30 16:31:29] WARNING[5770]: app_fax.c:177 phase_e_handler: Error transmitting fax. result=48: Disconnected after permitted retries.
By: Dmitry Andrianov (dimas) 2009-06-30 08:23:54

But you said that with verbose=1 it works ok.
How "Error transmitting fax. result=48: Disconnected after permitted retries.
[Jun 30 16:31:29] WARNING[5770]: app_fax.c:658 transmit: Transmission failed" maps to that?...
By: Badalian Vyacheslav (slavon) 2009-06-30 08:42:09

i call from standard ip phone to get verbose messages :)
By: Leif Madsen (lmadsen) 2009-08-20 15:43:07

Assigned to kpfleming for review as he has been working on fax lately. Please reassign as appropriate if this does not fit into the changes you have been working on. Thanks!
By: Leif Madsen (lmadsen) 2009-08-20 15:59:01

As this is being reported against 1.6.0.10, can you please re-test with the latest release in the 1.6.0 series as some significant work has been done recently in the fax realm.

If you still experience the issue, then you'll need to attach the usual set of SIP debugging information:

Full SIP trace of the issue, console debug, and SIP history.

Thanks!

By: Leif Madsen (lmadsen) 2009-08-20 16:20:18

My apologies -- not the latest release. The latest release candidate (as the latest release is 1.6.0.10 + security fixes).
By: Badalian Vyacheslav (slavon) 2009-08-21 01:25:16

1.6.0.14-rc1 work fine for me. Thanks!