[Jul 14 11:53:42] DEBUG[16137] devicestate.c: device 'DAHDI/31-1' state '2'
[Jul 14 11:53:42] DEBUG[16137] chan_dahdi.c: Enabled echo cancellation on channel 31
[Jul 14 11:53:42] DEBUG[16282] pbx.c: Launching 'Answer'
[Jul 14 11:53:42] DEBUG[16118] channel.c: Avoiding initial deadlock for channel '0x8698f60'
[Jul 14 11:53:42] DEBUG[16282] chan_dahdi.c: Requested indication -1 on channel DAHDI/31-1
[Jul 14 11:53:42] DEBUG[16118] channel.c: Avoiding initial deadlock for channel '0x8698f60'
[Jul 14 11:53:42] DEBUG[16118] devicestate.c: Changing state for DAHDI/31 - state 2 (In use)
[Jul 14 11:53:42] DEBUG[16118] devicestate.c: device 'DAHDI/31' state '2'
[Jul 14 11:53:42] DEBUG[16282] pbx.c: Function result is '111'
[Jul 14 11:53:42] DEBUG[16282] pbx.c: Launching 'Set'
[Jul 14 11:53:42] DEBUG[16282] pbx.c: Function result is '111'
[Jul 14 11:53:42] DEBUG[16282] pbx.c: Launching 'Set'
[Jul 14 11:53:42] DEBUG[16282] pbx.c: Launching 'ReceiveFAX'
[Jul 14 11:53:42] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to read format slin
[Jul 14 11:53:42] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to write format slin
[Jul 14 11:53:42] DEBUG[16282] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Jul 14 11:53:42] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[Jul 14 11:53:42] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[Jul 14 11:53:42] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[Jul 14 11:53:42] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[Jul 14 11:53:43] DEBUG[16282] chan_dahdi.c: Exception on 44, channel 31
[Jul 14 11:53:43] DEBUG[16282] chan_dahdi.c: Got event Event 27(27) on channel 31 (index 0)
[Jul 14 11:53:43] DEBUG[16282] chan_dahdi.c: Dunno what to do with event 27 on channel 31
[Jul 14 11:53:43] DEBUG[16282] chan_dahdi.c: Exception on 44, channel 31
[Jul 14 11:53:43] DEBUG[16282] chan_dahdi.c: Got event Event 23(23) on channel 31 (index 0)
[Jul 14 11:53:43] DEBUG[16282] chan_dahdi.c: Dunno what to do with event 23 on channel 31
[Jul 14 11:53:44] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[Jul 14 11:53:44] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_A_CED, state 1
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Starting answer mode
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_A_CED to T30_PHASE_B_TX
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 4
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Start T2
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 1 to 17
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 DIS:
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..1.= Can receive fax: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= 2-D coding: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..00= Recording width: 215mm +- 1%
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 10..= Recording length: Unlimited
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .1..= Error correction mode (ECM): ECM
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= T.6 coding: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Polled sub-address: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= T.43 coding: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Inch-based resolution preferred: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Selective polling: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Sub-addressing: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Password: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Character mode: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Duplex capability: Half only
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= JPEG coding: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30   0... ....= Extension indicator: Not set
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  DIS with final frame tag
[Jul 14 11:53:45] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[Jul 14 11:53:46] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:53:46] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:53:46] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[Jul 14 11:53:46] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 4
[Jul 14 11:53:46] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:53:46] DEBUG[16282] app_fax.c: FLOW T.30 Start T4
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 Retry number 1
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 4
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 DIS:
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..1.= Can receive fax: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= 2-D coding: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..00= Recording width: 215mm +- 1%
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 10..= Recording length: Unlimited
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .1..= Error correction mode (ECM): ECM
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= T.6 coding: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Polled sub-address: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= T.43 coding: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Inch-based resolution preferred: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Selective polling: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Sub-addressing: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Password: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Character mode: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Duplex capability: Half only
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= JPEG coding: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30   0... ....= Extension indicator: Not set
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  DIS with final frame tag
[Jul 14 11:53:49] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[Jul 14 11:53:51] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:53:51] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:53:51] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[Jul 14 11:53:51] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 4
[Jul 14 11:53:51] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:53:51] DEBUG[16282] app_fax.c: FLOW T.30 Start T4
[Jul 14 11:53:53] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[Jul 14 11:53:53] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[Jul 14 11:53:53] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[Jul 14 11:53:53] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[Jul 14 11:53:53] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[Jul 14 11:53:53] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30 Retry number 2
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 4
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30 DIS:
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..1.= Can receive fax: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= 2-D coding: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..00= Recording width: 215mm +- 1%
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 10..= Recording length: Unlimited
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .1..= Error correction mode (ECM): ECM
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= T.6 coding: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Polled sub-address: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= T.43 coding: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Inch-based resolution preferred: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Selective polling: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Sub-addressing: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Password: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Character mode: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Duplex capability: Half only
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= JPEG coding: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30   0... ....= Extension indicator: Not set
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  DIS with final frame tag
[Jul 14 11:53:54] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[Jul 14 11:53:56] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:53:56] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:53:56] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[Jul 14 11:53:56] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 4
[Jul 14 11:53:56] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:53:56] DEBUG[16282] app_fax.c: FLOW T.30 Start T4
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Too many retries. Giving up.
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 17 to 3
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  DCN with final frame tag
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 fa
[Jul 14 11:53:59] DEBUG[16282] dsp.c: 1100 Hz done detected
[Jul 14 11:53:59] DEBUG[16282] chan_dahdi.c: DTMF digit: f on DAHDI/31-1
[Jul 14 11:53:59] DEBUG[16282] channel.c: Soft-Hanging up channel 'DAHDI/31-1'
[Jul 14 11:53:59] DEBUG[16282] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: Channel hangup
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: Loop finished, res=-1
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Disconnecting
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_E
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 1
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 3 to 2
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: Fax phase E handler. result=48
[Jul 14 11:53:59] WARNING[16282] app_fax.c: Error transmitting fax. result=48: Disconnected after permitted retries.
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 2 to 32
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_E to T30_PHASE_CALL_FINISHED
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 8
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW FAX FAX exchange complete
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 8
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW FAX FAX exchange complete
[Jul 14 11:53:59] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to write format alaw
[Jul 14 11:53:59] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to read format alaw
[Jul 14 11:53:59] WARNING[16282] app_fax.c: Transmission error
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Spawn extension (from-avaya,fax,1) exited non-zero on 'DAHDI/31-1'
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Launching 'Goto'
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Launching 'Answer'
[Jul 14 11:53:59] DEBUG[16282] chan_dahdi.c: Requested indication -1 on channel DAHDI/31-1
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Function result is '111'
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Launching 'Set'
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Function result is '111'
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Launching 'Set'
[Jul 14 11:53:59] DEBUG[16282] pbx.c: Launching 'ReceiveFAX'
[Jul 14 11:53:59] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to read format slin
[Jul 14 11:53:59] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to write format slin
[Jul 14 11:53:59] DEBUG[16282] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[Jul 14 11:53:59] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[Jul 14 11:54:00] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[Jul 14 11:54:00] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_A_CED, state 1
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Starting answer mode
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_A_CED to T30_PHASE_B_TX
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 4
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Start T2
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 1 to 17
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 DIS:
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..1.= Can receive fax: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= 2-D coding: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..00= Recording width: 215mm +- 1%
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 10..= Recording length: Unlimited
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .1..= Error correction mode (ECM): ECM
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .1.. ....= T.6 coding: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Polled sub-address: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= T.43 coding: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Inch-based resolution preferred: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Selective polling: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Sub-addressing: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Password: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Character mode: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Duplex capability: Half only
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= JPEG coding: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30   0... ....= Extension indicator: Not set
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  DIS with final frame tag
[Jul 14 11:54:02] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[Jul 14 11:54:03] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 4
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 Start T4
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Abort (-8) in state 17
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Framing OK (-6) in state 17
[Jul 14 11:54:04] DEBUG[16282] app_fax.c: FLOW T.30 Start T4A
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Stop T4A (14240 remaining)
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Rx:  DCS with final frame tag
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Rx:  ff 13 83 00 86 f8 00
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 In state 17
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 DCS:
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... ..1.= Receive fax: Set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   ..00 01..= Selected data signalling rate: V.29, 9600bps
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Not set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= 2-D coding: Set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... ..00= Recording width: 215mm +- 1%
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... 10..= Recording length: Unlimited
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .111 ....= Minimum scan line time: 0ms
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   1... ....= Extension indicator: Set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .... 0...= Frame size: 256 octets
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   .0.. ....= T.6 coding: Not set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30   0... ....= Extension indicator: Not set
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Selected compression 2
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Get document at 9600bps, modem 6
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 17 to 7
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 Start T2
[Jul 14 11:54:05] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Abort (-8) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_C_NON_ECM_RX
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 6
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier up (-2) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Training failed (-5) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Abort (-8) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier down (-1) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier up (-2) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Training in progress (-3) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Abort (-8) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Training succeeded (-4) in state 7
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW T.30 Stop T2 (51200 remaining)
[Jul 14 11:54:06] DEBUG[16282] app_fax.c: FLOW FAX Switching from V.29 + V.21 to V.29 (-15.08dBm0)
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier down (-1) in state 7
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW T.30 Trainability (TCF) test result - 14860 total bits. longest run of zeros was 14636
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_C_NON_ECM_RX to T30_PHASE_B_TX
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 4
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 7 to 8
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  CFR with final frame tag
[Jul 14 11:54:08] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 84
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 8
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 8
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 8 to 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_C_NON_ECM_RX
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 6
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Start T2
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier up (-2) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Training failed (-5) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier down (-1) in state 10
[Jul 14 11:54:09] WARNING[16282] app_fax.c: WARNING T.30 Non-ECM carrier not found
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Abort (-8) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier up (-2) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Training in progress (-3) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Abort (-8) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Training succeeded (-4) in state 10
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW T.30 Stop T2 (51680 remaining)
[Jul 14 11:54:09] DEBUG[16282] app_fax.c: FLOW FAX Switching from V.29 + V.21 to V.29 (-16.11dBm0)
[Jul 14 11:54:14] DEBUG[16123] acl.c: Found IP address for this socket
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 10 to 11
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 Start T2
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 Non-ECM signal status is Carrier down (-1) in state 11
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_C_NON_ECM_RX to T30_PHASE_D_RX
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 4
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 0
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 11
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Framing OK (-6) in state 11
[Jul 14 11:54:35] DEBUG[16282] app_fax.c: FLOW T.30 Start T2A
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Stop T2A (15200 remaining)
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Rx:  PRI-EOP with final frame tag
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Rx:  ff 13 3f
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 In state 11
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Page no = 1
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Image size = 1728 x 1142 pixels
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Image resolution = 8031/m x 3850/m
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Bad rows = 0
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Longest bad row run = 0
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Page quality is perfect
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 11 to 22
[Jul 14 11:54:36] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 22
[Jul 14 11:54:39] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 22
[Jul 14 11:54:40] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Framing OK (-6) in state 22
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Stop none (0 remaining)
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Rx:  PRI-EOP with final frame tag
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Rx:  ff 13 3f
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 In state 22
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Unexpected PRI-EOP received in state 22
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 22 to 3
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  DCN with final frame tag
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Tx:  ff 13 fa
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 3
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_D_RX to T30_PHASE_D_TX
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:54:41] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 4
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_D_TX, state 3
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_D_TX, state 3
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW T.30 Disconnecting
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_D_TX to T30_PHASE_E
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 0
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 1
[Jul 14 11:54:42] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 3 to 2
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW T.30 Send complete in phase T30_PHASE_E, state 2
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: Fax phase E handler. result=13
[Jul 14 11:54:43] WARNING[16282] app_fax.c: Error transmitting fax. result=13: Unexpected message received.
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW T.30 Changing from state 2 to 32
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW T.30 Changing from phase T30_PHASE_E to T30_PHASE_CALL_FINISHED
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW FAX Set rx type 8
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW FAX FAX exchange complete
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW FAX Set tx type 8
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: FLOW FAX FAX exchange complete
[Jul 14 11:54:43] DEBUG[16282] app_fax.c: Loop finished, res=0
[Jul 14 11:54:43] DEBUG[16282] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Jul 14 11:54:43] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to write format alaw
[Jul 14 11:54:43] DEBUG[16282] channel.c: Set channel DAHDI/31-1 to read format alaw
[Jul 14 11:54:43] WARNING[16282] app_fax.c: Transmission failed
[Jul 14 11:54:43] DEBUG[16282] channel.c: Soft-Hanging up channel 'DAHDI/31-1'
[Jul 14 11:54:43] DEBUG[16282] channel.c: Hanging up channel 'DAHDI/31-1'
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: dahdi_hangup(DAHDI/31-1)
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/31-1
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Hangup: channel: 31 index = 0, normal = 44, callwait = -1, thirdcall = -1
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Disabled echo cancellation on channel 31
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/31-1
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Updated conferencing on 31, with 0 conference users
[Jul 14 11:54:43] DEBUG[16282] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/31-1
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '111'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '111'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '543'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is 'from-avaya'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is 'DAHDI/31-1'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '(null)'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is 'ReceiveFAX'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '/var/spool/asterisk/fax/111.tif'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '2010-07-14 11:53:42'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '2010-07-14 11:53:42'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '2010-07-14 11:54:43'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '61'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '61'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is 'ANSWERED'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is 'DOCUMENTATION'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '(null)'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '1279086822.0'
[Jul 14 11:54:43] DEBUG[16282] pbx.c: Function result is '(null)'
[Jul 14 11:54:43] DEBUG[16118] devicestate.c: Changing state for DAHDI/31 - state 0 (Unknown)
[Jul 14 11:54:43] DEBUG[16118] devicestate.c: device 'DAHDI/31' state '0'