2018-12-17 15:42:07.227774 [DEBUG] sofia.c:7084 Channel sofia/default/123456@10.0.4.21 entering state [completed][200] 2018-12-17 15:42:07.227774 [DEBUG] switch_core_media.c:9750 Remote address:port [10.0.4.21:18410] has not changed. 2018-12-17 15:42:07.227774 [INFO] sofia.c:1268 sofia/default/123456@10.0.4.21 Update Caller ID to "123456" <123456> 2018-12-17 15:42:07.227774 [DEBUG] sofia.c:7084 Channel sofia/default/123456@10.0.4.21 entering state [completing][200] 2018-12-17 15:42:07.227774 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=FreeSWITCH 1545012179 1545012181 IN IP4 10.0.4.21 s=FreeSWITCH c=IN IP4 10.0.4.21 t=0 0 m=image 20342 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2018-12-17 15:42:07.227774 [DEBUG] sofia.c:7084 Channel sofia/default/123456@10.0.4.21 entering state [ready][200] 2018-12-17 15:42:07.227774 [DEBUG] switch_core_media.c:4041 sofia/default/123456@10.0.4.21 T38 ACCEPT on response 2018-12-17 15:42:07.227774 [DEBUG] switch_core_media.c:4146 sofia/default/123456@10.0.4.21 T38 IS POSSIBLE on response 2018-12-17 15:42:07.227774 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/123456@10.0.4.21. 2018-12-17 15:42:07.227774 [DEBUG] sofia.c:7084 Channel sofia/default/123456@10.0.4.21 entering state [ready][200] 2018-12-17 15:42:07.247766 [DEBUG] switch_core_media.c:9750 Remote address:port [10.0.4.21:20342] has not changed. 2018-12-17 15:42:07.247766 [DEBUG] switch_core_media.c:9750 Remote address:port [10.0.4.21:20342] has not changed. 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:317 === Negotiation Result ======================================================= 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:318 Remote station id: SpanDSP Fax Ident 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:319 Local station id: 7345355234 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:320 Transfer Rate: 9600 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:322 ECM status off 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:323 remote country: 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:324 remote vendor: 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:325 remote model: 2018-12-17 15:42:12.667772 [DEBUG] mod_spandsp_fax.c:327 ============================================================================== 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:317 === Negotiation Result ======================================================= 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:318 Remote station id: 7345355234 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:319 Local station id: SpanDSP Fax Ident 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:320 Transfer Rate: 9600 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:322 ECM status off 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:323 remote country: 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:324 remote vendor: 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:325 remote model: 2018-12-17 15:42:14.647783 [DEBUG] mod_spandsp_fax.c:327 ============================================================================== 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:428 ==== Page Received =========================================================== 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:429 Page no = 1 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:430 Image type = bi-level (bi-level in the file) 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:431 Image size = 1728 x 1094 pixels (1728 x 1094 pixels in the file) 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:432 Image resolution = 8040/m x 3850/m (8040/m x 3850/m in the file) 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:433 Compression = T.4 2-D (4) 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:434 Compressed image size = 3558 bytes 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:435 Bad rows = 0 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:436 Longest bad row run = 0 2018-12-17 15:42:22.767775 [DEBUG] mod_spandsp_fax.c:437 ============================================================================== 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:428 ==== Page Sent =============================================================== 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:429 Page no = 1 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:430 Image type = bi-level (bi-level in the file) 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:431 Image size = 1728 x 1094 pixels (1728 x 1078 pixels in the file) 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:432 Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file) 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:433 Compression = T.4 2-D (4) 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:434 Compressed image size = 3558 bytes 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:435 Bad rows = 0 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:436 Longest bad row run = 0 2018-12-17 15:42:23.927765 [DEBUG] mod_spandsp_fax.c:437 ============================================================================== 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:496 ============================================================================== 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:502 Fax successfully received. 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 7345355234 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:514 Local station id: SpanDSP Fax Ident 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 1 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 1 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 8040x3850 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 9600 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:521 ECM status off 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:522 remote country: 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:523 remote vendor: 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:524 remote model: 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:526 ============================================================================== 2018-12-17 15:42:25.127808 [DEBUG] switch_core_codec.c:248 sofia/default/123456@10.0.4.21 Restore previous codec PCMA:8. EXECUTE sofia/default/123456@10.0.4.21 hangup() 2018-12-17 15:42:25.127808 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/123456@10.0.4.21 [CS_EXECUTE] [NORMAL_CLEARING] 2018-12-17 15:42:25.127808 [DEBUG] switch_core_session.c:2815 sofia/default/123456@10.0.4.21 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:650 (sofia/default/123456@10.0.4.21) State EXECUTE going to sleep 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:584 (sofia/default/123456@10.0.4.21) Running State Change CS_HANGUP (Cur 2 Tot 110) 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:850 (sofia/default/123456@10.0.4.21) Callstate Change ACTIVE -> HANGUP 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:852 (sofia/default/123456@10.0.4.21) State HANGUP 2018-12-17 15:42:25.127808 [DEBUG] mod_sofia.c:438 Channel sofia/default/123456@10.0.4.21 hanging up, cause: NORMAL_CLEARING 2018-12-17 15:42:25.127808 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/123456@10.0.4.21 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:60 sofia/default/123456@10.0.4.21 Standard HANGUP, cause: NORMAL_CLEARING 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:852 (sofia/default/123456@10.0.4.21) State HANGUP going to sleep 2018-12-17 15:42:25.127808 [NOTICE] sofia.c:1012 Hangup sofia/default/123456@10.0.4.21 [CS_EXECUTE] [NORMAL_CLEARING] 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:496 ============================================================================== 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:500 Fax successfully sent. 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:513 Remote station id: SpanDSP Fax Ident 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:514 Local station id: 7345355234 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 1 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 1 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 8040x3850 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 9600 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:521 ECM status off 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:522 remote country: 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:523 remote vendor: 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:524 remote model: 2018-12-17 15:42:25.127808 [DEBUG] mod_spandsp_fax.c:526 ============================================================================== 2018-12-17 15:42:25.127808 [DEBUG] switch_core_codec.c:248 sofia/default/123456@10.0.4.21 Restore previous codec PCMA:8. 2018-12-17 15:42:25.127808 [DEBUG] switch_core_session.c:2815 sofia/default/123456@10.0.4.21 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:650 (sofia/default/123456@10.0.4.21) State EXECUTE going to sleep 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:584 (sofia/default/123456@10.0.4.21) Running State Change CS_HANGUP (Cur 2 Tot 110) 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:850 (sofia/default/123456@10.0.4.21) Callstate Change ACTIVE -> HANGUP 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:852 (sofia/default/123456@10.0.4.21) State HANGUP 2018-12-17 15:42:25.127808 [DEBUG] mod_sofia.c:438 Channel sofia/default/123456@10.0.4.21 hanging up, cause: NORMAL_CLEARING 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:60 sofia/default/123456@10.0.4.21 Standard HANGUP, cause: NORMAL_CLEARING 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:852 (sofia/default/123456@10.0.4.21) State HANGUP going to sleep 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:619 (sofia/default/123456@10.0.4.21) State Change CS_HANGUP -> CS_REPORTING 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:584 (sofia/default/123456@10.0.4.21) Running State Change CS_REPORTING (Cur 2 Tot 110) 2018-12-17 15:42:25.127808 [DEBUG] switch_core_state_machine.c:938 (sofia/default/123456@10.0.4.21) State REPORTING 2018-12-17 15:42:25.127808 [INFO] mod_json_cdr.c:271 Process [3dd3f876-01cf-11e9-b2f0-47c80c1440b4.cdr.json] 2018-12-17 15:43:25.147765 [ERR] mod_json_cdr.c:396 Got error [504] posting to web server [http://127.0.0.1:8735/cdr.php] 2018-12-17 15:43:25.147765 [ERR] mod_json_cdr.c:415 Unable to post to web server 2018-12-17 15:43:25.147765 [INFO] mod_json_cdr.c:208 Backup file /usr/local/freeswitch/log/xml_json/3dd3f876-01cf-11e9-b2f0-47c80c1440b4.cdr.json 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:174 sofia/default/123456@10.0.4.21 Standard REPORTING, cause: NORMAL_CLEARING 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:938 (sofia/default/123456@10.0.4.21) State REPORTING going to sleep 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:610 (sofia/default/123456@10.0.4.21) State Change CS_REPORTING -> CS_DESTROY 2018-12-17 15:43:25.147765 [DEBUG] switch_core_session.c:1665 Session 109 (sofia/default/123456@10.0.4.21) Locked, Waiting on external entities 2018-12-17 15:43:25.147765 [NOTICE] switch_core_session.c:1683 Session 109 (sofia/default/123456@10.0.4.21) Ended 2018-12-17 15:43:25.147765 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/123456@10.0.4.21 [CS_DESTROY] 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:741 (sofia/default/123456@10.0.4.21) Running State Change CS_DESTROY (Cur 1 Tot 110) 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:751 (sofia/default/123456@10.0.4.21) State DESTROY 2018-12-17 15:43:25.147765 [DEBUG] mod_sofia.c:343 sofia/default/123456@10.0.4.21 SOFIA DESTROY 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:181 sofia/default/123456@10.0.4.21 Standard DESTROY 2018-12-17 15:43:25.147765 [DEBUG] switch_core_state_machine.c:751 (sofia/default/123456@10.0.4.21) State DESTROY going to sleep 2018-12-17 15:44:28.527764 [DEBUG] switch_core_state_machine.c:783 Hangup Command with Session system(/var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/3dd43444-01cf-11e9-b2f2-47c80c1440b4-FAX.tif 7345355234 7345355234_ devang.nathwani@inextrix.com /var/www/html/astpp/attachments/3dd43444-01cf-11e9-b2f2-47c80c1440b4-FAX.pdf devang.nathwani@inextrix.com 123456):