[asterisk-bugs] [Asterisk 0016750]: Audio loop reports T38 switchover but t38state != T38_STATE_NEGOTIATED

[Asterisk Bug Tracker]

A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=16750 
====================================================================== 
Reported By:                iskatel
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   16750
Category:                   Applications/app_fax
Reproducibility:            always
Severity:                   major
Priority:                   normal
Status:                     acknowledged
Asterisk Version:           1.6.2.1 
JIRA:                       SWP-839 
Regression:                 No 
Reviewboard Link:            
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2010-02-02 01:32 CST
Last Modified:              2010-06-04 14:41 CDT
====================================================================== 
Summary:                    Audio loop reports T38 switchover but t38state !=
T38_STATE_NEGOTIATED
Description: 
SendFax not working with t38

Path of call
Asterisk(1.1.1.1) -> Cisco AS5300(2.2.2.2) -> PSTN

app_fax.c was applied from
http://svnview.digium.com/svn/asterisk/branches/1.6.2?view=revision&revision=225871

After re-INVITE has been received from Cisco, asterisk sends 200 OK, then
ERROR appears and asterisk breaks the call. ReceiveFax works.    

====================================================================== 

---------------------------------------------------------------------- 
 (0122992) areversat (reporter) - 2010-06-04 14:41
 https://issues.asterisk.org/view.php?id=16750#c122992 
---------------------------------------------------------------------- 
I have the same problem here. Channel negogiates T.38 and then I get Audio
loop reports T38 switchover but t38state != T38_STATE_NEGOTIATED. From what
I can see in the code it's as if the channel driver didn't have the time to
update it's t.38 state.

                res = transmit_audio(s);
                if (res > 0) {
                        /* transmit_audio reports switchover to T38.
Update t38state */
                        s->t38state = ast_channel_get_t38_state(s->chan);
                        if (s->t38state != T38_STATE_NEGOTIATED) {

Here is the relevant log snippet :

[Jun  4 15:02:32] DEBUG[5163] channel.c: Scheduling timer at (50 requested
/ 50 actual) timer ticks per second
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: frame 4/-1, len=0
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: frame 4/20, len=0
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: frame 4/24, len=24
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: T38 request received, accepting
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: frame 4/20, len=0
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: frame 4/24, len=24
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: T38 negotiated, finishing audio
loop
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: Loop finished, res=1
[Jun  4 15:02:32] DEBUG[5163] channel.c: Scheduling timer at (0 requested
/ 0 actual) timer ticks per second
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: FLOW T.30 Changing from state 18
to 32
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: FLOW T.30 Changing from phase
T30_PHASE_A_CNG to T30_PHASE_CALL_FINISHED
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: FLOW FAX Set rx type 8
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: FLOW FAX FAX exchange complete
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: FLOW FAX Set tx type 8
[Jun  4 15:02:32] DEBUG[5163] app_fax.c: FLOW FAX FAX exchange complete
[Jun  4 15:02:32] ERROR[5163] app_fax.c: Audio loop reports T38 switchover
but t38state != T38_STATE_NEGOTIATED
[Jun  4 15:02:32] WARNING[5163] app_fax.c: Transmission error 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2010-06-04 14:41 areversat      Note Added: 0122992                          
======================================================================