Can not recieve fax


(Kirill Azarov) #1

Hi. I use FreePBX 12.0.50.1 and asterisk 11.16.0. I configured fax option in FreePBX. I try send fax from another faxmachine (not connected to my PBX) to asterisk. When I call to fax i hear fax signal and faxmachine tries to send fax, but without success. File in directory /var/spool/asterisk/fax/ not created.

Log from my asterisk

[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ivr-1:1] Macro("SIP/Cifra1-00001882", "blkvm-clr,") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("SIP/Cifra1-00001882", "SHARED(BLKVM,)=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("SIP/Cifra1-00001882", "GOSUB_RETVAL=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ivr-1:2] Set("SIP/Cifra1-00001882", "__NODEST=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ivr-1:3] Goto("SIP/Cifra1-00001882", "from-did-direct,200,1") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (from-did-direct,200,1)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:1] GotoIf("SIP/Cifra1-00001882", "0?ext-local,200,1") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:2] Macro("SIP/Cifra1-00001882", "user-callerid,") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/Cifra1-00001882", "TOUCH_MONITOR=1459242036.6558") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/Cifra1-00001882", "AMPUSER=4957703503") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/Cifra1-00001882", "0?report") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/Cifra1-00001882", "1?Set(REALCALLERIDNUM=4957703503)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/Cifra1-00001882", "AMPUSER=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/Cifra1-00001882", "0?limit") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/Cifra1-00001882", "AMPUSERCIDNAME=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/Cifra1-00001882", "1?report") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (macro-user-callerid,s,16)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/Cifra1-00001882", "0?continue") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("SIP/Cifra1-00001882", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:18] Set("SIP/Cifra1-00001882", "__TTL=64") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("SIP/Cifra1-00001882", "1?continue") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (macro-user-callerid,s,30)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/Cifra1-00001882", "CALLERID(number)=4957703503") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/Cifra1-00001882", "CALLERID(name)=4957703503") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/Cifra1-00001882", "CDR(cnum)=4957703503") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/Cifra1-00001882", "CDR(cnam)=4957703503") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/Cifra1-00001882", "CHANNEL(language)=en") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:3] Set("SIP/Cifra1-00001882", "DIAL_OPTIONS=TtrI") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:4] Set("SIP/Cifra1-00001882", "CONNECTEDLINE(num,i)=200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:5] Gosub("SIP/Cifra1-00001882", "sub-presencestate-display,s,1(200)") in new stack
[2016-03-29 12:00:41] WARNING[13540][C-00000c5d] func_presencestate.c: PRESENCE_STATE unknown
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-presencestate-display:1] Goto("SIP/Cifra1-00001882", "state-,1") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (sub-presencestate-display,state-,1)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [state-@sub-presencestate-display:1] Set("SIP/Cifra1-00001882", "PRESENCESTATE_DISPLAY=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [state-@sub-presencestate-display:2] Return("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:6] Set("SIP/Cifra1-00001882", "CONNECTEDLINE(name)=FAX") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:7] Set("SIP/Cifra1-00001882", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:8] Set("SIP/Cifra1-00001882", "__EXTTOCALL=200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:9] Set("SIP/Cifra1-00001882", "__PICKUPMARK=200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:10] Macro("SIP/Cifra1-00001882", "blkvm-setifempty,") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/Cifra1-00001882", "1?init") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (macro-blkvm-setifempty,s,4)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/Cifra1-00001882", "__BLKVM_CHANNEL=SIP/Cifra1-00001882") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/Cifra1-00001882", "SHARED(BLKVM,SIP/Cifra1-00001882)=TRUE") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/Cifra1-00001882", "GOSUB_RETVAL=TRUE") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:11] GotoIf("SIP/Cifra1-00001882", "1?skipov") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (from-did-direct,200,14)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:14] Set("SIP/Cifra1-00001882", "RRNODEST=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:15] Set("SIP/Cifra1-00001882", "__NODEST=200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:16] GosubIf("SIP/Cifra1-00001882", "0?sub-fmsetcid,s,1()") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:17] Set("SIP/Cifra1-00001882", "RecordMethod=Group") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:18] Gosub("SIP/Cifra1-00001882", "sub-record-check,s,1(exten,200,)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/Cifra1-00001882", "0?initialized") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/Cifra1-00001882", "__REC_STATUS=INITIALIZED") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/Cifra1-00001882", "NOW=1459242041") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/Cifra1-00001882", "__DAY=29") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/Cifra1-00001882", "__MONTH=03") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/Cifra1-00001882", "__YEAR=2016") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/Cifra1-00001882", "__TIMESTR=20160329-120041") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/Cifra1-00001882", "__FROMEXTEN=4957703503") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/Cifra1-00001882", "__MON_FMT=wav") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/Cifra1-00001882", "Recordings initialized") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/Cifra1-00001882", "0?Set(ARG3=dontcare)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/Cifra1-00001882", "REC_POLICY_MODE_SAVE=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/Cifra1-00001882", "0?Set(REC_STATUS=NO)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/Cifra1-00001882", "5?checkaction") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (sub-record-check,s,17)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/Cifra1-00001882", "1?sub-record-check,exten,1") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (sub-record-check,exten,1)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:1] NoOp("SIP/Cifra1-00001882", "Exten Recording Check between 4957703503 and 200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/Cifra1-00001882", "CALLTYPE=external") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:3] ExecIf("SIP/Cifra1-00001882", "0?Set(CALLTYPE=)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:4] Set("SIP/Cifra1-00001882", "CALLEE=dontcare") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:5] ExecIf("SIP/Cifra1-00001882", "0?Set(CALLEE=dontcare)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:6] GotoIf("SIP/Cifra1-00001882", "1?callee") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (sub-record-check,exten,11)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:11] Gosub("SIP/Cifra1-00001882", "recordcheck,1(dontcare,external,200)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/Cifra1-00001882", "Starting recording check against dontcare") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/Cifra1-00001882", "dontcare") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [exten@sub-record-check:12] Return("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:19] Set("SIP/Cifra1-00001882", "RingGroupMethod=ringallv2") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:20] Set("SIP/Cifra1-00001882", "_FMGRP=200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:21] GotoIf("SIP/Cifra1-00001882", "0?doconfirm") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:22] Macro("SIP/Cifra1-00001882", "dial,20,TtrI,200") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/Cifra1-00001882", "0?dial") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-dial:2] Set("SIP/Cifra1-00001882", "CHANNEL(musicclass)=default") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/Cifra1-00001882", "dialparties.agi") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: dialparties.agi: Caller ID name is '4957703503' number is '4957703503'
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: dialparties.agi: Methodology of ring is 'ringallv2'
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- dialparties.agi: Added extension 200 to extension map
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- dialparties.agi: Extension 200 cf is disabled
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- dialparties.agi: Extension 200 do not disturb is disabled
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- dialparties.agi: dbset CALLTRACE/200 to 4957703503
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- dialparties.agi: Filtered ARG3: 200
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: dialparties.agi: Setting default NOANSWER DIALSTATUS since no extensions available
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_agi.c: -- <SIP/Cifra1-00001882>AGI Script dialparties.agi completed, returning 0
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-dial:4] NoOp("SIP/Cifra1-00001882", "Returned from dialparties with no extensions to call and DIALSTATUS: NOANSWER") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:23] Goto("SIP/Cifra1-00001882", "nextstep") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (from-did-direct,200,25)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:25] Set("SIP/Cifra1-00001882", "RingGroupMethod=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:26] GotoIf("SIP/Cifra1-00001882", "0?nodest") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:27] Set("SIP/Cifra1-00001882", "__NODEST=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:28] Set("SIP/Cifra1-00001882", "__PICKUPMARK=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:29] Macro("SIP/Cifra1-00001882", "blkvm-clr,") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("SIP/Cifra1-00001882", "SHARED(BLKVM,SIP/Cifra1-00001882)=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("SIP/Cifra1-00001882", "GOSUB_RETVAL=") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:30] Set("SIP/Cifra1-00001882", "DIALSTATUS=NOANSWER") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@from-did-direct:31] Goto("SIP/Cifra1-00001882", "ext-fax,200,1") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (ext-fax,200,1)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ext-fax:1] Set("SIP/Cifra1-00001882", "FAX_FOR=FAX (200)") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ext-fax:2] NoOp("SIP/Cifra1-00001882", "Receiving Fax for: FAX (200), From: "4957703503" <4957703503>") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ext-fax:3] Set("SIP/Cifra1-00001882", "FAX_ATTACH_FORMAT=pdf") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ext-fax:4] Set("SIP/Cifra1-00001882", "FAX_RX_EMAIL=admin@salt-group.ru") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [200@ext-fax:5] Goto("SIP/Cifra1-00001882", "s,receivefax") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (ext-fax,s,3)
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@ext-fax:3] StopPlayTones("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@ext-fax:4] ReceiveFAX("SIP/Cifra1-00001882", "/var/spool/asterisk/fax/1459242036.6558.tif,f") in new stack
[2016-03-29 12:00:41] VERBOSE[13540][C-00000c5d] res_fax.c: -- Channel 'SIP/Cifra1-00001882' receiving FAX '/var/spool/asterisk/fax/1459242036.6558.tif'
[2016-03-29 12:00:42] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[2016-03-29 12:00:42] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[2016-03-29 12:00:43] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 1
[2016-03-29 12:00:43] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 1
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_A_CED, state 1
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Starting answer mode
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_A_CED to T30_PHASE_B_TX
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 0
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 4
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Start T2
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from state 1 to 17
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Sending ident 'unknown'
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: CSI without final frame tag
[2016-03-29 12:00:44] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: ff 03 40 6e 77 6f 6e 6b 6e 75 20 20 20 20 20 20 20 20 20 20 20 20 20
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 DIS:
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Store and forward Internet fax (T.37): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= 3G mobile network: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= V.8 capabilities: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Preferred octets: 256 octets
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..1.= Can receive fax: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= 2-D coding: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..00= Recording width: 215mm +- 1%
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 10..= Recording length: Unlimited
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .1..= Error correction mode (ECM): ECM
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .1.. ....= T.6 coding: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= "Field not valid" supported: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Multiple selective polling: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Polled sub-address: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= T.43 coding: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Plane interleave: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...1= R8x15.4lines/mm: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= Inch-based resolution preferred: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...1 ....= Metric-based resolution preferred: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Selective polling: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Sub-addressing: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Password: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= Character mode: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Digital network capability: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Duplex capability: Half only
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= JPEG coding: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Full colour mode: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= 12bits/pel component: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= No subsampling (1:1:1): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Custom illuminant: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Custom gamut range: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 0... ....= Extension indicator: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: DIS with final frame tag
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 4
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 0
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Start T4
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Retry number 1
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 0
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 4
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Sending ident 'unknown'
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: CSI without final frame tag
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: ff 03 40 6e 77 6f 6e 6b 6e 75 20 20 20 20 20 20 20 20 20 20 20 20 20
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 DIS:
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Store and forward Internet fax (T.37): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= 3G mobile network: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= V.8 capabilities: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Preferred octets: 256 octets
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..1.= Can receive fax: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= 2-D coding: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..00= Recording width: 215mm +- 1%
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 10..= Recording length: Unlimited
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed

(Kirill Azarov) #2
Not the whole log will fit in the first post

[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .1..= Error correction mode (ECM): ECM
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .1.. ....= T.6 coding: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= "Field not valid" supported: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Multiple selective polling: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Polled sub-address: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= T.43 coding: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Plane interleave: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...1= R8x15.4lines/mm: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= Inch-based resolution preferred: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...1 ....= Metric-based resolution preferred: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Selective polling: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Sub-addressing: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Password: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= Character mode: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Digital network capability: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Duplex capability: Half only
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= JPEG coding: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Full colour mode: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= 12bits/pel component: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= No subsampling (1:1:1): Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Custom illuminant: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Custom gamut range: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 0... ....= Extension indicator: Not set
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: DIS with final frame tag
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 4
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 0
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Start T4
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[2016-03-29 12:00:46] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Retry number 1
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 0
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 4
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Sending ident 'unknown'
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: CSI without final frame tag
[2016-03-29 12:00:49] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: ff 03 40 6e 77 6f 6e 6b 6e 75 20 20 20 20 20 20 20 20 20 20 20 20 20
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 DIS:
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Store and forward Internet fax (T.37): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= 3G mobile network: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= V.8 capabilities: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Preferred octets: 256 octets
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..1.= Can receive fax: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= 2-D coding: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..00= Recording width: 215mm +- 1%
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 10..= Recording length: Unlimited
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .1..= Error correction mode (ECM): ECM
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .1.. ....= T.6 coding: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= "Field not valid" supported: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Multiple selective polling: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Polled sub-address: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= T.43 coding: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Plane interleave: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...1= R8x15.4lines/mm: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= Inch-based resolution preferred: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...1 ....= Metric-based resolution preferred: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Selective polling: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Sub-addressing: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Password: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= Character mode: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Digital network capability: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Duplex capability: Half only
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 0...= JPEG coding: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...0 ....= Full colour mode: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= 12bits/pel component: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 1... ....= Extension indicator: Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ...0= No subsampling (1:1:1): Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... ..0.= Custom illuminant: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... .0..= Custom gamut range: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 0... ....= Extension indicator: Not set
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: DIS with final frame tag
[2016-03-29 12:00:51] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Tx: ff 13 80 00 ee f8 c4 80 91 80 80 80 18
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 4
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 0
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Start T4
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier down (-1) in state 17
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Carrier up (-2) in state 17
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 HDLC signal status is Framing OK (-6) in state 17
[2016-03-29 12:00:52] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Start T4A
[2016-03-29 12:01:23] NOTICE[1772] chan_sip.c: Disconnecting call 'SIP/Cifra1-00001882' for lack of RTP activity in 31 seconds
[2016-03-29 12:01:23] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from state 17 to 32
[2016-03-29 12:01:23] FAX[13540][C-00000c5d] res_fax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_CALL_FINISHED
[2016-03-29 12:01:23] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set rx type 9
[2016-03-29 12:01:23] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX FAX exchange complete
[2016-03-29 12:01:23] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX Set tx type 9
[2016-03-29 12:01:23] FAX[13540][C-00000c5d] res_fax.c: FLOW FAX FAX exchange complete
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: == Spawn extension (ext-fax, s, 4) exited non-zero on 'SIP/Cifra1-00001882'
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [h@ext-fax:1] GotoIf("SIP/Cifra1-00001882", "1?failed") in new stack
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (ext-fax,h,103)
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [h@ext-fax:103] NoOp("SIP/Cifra1-00001882", "FAX FAILED for: admin@salt-group.ru , From: "4957703503" <4957703503>") in new stack
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [h@ext-fax:104] Macro("SIP/Cifra1-00001882", "hangupcall,") in new stack
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-hangupcall:1] ExecIf("SIP/Cifra1-00001882", "0?Set(CDR(recordingfile)=.wav)") in new stack
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-hangupcall:2] GotoIf("SIP/Cifra1-00001882", "1?theend") in new stack
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Goto (macro-hangupcall,s,4)
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/Cifra1-00001882", "") in new stack
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/Cifra1-00001882' in macro 'hangupcall'
[2016-03-29 12:01:24] VERBOSE[13540][C-00000c5d] pbx.c: == Spawn extension (ext-fax, h, 104) exited non-zero on 'SIP/Cifra1-00001882'

nuinu

Сообщений: 1
Зарегистрирован: 29 мар 2016, 13:12

(Tom Ray) #3

@nuinu This is the issue right here:

[2016-03-29 12:01:23] NOTICE[1772] chan_sip.c: Disconnecting call ‘SIP/Cifra1-00001882’ for lack of RTP activity in 31 seconds

RTP is not flowing on the call. I would double check your RTP ports in any firewall/router to make sure they are open correctly and I would check with your carrier to see what is happening with the RTP stream.