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