[2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:23] Set("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(ecm)=yes") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:24] Set("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(headerinfo)=Received by 6135997206 2018-06-25 14:05") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:25] Set("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(localstationid)=6135997206") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:26] Set("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(maxrate)=14400") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:27] Set("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(minrate)=2400") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:28] NoOp("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(headerinfo) : Received by 6135997206 2018-06-25 14:05") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:29] NoOp("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(localstationid) : 6135997206") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:30] NoOp("PJSIP/FC-DFW-PROXY-0000f050", "FAXOPT(status) : ") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:31] ExecIf("PJSIP/FC-DFW-PROXY-0000f050", "0?Set(EXT_NAME=):Set(EXT_NAME=COMPANY)") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:32] Set("PJSIP/FC-DFW-PROXY-0000f050", "CDR(extension_name)=COMPANY") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:33] Verbose("PJSIP/FC-DFW-PROXY-0000f050", "1,Fax receive prepared -- waiting 6 seconds for remote carrier UDPTL re-invites") in new stack [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] app_verbose.c: Fax receive prepared -- waiting 6 seconds for remote carrier UDPTL re-invites [2018-06-25 14:05:55] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:34] Wait("PJSIP/FC-DFW-PROXY-0000f050", "6") in new stack [2018-06-25 14:05:55] VERBOSE[3369][C-00005bd3] app_mixmonitor.c: End MixMonitor Recording PJSIP/FC-DFW-PROXY-0000f050 [2018-06-25 14:06:01] VERBOSE[3368][C-00005bd3] pbx.c: Executing [fax@fax-receive:35] ReceiveFAX("PJSIP/FC-DFW-PROXY-0000f050", "/var/spool/asterisk/fax/fax-20180625-140555-000.tif,fd") in new stack [2018-06-25 14:06:01] VERBOSE[3368][C-00005bd3] res_fax.c: Channel 'PJSIP/FC-DFW-PROXY-0000f050' receiving FAX '/var/spool/asterisk/fax/fax-KOMnetworks-20180625-140555-000.tif' [2018-06-25 14:06:04] WARNING[3368][C-00005bd3] res_fax.c: channel 'PJSIP/FC-DFW-PROXY-0000f050' refused to negotiate T.38 [2018-06-25 14:06:04] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1 [2018-06-25 14:06:05] VERBOSE[3368][C-00005bd3] res_fax.c: Channel 'PJSIP/FC-DFW-PROXY-0000f050' fax session '373', [ 000.220398 ], stack sent 10 frames (200 ms) of silence. [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1 [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1 [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1 [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1 [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1 [2018-06-25 14:06:06] VERBOSE[3368][C-00005bd3] res_fax.c: Channel 'PJSIP/FC-DFW-PROXY-0000f050' fax session '373', [ 001.794873 ], channel sent 89 frames (1780 ms) of energy. [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1 [2018-06-25 14:06:06] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1 [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_A_CED, state 1 [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Starting answer mode [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_A_CED to T30_PHASE_B_TX [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW FAX Set rx type 0 [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW FAX Set tx type 4 [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Start T2 [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Changing from state 1 to 17 [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Sending ident '6135997206' [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Tx: CSI without final frame tag [2018-06-25 14:06:07] FAX[3368][C-00005bd3] res_fax.c: FLOW T.30 Tx: ff 03 40 36 30 32 37 39 39 35 33 31 36 20 20 20 20 20 20 20 20 20 20 [2018-06-25 14:06:07] VERBOSE[3368][C-00005bd3] res_fax.c: Channel 'PJSIP/FC-DFW-PROXY-0000f050' fax session '373', [ 002.820542 ], stack sent 130 frames (2600 ms) of energy. [2018-06-25 14:06:07] VERBOSE[3368][C-00005bd3] res_fax.c: Channel 'PJSIP/FC-DFW-PROXY-0000f050' fax session '373', [ 002.880392 ], stack sent 3 frames (60 ms) of silence. [2018-06-25 14:06:07] VERBOSE[3368][C-00005bd3] res_fax.c: Channel 'PJSIP/FC-DFW-PROXY-0000f050' fax session '373', [ 003.034922 ], channel sent 62 frames (1240 ms) of silence.