PBX is running on Asterisk 11.6.0 and Digium FAX Driver: 11.0_1.3.1
- Code: Select all
-- Executing [XXXXXXX@pstn-in:1] Goto("DAHDI/i4/ZXXXXXXXX-aee", "fax-rx,receive,1") in new stack
-- Goto (fax-rx,receive,1)
-- Executing [receive@fax-rx:1] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "**** FAX RECEIVE ****") in new stack
-- Executing [receive@fax-rx:2] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXFILE=faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif") in new stack
-- Executing [receive@fax-rx:3] Set("DAHDI/i4/ZXXXXXXXX-aee", "GLOBAL(LASTFAXCALLERNUM)=ZXXXXXXXX") in new stack
== Setting global variable 'LASTFAXCALLERNUM' to 'ZXXXXXXXX'
-- Executing [receive@fax-rx:4] Set("DAHDI/i4/ZXXXXXXXX-aee", "GLOBAL(LASTFAXCALLERNAME)=") in new stack
== Setting global variable 'LASTFAXCALLERNAME' to ''
-- Executing [receive@fax-rx:5] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "**** SETTING FAXOPT ****") in new stack
-- Executing [receive@fax-rx:6] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(ecm)=yes") in new stack
-- Executing [receive@fax-rx:7] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(headerinfo)=MY FAXBACK RX") in new stack
-- Executing [receive@fax-rx:8] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(localstationid)=713747390") in new stack
-- Executing [receive@fax-rx:9] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(maxrate)=9600") in new stack
-- Executing [receive@fax-rx:10] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(minrate)=2400") in new stack
-- Executing [receive@fax-rx:11] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(ecm) : yes") in new stack
-- Executing [receive@fax-rx:12] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(headerinfo) : MY FAXBACK RX") in new stack
-- Executing [receive@fax-rx:13] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(localstationid) : 713747390") in new stack
-- Executing [receive@fax-rx:14] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(maxrate) : 9600") in new stack
-- Executing [receive@fax-rx:15] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(minrate) : 2400") in new stack
-- Executing [receive@fax-rx:16] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "**** RECEIVING FAX : faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif ****") in new stack
-- Executing [receive@fax-rx:17] ReceiveFAX("DAHDI/i4/ZXXXXXXXX-aee", "/var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif") in new stack
-- Channel 'DAHDI/i4/ZXXXXXXXX-aee' receiving FAX '/var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif'
-- Channel 'DAHDI/i4/ZXXXXXXXX-aee' FAX session '80' started
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 000.000721 ], channel sent 3 frames (60 ms) of energy.
-- FAX handle 0: [ 000.000094 ], STAT_EVT_STRT_RX st: IDLE rt: IDLENSRX
-- FAX handle 0: [ 000.000191 ], STAT_EVT_RX_HW_RDY st: WT_RX_HW_RDY rt: RRDYNHRY
-- FAX handle 0: [ 000.000218 ], P30EVN_RECEIVE_STARTED
-- FAX handle 0: [ 000.000228 ], STAT_INFO_CSI
-- FAX handle 0: [ 000.000243 ], STAT_INFO_DIS
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 000.099025 ], stack sent 5 frames (100 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 000.159119 ], stack sent 3 frames (60 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.157218 ], stack sent 150 frames (3000 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.219149 ], stack sent 3 frames (60 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.258974 ], channel sent 163 frames (3260 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.299039 ], channel sent 2 frames (40 ms) of energy.
-- FAX handle 0: [ 005.238358 ], STAT_EVT_TX_V21_DONE st: WT_DIS_RSP rt: WDSRNT21
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 005.578391 ], stack sent 118 frames (2360 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 006.098987 ], channel sent 140 frames (2800 ms) of silence.
-- FAX handle 0: [ 008.018560 ], STAT_INFO_TSI
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 008.379050 ], channel sent 114 frames (2280 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 008.439062 ], channel sent 3 frames (60 ms) of silence.
-- FAX handle 0: [ 008.458464 ], STAT_INFO_DCS
-- FAX handle 0: [ 008.458488 ], STAT_EVT_DCS st: WT_DIS_RSP rt: WDSRNDCS
-- FAX handle 0: [ 008.458498 ], STAT_NEG_V29_9600
-- FAX handle 0: [ 008.458504 ], STAT_NEG_MMR
-- FAX handle 0: [ 008.458510 ], STAT_NEG_A4
-- FAX handle 0: [ 008.458515 ], STAT_NEG_RES_204x196
-- FAX handle 0: [ 008.458520 ], STAT_NEG_ECM
-- FAX handle 0: [ 008.458526 ], STAT_EVT_SW_ECM st: WT_DIS_RSP rt: WDSRNSWE
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 010.179083 ], channel sent 87 frames (1740 ms) of energy.
-- FAX handle 0: [ 011.671546 ], STAT_EVT_RX_TRN_END st: RCV_ECM_TRN rt: RTCFNERT
-- FAX handle 0: [ 011.671570 ], STAT_FRM_CFR
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 011.811183 ], stack sent 312 frames (6240 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 012.619153 ], channel sent 122 frames (2440 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 012.639131 ], channel sent 1 frames (20 ms) of energy.
-- FAX handle 0: [ 012.858565 ], STAT_EVT_TX_V21_DONE st: RCV_ECM_STRT rt: RECMNT21
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 013.198250 ], stack sent 69 frames (1380 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 013.799076 ], channel sent 58 frames (1160 ms) of silence.
-- FAX handle 0: [ 013.956743 ], STAT_EVT_RX_IMG_STRT st: RCV_ECM_STRT rt: RECMNSRI
-- FAX handle 0: [ 013.956764 ], P30EVN_PHASE_C
-- FAX handle 0: [ 013.956772 ], P30EVN_DOC_START
-- FAX handle 0: [ 013.957276 ], P30EVN_PAGE_START
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 023.099316 ], channel sent 465 frames (9300 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 023.139319 ], channel sent 2 frames (40 ms) of silence.
-- FAX handle 0: [ 023.199184 ], STAT_EVT_RX_IMG_END st: RCV_ECM rt: RECMNERI
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.559363 ], channel sent 71 frames (1420 ms) of energy.
-- FAX handle 0: [ 024.638788 ], STAT_INFO_PPS_EOP
-- FAX handle 0: [ 024.639046 ], STAT_EVT_PPS_EOP st: F_END_ECM rt: FEEMNP_P
-- FAX handle 0: [ 024.639060 ], STAT_EVT_ECM_ERR st: F_END_ECM rt: XXXXNEBE
-- FAX handle 0: [ 024.639070 ], STAT_INFO_PPR
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.799439 ], stack sent 580 frames (11600 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.839340 ], channel sent 14 frames (280 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.859362 ], channel sent 1 frames (20 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 026.439426 ], channel sent 79 frames (1580 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 026.459386 ], channel sent 1 frames (20 ms) of energy.
-- FAX handle 0: [ 026.854895 ], STAT_EVT_TX_V21_DONE st: RCV_ECM_STRT rt: RECMNT21
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.099402 ], channel sent 32 frames (640 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.119425 ], channel sent 1 frames (20 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.177559 ], stack sent 119 frames (2380 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.779409 ], channel sent 33 frames (660 ms) of silence.
-- FAX handle 0: [ 027.936005 ], STAT_EVT_RX_IMG_STRT st: RCV_ECM_STRT rt: RECMNSRI
-- FAX handle 0: [ 027.936034 ], P30EVN_PHASE_C
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 037.059604 ], channel sent 464 frames (9280 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 037.119547 ], channel sent 3 frames (60 ms) of silence.
-- FAX handle 0: [ 037.179611 ], STAT_EVT_RX_IMG_END st: RCV_ECM rt: RECMNERI
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 038.519551 ], channel sent 70 frames (1400 ms) of energy.
-- FAX handle 0: [ 038.599027 ], STAT_INFO_PPS_EOP
-- FAX handle 0: [ 038.599511 ], STAT_EVT_PPS_EOP st: F_END_ECM rt: FEEMNP_P
-- FAX handle 0: [ 038.599584 ], STAT_EVT_ECM_ERR st: F_END_ECM rt: XXXXNEBE
-- FAX handle 0: [ 038.599597 ], STAT_INFO_PPR
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 038.750821 ], stack sent 579 frames (11580 ms) of silence.
-- FAX handle 0: [ 040.815144 ], STAT_EVT_TX_V21_DONE st: RCV_ECM_STRT rt: RECMNT21
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 040.919605 ], channel sent 120 frames (2400 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 040.939586 ], channel sent 1 frames (20 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 041.137841 ], stack sent 119 frames (2380 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 041.739721 ], channel sent 40 frames (800 ms) of silence.
-- FAX handle 0: [ 041.895318 ], STAT_EVT_RX_IMG_STRT st: RCV_ECM_STRT rt: RECMNSRI
-- FAX handle 0: [ 041.895341 ], P30EVN_PHASE_C
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 051.019770 ], channel sent 464 frames (9280 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 051.079836 ], channel sent 3 frames (60 ms) of silence.
-- FAX handle 0: [ 051.139334 ], STAT_EVT_RX_IMG_END st: RCV_ECM rt: RECMNERI
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 052.479923 ], channel sent 70 frames (1400 ms) of energy.
-- FAX handle 0: [ 052.559306 ], STAT_INFO_PPS_EOP
-- FAX handle 0: [ 052.559327 ], STAT_EVT_PPS_EOP st: F_END_ECM rt: FEEMNP_P
-- FAX handle 0: [ 052.559337 ], STAT_EVT_ECM_ERR st: F_END_ECM rt: XXXXNEBE
-- FAX handle 0: [ 052.559347 ], STAT_INFO_PPR
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 052.720049 ], stack sent 579 frames (11580 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 054.099889 ], channel sent 81 frames (1620 ms) of silence.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 054.119933 ], channel sent 1 frames (20 ms) of energy.
-- FAX handle 0: [ 054.774505 ], STAT_EVT_TX_V21_DONE st: RCV_ECM_STRT rt: RECMNT21
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 055.097148 ], stack sent 119 frames (2380 ms) of energy.
> Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 055.700005 ], channel sent 79 frames (1580 ms) of silence.
-- FAX handle 0: [ 061.499399 ], STAT_EVT_T2_EXP st: RCV_ECM_STRT rt: RESTNT2X
-- FAX handle 0: [ 061.499427 ], STAT_EVT_GO_TO_B st: RCV_ECM_STRT rt: XXXXNGOB
-- FAX handle 0: [ 063.999938 ], STAT_EVT_HW_CLOSE st: WT_HW_CLS rt: WCLSNCLS
-- FAX handle 0: [ 064.000621 ], P30EVN_PAGE_END
-- FAX handle 0: [ 064.000641 ], P30EVN_DOC_END
-- FAX handle 0: [ 064.000648 ], STAT_SES_COMPLETE
-- FAX handle 0: [ 064.000664 ], P30EVN_COMPLETE
-- Channel 'DAHDI/i4/ZXXXXXXXX-aee' FAX session '80' is complete, result: 'FAILED' (FAX_FAILURE_PROTOCOL_ERROR), error: '3RD_T2_TIMEOUT', pages: 0, resolution: '204x196', transfer rate: '9600', remoteSID: '0048ZXXXXXXXX'
-- Executing [h@fax-rx:1] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(ecm) : yes") in new stack
-- Executing [h@fax-rx:2] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(filename) : /var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif") in new stack
-- Executing [h@fax-rx:3] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(headerinfo) : MY FAXBACK RX") in new stack
-- Executing [h@fax-rx:4] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(localstationid) : 713747390") in new stack
-- Executing [h@fax-rx:5] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(maxrate) : 9600") in new stack
-- Executing [h@fax-rx:6] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(minrate) : 2400") in new stack
-- Executing [h@fax-rx:7] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(pages) : 0") in new stack
-- Executing [h@fax-rx:8] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(rate) : 9600") in new stack
-- Executing [h@fax-rx:9] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(remotestationid) : 0048ZXXXXXXXX") in new stack
-- Executing [h@fax-rx:10] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(resolution) : 204x196") in new stack
-- Executing [h@fax-rx:11] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(status) : FAILED") in new stack
-- Executing [h@fax-rx:12] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(statusstr) : FAX_FAILURE_PROTOCOL_ERROR") in new stack
-- Executing [h@fax-rx:13] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(error) : 3RD_T2_TIMEOUT") in new stack
-- Executing [h@fax-rx:14] System("DAHDI/i4/ZXXXXXXXX-aee", "/var/lib/asterisk/agi-bin/fax2email.pl /var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif XXXXXXX ZXXXXXXXX") in new stack