Install Problem

Post queries and issues that you found during ictfax installation

Install Problem

Postby serqet » Wed Aug 23, 2017 7:39 pm

hi, before sorry my bad english.

I installed the version of ictfax 3.7.4 without any problems. But when trying to send a fax it gave the following error;
"ICT\Core\CoreException: Document not found in ICT\Core\Message\Document->load()"


I downloaded the latest version of ictfax later on, but at this time I get the underlying error during installation.
"ictfax An AJAX HTTP error occurred. HTTP Result Code: 500 Debugging"

Is there anyone I can really install and run this system I'm wondering?
If you need to pay for it, why is there an install guide?

Somebody please help me.
Thank you.
serqet
 
Posts: 2
Joined: Mon Sep 14, 2015 10:59 pm

Re: Install Problem

Postby falak » Thu Aug 24, 2017 8:26 am

our team working on your reported problem, We will release latest version of ICTFax very soon.

But now please download ICTFax 3.7.4 from "https://github.com/ictinnovations/ictfax/releases" and install on your server but don`t install ICTPBX module from this release. Please download ICTPBX module form latest version of ICTFax "https://github.com/ictinnovations/ictfax" and install it on your system, after that follow the installation guide.

Best Regards
falak
Site Admin
 
Posts: 168
Joined: Tue Jan 17, 2012 7:33 am

Re: Install Problem

Postby serqet » Sun Aug 27, 2017 5:45 pm

Thank you for reply...

I did the installation as you said. I can send and receive faxes, but I can only get incoming faxes by entering it in the server's directory, the incoming fax appears in the drupal interface but there is no button to view or download it just delete it does not delete the room file, what is the solution?

I did not get mail from one of the incoming faxes, which file do we need to enter our smtp settings?
serqet
 
Posts: 2
Joined: Mon Sep 14, 2015 10:59 pm

Re: Install Problem

Postby falak » Wed Sep 06, 2017 8:05 am

for incoming fax, Please check your inbox for configured email
falak
Site Admin
 
Posts: 168
Joined: Tue Jan 17, 2012 7:33 am

Re: Install Problem

Postby damiren » Tue Oct 03, 2017 2:51 pm

Hello,
I am using the last version of ictfax -3.7.4.

I also cannot see any ways to view sent/received faxes from the web interface. This feature was disabled? Is there a way to implement it back?

Also i get no email with the fax as attachment, but i get this error during sending/receiving process:

2017-10-03 16:32:58.829370 [INFO] switch_cpp.cpp:1358 200
2017-10-03 16:32:58.829370 [INFO] switch_cpp.cpp:1358 Array
2017-10-03 16:32:58.829370 [ERR] mod_lua.cpp:203 /usr/ictcore/bin/freeswitch/lib/JSON.lua:676: /usr/ictcore/bin/freeswitch/lib/JSON.lua:383: can't parse JSON at char 1 of: Array
stack traceback:
[C]: in function 'assert'
/usr/ictcore/bin/freeswitch/lib/JSON.lua:676: in function </usr/ictcore/bin/freeswitch/lib/JSON.lua:638>
(...tail calls...)
/usr/ictcore/bin/freeswitch/application.lua:224: in main chunk

Can you please tell me if this is the cause, and if yes, how i can solve it?

here are my ictcore.log:
Code: Select all
[2017-10-03 14:32:23] [1466] [FLOW] New request received to process status of application : inbound
[2017-10-03 14:32:23] [1466] [FLOW] No transmission found, searching for dialplan
[2017-10-03 14:32:23] [1466] [INFO] looking for available dialplan
[2017-10-03 14:32:23] [1466] [FLOW] Trying with dialplan id : 3
[2017-10-03 14:32:23] [1466] [FLOW] Successfully authenticated for program : faxtoemail
[2017-10-03 14:32:23] [1466] [FLOW] Processing with program : faxtoemail(7)
[2017-10-03 14:32:23] [1466] [FLOW] Processing application : inbound(17)
[2017-10-03 14:32:23] [1466] [FLOW] Application processing completed with result: success
[2017-10-03 14:32:23] [1466] [FLOW] Executing application : connect(18)
[2017-10-03 14:32:24] [1466] [FLOW] Final spool status : connected
[2017-10-03 14:32:24] [1466] [FLOW] Final transmission status : processing
[2017-10-03 14:32:24] [1466] [FLOW] Request processing completed
[2017-10-03 14:32:24] [1467] [FLOW] New request received to process status of application : 18
[2017-10-03 14:32:24] [1467] [FLOW] Existing transmission requested with id : 17
[2017-10-03 14:32:24] [1467] [FLOW] Processing with program : faxtoemail(7)
[2017-10-03 14:32:24] [1467] [FLOW] Processing application : connect(18)
[2017-10-03 14:32:24] [1467] [FLOW] Application processing completed with result: success
[2017-10-03 14:32:24] [1467] [FLOW] Executing application : fax_receive(19)
[2017-10-03 14:32:24] [1467] [FLOW] Final spool status : connected
[2017-10-03 14:32:24] [1467] [FLOW] Final transmission status : processing
[2017-10-03 14:32:24] [1467] [FLOW] Request processing completed
[2017-10-03 14:32:58] [1469] [FLOW] New request received to process status of application : 19
[2017-10-03 14:32:58] [1469] [FLOW] Existing transmission requested with id : 17
[2017-10-03 14:32:58] [1469] [FLOW] Processing with program : faxtoemail(7)
[2017-10-03 14:32:58] [1469] [FLOW] Processing application : fax_receive(19)
[2017-10-03 14:32:58] [1469] [FLOW] Application processing completed with result: success
[2017-10-03 14:32:58] [1469] [FLOW] Final spool status : completed
[2017-10-03 14:32:58] [1469] [FLOW] Final transmission status : completed
[2017-10-03 14:32:58] [1469] [FLOW] Request processing completed
[2017-10-03 14:33:01] [29265] [FLOW] New thread started for: ICT\Core\Thread\Task
[2017-10-03 14:33:01] [29265] [WARNING] task has been expired:5
[2017-10-03 14:34:01] [29334] [FLOW] New thread started for: ICT\Core\Thread\Task
[2017-10-03 14:34:01] [29334] [FLOW] processing task:5
[2017-10-03 14:34:01] [29334] [FLOW] Executing transmission with id : 18
[2017-10-03 14:34:01] [29334] [FLOW] Attempting with spool_id : 18
[2017-10-03 14:34:01] [29334] [FLOW] Executing program : sendemail(17)
[2017-10-03 14:34:01] [29334] [FLOW] Executing application : email_send(46)
[2017-10-03 14:34:01] [29334] [ERROR] No provider available
[2017-10-03 14:34:01] [29334] [NOTICE] No provider available
[2017-10-03 14:34:01] [29334] [NOTICE] Using localhost as email gateway
[2017-10-03 14:34:01] [29334] [ERROR] Address in mailbox given [] does not comply with RFC 2822, 3.6.2.
[2017-10-03 14:34:01] [29334] [ERROR] error while preparing email message
[2017-10-03 14:34:01] [29334] [ERROR] error while preparing email message
[2017-10-03 14:34:01] [29334] [ERROR] Unable to process transmission task


Thank you in advance
damiren
 
Posts: 4
Joined: Fri Apr 01, 2016 8:11 am

Re: Install Problem

Postby kashif » Thu Oct 12, 2017 7:46 am

Please upload full log of freeswitch.
kashif
 
Posts: 22
Joined: Tue Mar 24, 2015 5:17 am

Re: Install Problem

Postby damiren » Tue Oct 17, 2017 2:24 pm

hello,

bellow you have a part of the freeswitch log
Code: Select all
FreeSWITCH Version 1.4.26~64bit ( 64bit)

FreeSWITCH Started
Max Sessions [1000]
Session Rate [30]
SQL [Enabled]
2017-10-03 14:24:29.007169 [DEBUG] switch_loadable_module.c:702 Chat Thread Started
2017-10-03 14:24:29.007182 [DEBUG] switch_loadable_module.c:702 Chat Thread Started
2017-10-03 14:24:29.007137 [INFO] switch_time.c:607 Clock synchronized to system time.
2017-10-03 14:24:35.747166 [DEBUG] switch_ivr_originate.c:2124 Parsing global variables
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [fax_enable_t38_request]=[true]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [fax_enable_t38]=[true]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [fax_verbose]=[true]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [fax_use_ecm]=[true]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [failure_causes]=[NORMAL_CLEARING]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [transfer_on_fail]=[UNALLOCATED_NUMBER auto_cause xml ictcore_fail]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [api_hangup_hook]=[lua /usr/ictcore/bin/freeswitch/spool_failed.lua 1 1 error]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [session_in_hangup_hook]=[true]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [ignore_early_media]=[true]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [codec_string]=[PCMU]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [spool_status]=[connected]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [spool_id]=[1]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [origination_caller_id_number]=[hiden_number]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [origination_caller_id_name]=[hiden_number]
2017-10-03 14:24:35.769172 [DEBUG] switch_event.c:1698 Parsing variable [application_result]=[success]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.769172 [NOTICE] switch_channel.c:1077 New Channel sofia/ictcore/hiden_number [d1622c92-a835-11e7-9ce5-99473e034b79]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.769172 [DEBUG] mod_sofia.c:4700 (sofia/ictcore/hiden_number) State Change CS_NEW -> CS_INIT
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.769172 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.769172 [DEBUG] switch_core_state_machine.c:472 (sofia/ictcore/hiden_number) Running State Change CS_INIT
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.769172 [DEBUG] switch_core_state_machine.c:512 (sofia/ictcore/hiden_number) State INIT
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.769172 [DEBUG] mod_sofia.c:87 sofia/ictcore/hiden_number SOFIA INIT
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] sofia_glue.c:1241 sofia/ictcore/hiden_number sending invite version: 1.4.26  64bit
d1622c92-a835-11e7-9ce5-99473e034b79 Local SDP:
d1622c92-a835-11e7-9ce5-99473e034b79 v=0
d1622c92-a835-11e7-9ce5-99473e034b79 o=FreeSWITCH 1507006767 1507006768 IN IP4 10.184.1.66
d1622c92-a835-11e7-9ce5-99473e034b79 s=FreeSWITCH
d1622c92-a835-11e7-9ce5-99473e034b79 c=IN IP4 10.184.1.66
d1622c92-a835-11e7-9ce5-99473e034b79 t=0 0
d1622c92-a835-11e7-9ce5-99473e034b79 m=audio 26708 RTP/AVP 0 101 13
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:0 PCMU/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:101 telephone-event/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=fmtp:101 0-16
d1622c92-a835-11e7-9ce5-99473e034b79 a=ptime:20
d1622c92-a835-11e7-9ce5-99473e034b79 a=sendrecv
d1622c92-a835-11e7-9ce5-99473e034b79
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:40 sofia/ictcore/hiden_number Standard INIT
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:48 (sofia/ictcore/hiden_number) State Change CS_INIT -> CS_ROUTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:512 (sofia/ictcore/hiden_number) State INIT going to sleep
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:472 (sofia/ictcore/hiden_number) Running State Change CS_ROUTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:528 (sofia/ictcore/hiden_number) State ROUTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] mod_sofia.c:123 sofia/ictcore/hiden_number SOFIA ROUTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_ivr_originate.c:67 (sofia/ictcore/hiden_number) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:528 (sofia/ictcore/hiden_number) State ROUTING going to sleep
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:472 (sofia/ictcore/hiden_number) Running State Change CS_CONSUME_MEDIA
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:547 (sofia/ictcore/hiden_number) State CONSUME_MEDIA
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_state_machine.c:547 (sofia/ictcore/hiden_number) State CONSUME_MEDIA going to sleep
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.807159 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [calling][0]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.847165 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.847165 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:35.847165 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [calling][0]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [proceeding][183]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] sofia.c:6668 Remote SDP:
d1622c92-a835-11e7-9ce5-99473e034b79 v=0
d1622c92-a835-11e7-9ce5-99473e034b79 o=root 1852056200 1852056200 IN IP4 10.55.65.8
d1622c92-a835-11e7-9ce5-99473e034b79 s=Asterisk PBX 13.1-cert2
d1622c92-a835-11e7-9ce5-99473e034b79 c=IN IP4 10.55.65.8
d1622c92-a835-11e7-9ce5-99473e034b79 t=0 0
d1622c92-a835-11e7-9ce5-99473e034b79 m=audio 12992 RTP/AVP 0 8 101
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:0 PCMU/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:8 PCMA/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:101 telephone-event/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=fmtp:101 0-16
d1622c92-a835-11e7-9ce5-99473e034b79 a=maxptime:150
d1622c92-a835-11e7-9ce5-99473e034b79
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:2506 Set Codec sofia/ictcore/hiden_number PCMU/8000 20 ms 160 samples 64000 bits 1 channels
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_codec.c:111 sofia/ictcore/hiden_number Original read codec set to PCMU:0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:3933 Set 2833 dtmf send payload to 101
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/ictcore/hiden_number] 10.184.1.66 port 26708 -> 10.55.65.8 port 12992 codec: 0 ms: 20
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.327159 [DEBUG] switch_rtp.c:3608 Starting timer [soft] 160 bytes per 20ms
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.347161 [DEBUG] switch_core_media.c:5476 Set 2833 dtmf send payload to 101
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.347161 [DEBUG] switch_core_media.c:5482 Set 2833 dtmf receive payload to 101
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.347161 [DEBUG] switch_core_media.c:5504 sofia/ictcore/hiden_number Set rtp dtmf delay to 40
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.347161 [NOTICE] sofia_media.c:92 Pre-Answer sofia/ictcore/hiden_number!
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:37.347161 [DEBUG] switch_channel.c:3421 (sofia/ictcore/hiden_number) Callstate Change DOWN -> EARLY
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [completing][200]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] sofia.c:6665 Duplicate SDP
d1622c92-a835-11e7-9ce5-99473e034b79 v=0
d1622c92-a835-11e7-9ce5-99473e034b79 o=root 1852056200 1852056200 IN IP4 10.55.65.8
d1622c92-a835-11e7-9ce5-99473e034b79 s=Asterisk PBX 13.1-cert2
d1622c92-a835-11e7-9ce5-99473e034b79 c=IN IP4 10.55.65.8
d1622c92-a835-11e7-9ce5-99473e034b79 t=0 0
d1622c92-a835-11e7-9ce5-99473e034b79 m=audio 12992 RTP/AVP 0 8 101
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:0 PCMU/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:8 PCMA/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=rtpmap:101 telephone-event/8000
d1622c92-a835-11e7-9ce5-99473e034b79 a=fmtp:101 0-16
d1622c92-a835-11e7-9ce5-99473e034b79 a=maxptime:150
d1622c92-a835-11e7-9ce5-99473e034b79
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [ready][200]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [NOTICE] sofia.c:7466 Channel [sofia/ictcore/hiden_number] has been answered
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.567172 [DEBUG] switch_channel.c:3713 (sofia/ictcore/hiden_number) Callstate Change EARLY -> ACTIVE
2017-10-03 14:24:39.587257 [DEBUG] switch_ivr_originate.c:3604 Originate Resulted in Success: [sofia/ictcore/hiden_number]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [INFO] switch_channel.c:3084 sofia/ictcore/hiden_number Flipping CID from "hiden_number" <hiden_number> to "Outbound Call" <hiden_number>
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [DEBUG] mod_commands.c:4472 (sofia/ictcore/hiden_number) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [DEBUG] switch_core_state_machine.c:472 (sofia/ictcore/hiden_number) Running State Change CS_EXECUTE
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [DEBUG] switch_core_state_machine.c:535 (sofia/ictcore/hiden_number) State EXECUTE
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [DEBUG] mod_sofia.c:178 sofia/ictcore/hiden_number SOFIA EXECUTE
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.587257 [DEBUG] switch_core_state_machine.c:258 sofia/ictcore/hiden_number Standard EXECUTE
d1622c92-a835-11e7-9ce5-99473e034b79 EXECUTE sofia/ictcore/hiden_number lua(/usr/ictcore/bin/freeswitch/application.lua 1 1 success)
d1622c92-a835-11e7-9ce5-99473e034b79 EXECUTE sofia/ictcore/hiden_number curl(http://localhost/ictcore/gateway.php post username=myuser&application_id=1&application_data=%7B%22result%22%3A%22success%22%7D&spool_id=1&gateway_flag=8&password=plsChangeMe)
2017-10-03 14:24:39.587257 [DEBUG] mod_curl.c:182 method: post, url: http://localhost/ictcore/gateway.php, content-type: (null)
2017-10-03 14:24:39.587257 [DEBUG] mod_curl.c:211 Post data: username=myuser&application_id=1&application_data={"result":"success"}&spool_id=1&gateway_flag=8&password=plsChangeMe
2017-10-03 14:24:39.667574 [INFO] switch_cpp.cpp:1358 2002017-10-03 14:24:39.667574 [INFO] switch_cpp.cpp:1358 {
  "application_id": "2",
  "input": {
    "fax_enable_t38_request": "true",
    "fax_enable_t38": "true",
    "fax_local_station_id": "ICTCore",
    "fax_header": "install1.png",
    "fax_ident": "hiden_number",
    "application_result": "failed",
    "execute_on_fax_success": "set application_result=success"
  },
  "output": {
    "error": "fax_result_text",
    "pages": "fax_document_transferred_pages",
    "result": "application_result"
  },
  "batch": [
    {
      "name": "playback",
      "data": "silence_stream://2000"
    },
    {
      "name": "txfax",
      "data": "/usr/ictcore/data/document/document_0_1.tif"
    }
  ]
}d1622c92-a835-11e7-9ce5-99473e034b79 EXECUTE sofia/ictcore/hiden_number playback(silence_stream://2000)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:39.667574 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:41.627170 [DEBUG] switch_ivr_play_say.c:1749 done playing file silence_stream://2000
d1622c92-a835-11e7-9ce5-99473e034b79 EXECUTE sofia/ictcore/hiden_number txfax(/usr/ictcore/data/document/document_0_1.tif)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:41.627170 [DEBUG] mod_spandsp_fax.c:1439 Raw read codec activation Success L16 20000
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:41.627170 [DEBUG] switch_core_codec.c:221 sofia/ictcore/hiden_number Push codec L16:100
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:41.627170 [DEBUG] mod_spandsp_fax.c:1456 Raw write codec activation Success L16
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:41.847162 [DEBUG] switch_rtp.c:5928 Correct ip/port confirmed.
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.867165 [DEBUG] switch_core_media.c:7412 sofia/ictcore/hiden_number image media sdp:
d1622c92-a835-11e7-9ce5-99473e034b79 v=0
d1622c92-a835-11e7-9ce5-99473e034b79 o=FreeSWITCH 1507006767 1507006769 IN IP4 10.184.1.66
d1622c92-a835-11e7-9ce5-99473e034b79 s=FreeSWITCH
d1622c92-a835-11e7-9ce5-99473e034b79 c=IN IP4 10.184.1.66
d1622c92-a835-11e7-9ce5-99473e034b79 t=0 0
d1622c92-a835-11e7-9ce5-99473e034b79 m=image 26708 udptl t38
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxVersion:0
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38MaxBitRate:14400
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxFillBitRemoval
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxRateManagement:transferredTCF
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxMaxBuffer:2000
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxMaxDatagram:400
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxUdpEC:t38UDPRedundancy
d1622c92-a835-11e7-9ce5-99473e034b79
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.867165 [DEBUG] sofia_glue.c:1241 sofia/ictcore/hiden_number sending invite version: 1.4.26  64bit
d1622c92-a835-11e7-9ce5-99473e034b79 Local SDP:
d1622c92-a835-11e7-9ce5-99473e034b79 v=0
d1622c92-a835-11e7-9ce5-99473e034b79 o=FreeSWITCH 1507006767 1507006769 IN IP4 10.184.1.66
d1622c92-a835-11e7-9ce5-99473e034b79 s=FreeSWITCH
d1622c92-a835-11e7-9ce5-99473e034b79 c=IN IP4 10.184.1.66
d1622c92-a835-11e7-9ce5-99473e034b79 t=0 0
d1622c92-a835-11e7-9ce5-99473e034b79 m=image 26708 udptl t38
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxVersion:0
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38MaxBitRate:14400
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxFillBitRemoval
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxRateManagement:transferredTCF
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxMaxBuffer:2000
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxMaxDatagram:400
d1622c92-a835-11e7-9ce5-99473e034b79 a=T38FaxUdpEC:t38UDPRedundancy
d1622c92-a835-11e7-9ce5-99473e034b79
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.867165 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.887198 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [calling][0]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.947160 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.947160 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.947160 [DEBUG] switch_core_session.c:1062 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] sofia.c:5900 sofia/ictcore/hiden_number T38 invite failed
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] sofia.c:6658 Channel sofia/ictcore/hiden_number entering state [ready][488]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:43.967170 [DEBUG] switch_core_media.c:3933 Set 2833 dtmf send payload to 101
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:44.907177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.087178 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state T
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.087178 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase A_CNG to B_RX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.087178 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.087178 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.267164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state T
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:45.807163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state T
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:46.787169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:46.787169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  NSF without final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:46.787169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 03 20 00 00 00 5a 00 00 00
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:46.787169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 The remote was made in Japan
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:46.787169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 The remote was made by Unknown - indeterminate
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.527168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.527168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  CSI without final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.527168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 03 40 34 31 33 34 34 32 32 32 30 20 20 20 20 20 20 20 20 20 20 20
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.527168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Remote gave CSI as: "hiden_number"
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop none (0 remaining)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  DIS with final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 80 00 6e a8 80 80 10
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state T
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS:
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= 3G mobile network: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= V.8 capabilities: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Preferred octets: 256 octets
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= Can receive fax: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= 2-D coding: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..00= Recording width: 215mm +- 1%
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 10..= Recording length: Unlimited
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .010 ....= Receiver's minimum scan line time: 10ms at 3.85 l/mm; T7.7 = T3.85
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= T.6 coding: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= "Field not valid" supported: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= Multiple selective polling: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Polled sub-address: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= T.43 coding: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...0 ....= Plane interleave: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   1... ....= Extension indicator: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= R8x15.4lines/mm: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= Inch-based resolution preferred: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ...1 ....= Metric-based resolution preferred: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= Selective polling: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= Extension indicator: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:317 === Negotiation Result =======================================================
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:318 Remote station id: hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:319 Local station id:  hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:320 Transfer Rate:     14400
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:322 ECM status         off
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:323 remote country:   Japan
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:324 remote vendor:    Unknown - indeterminate
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:325 remote model:
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:327 ==============================================================================
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Trying to send file /usr/ictcore/data/document/document_0_1.tif
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:47.907176 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start sending document
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Choose image type bi-level (0), compression T.4 1-D (2)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Minimum bits per row will be 144
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting page 1 of transfer
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Image width is A4 at 8040dpm x 3850dpm
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Put document with modem (7) V.17 at 14400bps
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state T to D
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  TSI without final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.127205 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 03 43 39 34 37 38 30 31 39 36 33 30 20 20 20 20 20 20 20 20 20 20
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.147179 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Abort (-8) in state D
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.167170 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state D
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.167170 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.167170 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:48.167170 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state D
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DCS:
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 0...= 3G mobile network: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..1.= Receive fax: Set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   ..10 00..= Selected data signalling rate: V.17 14400bps
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .0.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= 2-D coding: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... ..00= Recording width: 215mm +- 1%
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .... 10..= Recording length: Unlimited
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   .010 ....= Minimum scan line time: 10ms
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30   0... ....= Extension indicator: Not set
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  DCS with final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:49.747180 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 83 00 22 28
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.027163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state D
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.107163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state D
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.107163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state D to D_TCF
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.107163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to C_NON_ECM_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.107163 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.107163 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:50.107163 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 7
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:53.127169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase C_NON_ECM_TX, state D_TCF
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:53.127169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase C_NON_ECM_TX to B_RX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:53.127169 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:53.127169 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:53.127169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:53.127169 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state D_TCF to D_POST_TCF
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:55.207162 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state D_POST_TCF
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:55.427162 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state D_POST_TCF
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:55.427162 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (15840 remaining)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  CFR with final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 84
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state D_POST_TCF
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Trainability test succeeded
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state D_POST_TCF to I
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.447177 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase C_NON_ECM_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.507178 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state I
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.507178 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to C_NON_ECM_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.507178 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.507178 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:56.507178 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 7
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase C_NON_ECM_TX, state I
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase C_NON_ECM_TX to D_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state I to II_Q
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No more pages to send
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  EOP with final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:24:59.927164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 2f
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:00.987163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state II_Q
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:01.067168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state II_Q
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:01.067168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to D_RX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:01.067168 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:01.067168 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:01.067168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:03.187164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state II_Q
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:03.387168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state II_Q
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:03.387168 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (16160 remaining)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  MCF with final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx:  ff 13 8c
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state II_Q
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:428 ==== Page Sent ===============================================================
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:429 Page no = 1
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:430 Image type = bi-level (bi-level in the file)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:431 Image size = 1728 x 271 pixels (1728 x 255 pixels in the file)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:432 Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:433 Compression = T.4 1-D (2)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:434 Compressed image size = 5684 bytes
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:435 Bad rows = 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:436 Longest bad row run = 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:437 ==============================================================================
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase D_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state II_Q to C
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  DCN with final frame tag
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx:  ff 13 fb
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.367167 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Success - delivered 1 pages
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.427162 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state C
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.427162 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_RX to D_TX
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.427162 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:04.427162 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 4
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.507164 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.587163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.587163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting final pause before disconnecting
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.587163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to E
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.587163 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 0
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.587163 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 1
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:05.587163 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state C to B
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase E, state B
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:496 ==============================================================================
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:500 Fax successfully sent.
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:513 Remote station id: hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:514 Local station id:  hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 1
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:517 Total fax pages:   1
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:518 Image resolution:  8040x3850
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate:     14400
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:521 ECM status         off
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:522 remote country:   Japan
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:523 remote vendor:    Unknown - indeterminate
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:524 remote model:
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:526 ==============================================================================
d1622c92-a835-11e7-9ce5-99473e034b79 EXECUTE sofia/ictcore/hiden_number set(application_result=success)
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_dptools.c:1477 sofia/ictcore/hiden_number SET [application_result]=[success]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state B to CALL_FINISHED
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase E to CALL_FINISHED
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set rx type 9
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX FAX exchange complete
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX Set tx type 9
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW FAX FAX exchange complete
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Call completed
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.607773 [DEBUG] switch_core_codec.c:246 sofia/ictcore/hiden_number Restore previous codec PCMU:0.
d1622c92-a835-11e7-9ce5-99473e034b79 EXECUTE sofia/ictcore/hiden_number curl(http://localhost/ictcore/gateway.php post username=myuser&application_id=2&application_data=%7B%22error%22%3A%22OK%22%2C%22pages%22%3A%221%22%2C%22result%22%3A%22success%22%7D&spool_id=1&gateway_flag=8&password=plsChangeMe)
2017-10-03 14:25:06.607773 [DEBUG] mod_curl.c:182 method: post, url: http://localhost/ictcore/gateway.php, content-type: (null)
2017-10-03 14:25:06.607773 [DEBUG] mod_curl.c:211 Post data: username=myuser&application_id=2&application_data={"error":"OK","pages":"1","result":"success"}&spool_id=1&gateway_flag=8&password=plsChangeMe
2017-10-03 14:25:06.647769 [INFO] switch_cpp.cpp:1358 2002017-10-03 14:25:06.647769 [INFO] switch_cpp.cpp:1358 Array2017-10-03 14:25:06.647769 [ERR] mod_lua.cpp:203 /usr/ictcore/bin/freeswitch/lib/JSON.lua:676: /usr/ictcore/bin/freeswitch/lib/JSON.lua:383: can't parse JSON at char 1 of: Array
stack traceback:
   [C]: in function 'assert'
   /usr/ictcore/bin/freeswitch/lib/JSON.lua:676: in function </usr/ictcore/bin/freeswitch/lib/JSON.lua:638>
   (...tail calls...)
   /usr/ictcore/bin/freeswitch/application.lua:224: in main chunk
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_cpp.cpp:1105 sofia/ictcore/hiden_number destroy/unlink session from object
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [NOTICE] switch_core_state_machine.c:315 sofia/ictcore/hiden_number has executed the last dialplan instruction, hanging up.
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/ictcore/hiden_number [CS_EXECUTE] [NORMAL_CLEARING]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_channel.c:3244 Send signal sofia/ictcore/hiden_number [KILL]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:535 (sofia/ictcore/hiden_number) State EXECUTE going to sleep
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:472 (sofia/ictcore/hiden_number) Running State Change CS_HANGUP
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:735 (sofia/ictcore/hiden_number) Callstate Change ACTIVE -> HANGUP
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:737 (sofia/ictcore/hiden_number) State HANGUP
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] mod_sofia.c:413 Channel sofia/ictcore/hiden_number hanging up, cause: NORMAL_CLEARING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] mod_sofia.c:465 Sending BYE to sofia/ictcore/hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:60 sofia/ictcore/hiden_number Standard HANGUP, cause: NORMAL_CLEARING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:737 (sofia/ictcore/hiden_number) State HANGUP going to sleep
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:504 (sofia/ictcore/hiden_number) State Change CS_HANGUP -> CS_REPORTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:472 (sofia/ictcore/hiden_number) Running State Change CS_REPORTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:823 (sofia/ictcore/hiden_number) State REPORTING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:104 sofia/ictcore/hiden_number Standard REPORTING, cause: NORMAL_CLEARING
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:823 (sofia/ictcore/hiden_number) State REPORTING going to sleep
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:498 (sofia/ictcore/hiden_number) State Change CS_REPORTING -> CS_DESTROY
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_session.c:1397 Send signal sofia/ictcore/hiden_number [BREAK]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_session.c:1624 Session 1 (sofia/ictcore/hiden_number) Locked, Waiting on external entities
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [NOTICE] switch_core_session.c:1642 Session 1 (sofia/ictcore/hiden_number) Ended
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [NOTICE] switch_core_session.c:1646 Close Channel sofia/ictcore/hiden_number [CS_DESTROY]
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:626 (sofia/ictcore/hiden_number) Running State Change CS_DESTROY
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:636 (sofia/ictcore/hiden_number) State DESTROY
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] mod_sofia.c:323 sofia/ictcore/hiden_number SOFIA DESTROY
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:111 sofia/ictcore/hiden_number Standard DESTROY
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.647769 [DEBUG] switch_core_state_machine.c:636 (sofia/ictcore/hiden_number) State DESTROY going to sleep


P.S i cannot load the full log because there is a limit of chars per post and i cannot upload the zip file due to a warning.

Thank you in advance
damiren
 
Posts: 4
Joined: Fri Apr 01, 2016 8:11 am

Re: Install Problem

Postby kashif » Thu Oct 19, 2017 7:44 am

according to your freeswitch log, "Fax successfully delivered".

d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:500 Fax successfully sent.
[/b]d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:513 Remote station id: hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:514 Local station id: hiden_number
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 1
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 1
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 8040x3850
d1622c92-a835-11e7-9ce5-99473e034b79 2017-10-03 14:25:06.587166 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 14400


email notification only work with email2fax / fax2email feature not for web2fax.
kashif
 
Posts: 22
Joined: Tue Mar 24, 2015 5:17 am


Return to Installation

Who is online

Users browsing this forum: Yahoo [Bot] and 1 guest

cron