Unable to TXFAX, but receiving is OK. Always result (49)
Hi All, I can receive faxes and email them to their recipients now with rxfax!!!! I cannot send anything though. My test command is: (with number changed) /usr/local/freeswitch/bin/fs_cli -x "originate {ignore_early_media=true}sofia/gateway/voipinnovations/11234567890 &txfax(/usr/sean/output/txfax.tiff)" I have also tried it without the ignore_early_media. I do not have any V.38 enabled. When I did no incoming faxes would work. The receiving fax never sent tone, so sender never connected. Any help would be greatly appreciated. Thanks. Here is the FS log: freeswitch@internal> 2012-03-19 12:58:06.511937 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables 2012-03-19 12:58:06.511937 [DEBUG] switch_event.c:1521 Parsing variable [ignore_early_media]=[true] 2012-03-19 12:58:06.511937 [NOTICE] switch_channel.c:924 New Channel sofia/external_noauth/14108038876 [bc6cadc8-761b-42cb-8873-889320fc2658] 2012-03-19 12:58:06.511937 [DEBUG] mod_sofia.c:4674 (sofia/external_noauth/14108038876) State Change CS_NEW -> CS_INIT 2012-03-19 12:58:06.511937 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:06.511937 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_INIT 2012-03-19 12:58:06.511937 [DEBUG] switch_core_state_machine.c:401 (sofia/external_noauth/14108038876) State INIT 2012-03-19 12:58:06.511937 [DEBUG] mod_sofia.c:85 sofia/external_noauth/14108038876 SOFIA INIT 2012-03-19 12:58:07.117896 [DEBUG] switch_nat.c:511 mapped public port 17322 protocol UDP to localport 17322 2012-03-19 12:58:07.602876 [DEBUG] switch_nat.c:511 mapped public port 17323 protocol UDP to localport 17323 2012-03-19 12:58:07.602876 [DEBUG] mod_sofia.c:125 (sofia/external_noauth/14108038876) State Change CS_INIT -> CS_ROUTING 2012-03-19 12:58:07.602876 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:401 (sofia/external_noauth/14108038876) State INIT going to sleep 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_ROUTING 2012-03-19 12:58:07.602876 [DEBUG] switch_channel.c:1884 (sofia/external_noauth/14108038876) Callstate Change DOWN -> RINGING 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:410 (sofia/external_noauth/14108038876) State ROUTING 2012-03-19 12:58:07.602876 [DEBUG] mod_sofia.c:148 sofia/external_noauth/14108038876 SOFIA ROUTING 2012-03-19 12:58:07.602876 [DEBUG] switch_ivr_originate.c:66 (sofia/external_noauth/14108038876) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-03-19 12:58:07.602876 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:410 (sofia/external_noauth/14108038876) State ROUTING going to sleep 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_CONSUME_MEDIA 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:429 (sofia/external_noauth/14108038876) State CONSUME_MEDIA 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:429 (sofia/external_noauth/14108038876) State CONSUME_MEDIA going to sleep 2012-03-19 12:58:07.602876 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:07.602876 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [calling][0] 2012-03-19 12:58:10.613725 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:10.613725 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:10.623715 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [proceeding][183] 2012-03-19 12:58:10.623715 [DEBUG] sofia.c:5505 Remote SDP: v=0 o=Sansay-VSXi 188 1 IN IP4 64.136.174.30 s=Session Controller c=IN IP4 69.85.185.142 t=0 0 m=audio 26144 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:115:32000:20:48000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:107:16000:20:32000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:2919 Set Codec sofia/external_noauth/14108038876 PCMU/8000 20 ms 160 samples 64000 bits 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4912 Set 2833 dtmf send payload to 101 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:3171 AUDIO RTP [sofia/external_noauth/14108038876] 10.10.40.185 port 17322 -> 69.85.185.142 port 26144 codec: 0 ms: 20 2012-03-19 12:58:10.623715 [DEBUG] switch_rtp.c:1659 Starting timer [soft] 160 bytes per 20ms 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:3435 Set 2833 dtmf send payload to 101 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:3441 Set 2833 dtmf receive payload to 101 2012-03-19 12:58:10.623715 [NOTICE] sofia_glue.c:3945 Pre-Answer sofia/external_noauth/14108038876! 2012-03-19 12:58:10.623715 [DEBUG] switch_channel.c:2930 (sofia/external_noauth/14108038876) Callstate Change RINGING -> EARLY 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [completing][200] 2012-03-19 12:58:23.010042 [DEBUG] sofia.c:5502 Duplicate SDP v=0 o=Sansay-VSXi 188 1 IN IP4 64.136.174.30 s=Session Controller c=IN IP4 69.85.185.142 t=0 0 m=audio 26144 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [ready][200] 2012-03-19 12:58:23.010042 [DEBUG] switch_channel.c:3188 (sofia/external_noauth/14108038876) Callstate Change EARLY -> ACTIVE 2012-03-19 12:58:23.010042 [NOTICE] sofia.c:6142 Channel [sofia/external_noauth/14108038876] has been answered 2012-03-19 12:58:23.010042 [DEBUG] switch_ivr_originate.c:3266 Originate Resulted in Success: [sofia/external_noauth/14108038876] 2012-03-19 12:58:23.010042 [INFO] switch_channel.c:2708 sofia/external_noauth/14108038876 Flipping CID from "" <0000000000> to "Outbound Call" <14108038876> 2012-03-19 12:58:23.010042 [DEBUG] mod_commands.c:3574 (sofia/external_noauth/14108038876) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] switch_core_state_machine.c:417 (sofia/external_noauth/14108038876) State EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] mod_sofia.c:241 sofia/external_noauth/14108038876 SOFIA EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] switch_core_state_machine.c:192 sofia/external_noauth/14108038876 Standard EXECUTE EXECUTE sofia/external_noauth/14108038876 txfax(/usr/sean/output/txfax.tiff) 2012-03-19 12:58:23.010042 [DEBUG] mod_spandsp_fax.c:1355 Raw read codec activation Success L16 20000 2012-03-19 12:58:23.010042 [DEBUG] switch_core_codec.c:116 sofia/external_noauth/14108038876 Push codec L16:70 2012-03-19 12:58:23.010042 [DEBUG] mod_spandsp_fax.c:1371 Raw write codec activation Success L16 2012-03-19 12:58:23.070036 [DEBUG] switch_rtp.c:3204 Correct ip/port confirmed. 2012-03-19 12:58:25.539903 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] switch_channel.c:2846 (sofia/external_noauth/14108038876) Callstate Change ACTIVE -> HANGUP 2012-03-19 12:58:25.549903 [NOTICE] sofia.c:623 Hangup sofia/external_noauth/14108038876 [CS_EXECUTE] [NORMAL_CLEARING] 2012-03-19 12:58:25.549903 [DEBUG] switch_channel.c:2869 Send signal sofia/external_noauth/14108038876 [KILL] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:489 ============================================================================ == 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:502 Fax processing not successful - result (49) The call dropped prematurely. 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:507 Remote station id: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:508 Local station id: SpanDSP Fax Ident 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:509 Pages transferred: 0 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:511 Total fax pages: 0 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:512 Image resolution: 0x0 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:513 Transfer Rate: 14400 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:515 ECM status off 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:516 remote country: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:517 remote vendor: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:518 remote model: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:520 ============================================================================ == 2012-03-19 12:58:25.549903 [DEBUG] switch_core_codec.c:141 sofia/external_noauth/14108038876 Restore previous codec PCMU:0. 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:2285 sofia/external_noauth/14108038876 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:417 (sofia/external_noauth/14108038876) State EXECUTE going to sleep 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_HANGUP 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:602 (sofia/external_noauth/14108038876) State HANGUP 2012-03-19 12:58:25.549903 [DEBUG] mod_sofia.c:463 sofia/external_noauth/14108038876 Overriding SIP cause 480 with 200 from the other leg 2012-03-19 12:58:25.549903 [DEBUG] mod_sofia.c:469 Channel sofia/external_noauth/14108038876 hanging up, cause: NORMAL_CLEARING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:47 sofia/external_noauth/14108038876 Standard HANGUP, cause: NORMAL_CLEARING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:602 (sofia/external_noauth/14108038876) State HANGUP going to sleep 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:393 (sofia/external_noauth/14108038876) State Change CS_HANGUP -> CS_REPORTING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_REPORTING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:662 (sofia/external_noauth/14108038876) State REPORTING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:79 sofia/external_noauth/14108038876 Standard REPORTING, cause: NORMAL_CLEARING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:662 (sofia/external_noauth/14108038876) State REPORTING going to sleep 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:387 (sofia/external_noauth/14108038876) State Change CS_REPORTING -> CS_DESTROY 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1380 Session 184 (sofia/external_noauth/14108038876) Locked, Waiting on external entities 2012-03-19 12:58:25.549903 [NOTICE] switch_core_session.c:1398 Session 184 (sofia/external_noauth/14108038876) Ended 2012-03-19 12:58:25.549903 [NOTICE] switch_core_session.c:1400 Close Channel sofia/external_noauth/14108038876 [CS_DESTROY] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:491 (sofia/external_noauth/14108038876) Callstate Change HANGUP -> DOWN 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:494 (sofia/external_noauth/14108038876) Running State Change CS_DESTROY 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:504 (sofia/external_noauth/14108038876) State DESTROY 2012-03-19 12:58:25.549903 [DEBUG] mod_sofia.c:374 sofia/external_noauth/14108038876 SOFIA DESTROY 2012-03-19 12:58:25.559907 [DEBUG] switch_nat.c:571 unmapped public port 17322 protocol UDP to localport 17322 2012-03-19 12:58:25.579904 [DEBUG] switch_nat.c:571 unmapped public port 17323 protocol UDP to localport 17323 2012-03-19 12:58:25.579904 [DEBUG] switch_core_state_machine.c:86 sofia/external_noauth/14108038876 Standard DESTROY 2012-03-19 12:58:25.579904 [DEBUG] switch_core_state_machine.c:504 (sofia/external_noauth/14108038876) State DESTROY going to sleep
DUH! The fax phone number is really critical whether you are dyslexic or not. From: Sean Devoy [mailto:sdevoy@bizfocused.com] Sent: Monday, March 19, 2012 1:07 PM To: freeswitch-users@lists.freeswitch.org Subject: [Freeswitch-users] Unable to TXFAX, but receiving is OK. Always result (49) Hi All, I can receive faxes and email them to their recipients now with rxfax!!!! I cannot send anything though. My test command is: (with number changed) /usr/local/freeswitch/bin/fs_cli -x "originate {ignore_early_media=true}sofia/gateway/voipinnovations/11234567890 &txfax(/usr/sean/output/txfax.tiff)" I have also tried it without the ignore_early_media. I do not have any V.38 enabled. When I did no incoming faxes would work. The receiving fax never sent tone, so sender never connected. Any help would be greatly appreciated. Thanks. Here is the FS log: freeswitch@internal> 2012-03-19 12:58:06.511937 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables 2012-03-19 12:58:06.511937 [DEBUG] switch_event.c:1521 Parsing variable [ignore_early_media]=[true] 2012-03-19 12:58:06.511937 [NOTICE] switch_channel.c:924 New Channel sofia/external_noauth/14108038876 [bc6cadc8-761b-42cb-8873-889320fc2658] 2012-03-19 12:58:06.511937 [DEBUG] mod_sofia.c:4674 (sofia/external_noauth/14108038876) State Change CS_NEW -> CS_INIT 2012-03-19 12:58:06.511937 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:06.511937 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_INIT 2012-03-19 12:58:06.511937 [DEBUG] switch_core_state_machine.c:401 (sofia/external_noauth/14108038876) State INIT 2012-03-19 12:58:06.511937 [DEBUG] mod_sofia.c:85 sofia/external_noauth/14108038876 SOFIA INIT 2012-03-19 12:58:07.117896 [DEBUG] switch_nat.c:511 mapped public port 17322 protocol UDP to localport 17322 2012-03-19 12:58:07.602876 [DEBUG] switch_nat.c:511 mapped public port 17323 protocol UDP to localport 17323 2012-03-19 12:58:07.602876 [DEBUG] mod_sofia.c:125 (sofia/external_noauth/14108038876) State Change CS_INIT -> CS_ROUTING 2012-03-19 12:58:07.602876 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:401 (sofia/external_noauth/14108038876) State INIT going to sleep 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_ROUTING 2012-03-19 12:58:07.602876 [DEBUG] switch_channel.c:1884 (sofia/external_noauth/14108038876) Callstate Change DOWN -> RINGING 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:410 (sofia/external_noauth/14108038876) State ROUTING 2012-03-19 12:58:07.602876 [DEBUG] mod_sofia.c:148 sofia/external_noauth/14108038876 SOFIA ROUTING 2012-03-19 12:58:07.602876 [DEBUG] switch_ivr_originate.c:66 (sofia/external_noauth/14108038876) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-03-19 12:58:07.602876 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:410 (sofia/external_noauth/14108038876) State ROUTING going to sleep 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_CONSUME_MEDIA 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:429 (sofia/external_noauth/14108038876) State CONSUME_MEDIA 2012-03-19 12:58:07.602876 [DEBUG] switch_core_state_machine.c:429 (sofia/external_noauth/14108038876) State CONSUME_MEDIA going to sleep 2012-03-19 12:58:07.602876 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:07.602876 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [calling][0] 2012-03-19 12:58:10.613725 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:10.613725 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:10.623715 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [proceeding][183] 2012-03-19 12:58:10.623715 [DEBUG] sofia.c:5505 Remote SDP: v=0 o=Sansay-VSXi 188 1 IN IP4 64.136.174.30 s=Session Controller c=IN IP4 69.85.185.142 t=0 0 m=audio 26144 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:115:32000:20:48000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:107:16000:20:32000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:2919 Set Codec sofia/external_noauth/14108038876 PCMU/8000 20 ms 160 samples 64000 bits 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:4912 Set 2833 dtmf send payload to 101 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:3171 AUDIO RTP [sofia/external_noauth/14108038876] 10.10.40.185 port 17322 -> 69.85.185.142 port 26144 codec: 0 ms: 20 2012-03-19 12:58:10.623715 [DEBUG] switch_rtp.c:1659 Starting timer [soft] 160 bytes per 20ms 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:3435 Set 2833 dtmf send payload to 101 2012-03-19 12:58:10.623715 [DEBUG] sofia_glue.c:3441 Set 2833 dtmf receive payload to 101 2012-03-19 12:58:10.623715 [NOTICE] sofia_glue.c:3945 Pre-Answer sofia/external_noauth/14108038876! 2012-03-19 12:58:10.623715 [DEBUG] switch_channel.c:2930 (sofia/external_noauth/14108038876) Callstate Change RINGING -> EARLY 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [completing][200] 2012-03-19 12:58:23.010042 [DEBUG] sofia.c:5502 Duplicate SDP v=0 o=Sansay-VSXi 188 1 IN IP4 64.136.174.30 s=Session Controller c=IN IP4 69.85.185.142 t=0 0 m=audio 26144 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] sofia.c:5494 Channel sofia/external_noauth/14108038876 entering state [ready][200] 2012-03-19 12:58:23.010042 [DEBUG] switch_channel.c:3188 (sofia/external_noauth/14108038876) Callstate Change EARLY -> ACTIVE 2012-03-19 12:58:23.010042 [NOTICE] sofia.c:6142 Channel [sofia/external_noauth/14108038876] has been answered 2012-03-19 12:58:23.010042 [DEBUG] switch_ivr_originate.c:3266 Originate Resulted in Success: [sofia/external_noauth/14108038876] 2012-03-19 12:58:23.010042 [INFO] switch_channel.c:2708 sofia/external_noauth/14108038876 Flipping CID from "" <0000000000> to "Outbound Call" <14108038876> 2012-03-19 12:58:23.010042 [DEBUG] mod_commands.c:3574 (sofia/external_noauth/14108038876) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:23.010042 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] switch_core_state_machine.c:417 (sofia/external_noauth/14108038876) State EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] mod_sofia.c:241 sofia/external_noauth/14108038876 SOFIA EXECUTE 2012-03-19 12:58:23.010042 [DEBUG] switch_core_state_machine.c:192 sofia/external_noauth/14108038876 Standard EXECUTE EXECUTE sofia/external_noauth/14108038876 txfax(/usr/sean/output/txfax.tiff) 2012-03-19 12:58:23.010042 [DEBUG] mod_spandsp_fax.c:1355 Raw read codec activation Success L16 20000 2012-03-19 12:58:23.010042 [DEBUG] switch_core_codec.c:116 sofia/external_noauth/14108038876 Push codec L16:70 2012-03-19 12:58:23.010042 [DEBUG] mod_spandsp_fax.c:1371 Raw write codec activation Success L16 2012-03-19 12:58:23.070036 [DEBUG] switch_rtp.c:3204 Correct ip/port confirmed. 2012-03-19 12:58:25.539903 [DEBUG] switch_core_session.c:875 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] switch_channel.c:2846 (sofia/external_noauth/14108038876) Callstate Change ACTIVE -> HANGUP 2012-03-19 12:58:25.549903 [NOTICE] sofia.c:623 Hangup sofia/external_noauth/14108038876 [CS_EXECUTE] [NORMAL_CLEARING] 2012-03-19 12:58:25.549903 [DEBUG] switch_channel.c:2869 Send signal sofia/external_noauth/14108038876 [KILL] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:489 ============================================================================ == 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:502 Fax processing not successful - result (49) The call dropped prematurely. 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:507 Remote station id: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:508 Local station id: SpanDSP Fax Ident 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:509 Pages transferred: 0 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:511 Total fax pages: 0 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:512 Image resolution: 0x0 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:513 Transfer Rate: 14400 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:515 ECM status off 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:516 remote country: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:517 remote vendor: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:518 remote model: 2012-03-19 12:58:25.549903 [DEBUG] mod_spandsp_fax.c:520 ============================================================================ == 2012-03-19 12:58:25.549903 [DEBUG] switch_core_codec.c:141 sofia/external_noauth/14108038876 Restore previous codec PCMU:0. 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:2285 sofia/external_noauth/14108038876 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:417 (sofia/external_noauth/14108038876) State EXECUTE going to sleep 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_HANGUP 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:602 (sofia/external_noauth/14108038876) State HANGUP 2012-03-19 12:58:25.549903 [DEBUG] mod_sofia.c:463 sofia/external_noauth/14108038876 Overriding SIP cause 480 with 200 from the other leg 2012-03-19 12:58:25.549903 [DEBUG] mod_sofia.c:469 Channel sofia/external_noauth/14108038876 hanging up, cause: NORMAL_CLEARING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:47 sofia/external_noauth/14108038876 Standard HANGUP, cause: NORMAL_CLEARING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:602 (sofia/external_noauth/14108038876) State HANGUP going to sleep 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:393 (sofia/external_noauth/14108038876) State Change CS_HANGUP -> CS_REPORTING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:362 (sofia/external_noauth/14108038876) Running State Change CS_REPORTING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:662 (sofia/external_noauth/14108038876) State REPORTING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:79 sofia/external_noauth/14108038876 Standard REPORTING, cause: NORMAL_CLEARING 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:662 (sofia/external_noauth/14108038876) State REPORTING going to sleep 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:387 (sofia/external_noauth/14108038876) State Change CS_REPORTING -> CS_DESTROY 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1180 Send signal sofia/external_noauth/14108038876 [BREAK] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_session.c:1380 Session 184 (sofia/external_noauth/14108038876) Locked, Waiting on external entities 2012-03-19 12:58:25.549903 [NOTICE] switch_core_session.c:1398 Session 184 (sofia/external_noauth/14108038876) Ended 2012-03-19 12:58:25.549903 [NOTICE] switch_core_session.c:1400 Close Channel sofia/external_noauth/14108038876 [CS_DESTROY] 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:491 (sofia/external_noauth/14108038876) Callstate Change HANGUP -> DOWN 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:494 (sofia/external_noauth/14108038876) Running State Change CS_DESTROY 2012-03-19 12:58:25.549903 [DEBUG] switch_core_state_machine.c:504 (sofia/external_noauth/14108038876) State DESTROY 2012-03-19 12:58:25.549903 [DEBUG] mod_sofia.c:374 sofia/external_noauth/14108038876 SOFIA DESTROY 2012-03-19 12:58:25.559907 [DEBUG] switch_nat.c:571 unmapped public port 17322 protocol UDP to localport 17322 2012-03-19 12:58:25.579904 [DEBUG] switch_nat.c:571 unmapped public port 17323 protocol UDP to localport 17323 2012-03-19 12:58:25.579904 [DEBUG] switch_core_state_machine.c:86 sofia/external_noauth/14108038876 Standard DESTROY 2012-03-19 12:58:25.579904 [DEBUG] switch_core_state_machine.c:504 (sofia/external_noauth/14108038876) State DESTROY going to sleep
Faxing is a B to try and get it to work reliably over VOIP. I have added a troubleshooting section at: http://wiki.freeswitch.com/wiki/Mod_spandsp#Troubleshooting with what I have found. It may help to track down the problem. ~mitch
Does your ITSP support T.38? Try to catch RE-INVITE ngrep -d eth0 -qt -W byline -i t38 20 марта 2012 г. 7:11 пользователь Mitch Capper <mitch.capper@gmail.com>написал:
Faxing is a B to try and get it to work reliably over VOIP. I have added a troubleshooting section at: http://wiki.freeswitch.com/wiki/Mod_spandsp#Troubleshooting with what I have found. It may help to track down the problem.
~mitch
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
participants (3)
-
Anton Kvashenkin
-
Mitch Capper
-
Sean Devoy