[Freeswitch-users] Dialplan for FS modems - odd behaviour or am I doing it wrong?

[Alex Crow]

Hi,

Please see the below log snippet. I am accepting faxes from an ISDN 
gateway on sequential numbers and attempting to route them into distinct 
freeswitch modems by taking the last two digits of the called number thusly:

    <condition field="destination_number" 
expression="^12([1-2][0-9]|3[0-6])$">
         <!--<action application="set" data="ignore_early_media=true"/>-->
         <action application="set" data="sip_ignore_reinvites=true"/>
         <action application="set" 
data="absolute_codec_string='PCMA,PCMU'"/>
         <action application="set" data="jitterbuffer_msec=600:600:60"/>
         <action application="set" data="sip_jitter_buffer_plc=false"/>
         <action application="bridge" data="modem/$1/a"/>
    </condition>

And then have Hylafax email them on depending on which modem they 
arrived on (via FaxDispatch).

However as you can see from the below log snippets, it seems that FS is 
quite happy to change the modem device from what is actually delivered 
via the dialplan. Consequently a number of my faxes are now being routed 
to the wrong people.

Is my bridge dest wrong? Should it be something like modem/$1/0 instead 
of "a"?

Thanks

Alex

Dialplan: sofia/internal/anonymous at anonymous.invalid Action 
set(sip_ignore_reinvites=true)
Dialplan: sofia/internal/anonymous at anonymous.invalid Action 
set(absolute_codec_string='PCMA,PCMU')
Dialplan: sofia/internal/anonymous at anonymous.invalid Action 
set(jitterbuffer_msec=600:600:60)
Dialplan: sofia/internal/anonymous at anonymous.invalid Action 
set(sip_jitter_buffer_plc=false)
Dialplan: sofia/internal/anonymous at anonymous.invalid Action 
bridge(modem/13/a)
2012-12-11 14:43:18.873181 [DEBUG] switch_core_state_machine.c:167 
(sofia/internal/anonymous at anonymous.invalid) State Change CS_ROUTING -> 
CS_EXECUTE
2012-12-11 14:43:18.873181 [DEBUG] switch_core_session.c:1283 Send 
signal sofia/internal/anonymous at anonymous.invalid [BREAK]
2012-12-11 14:43:18.873181 [DEBUG] switch_core_state_machine.c:470 
(sofia/internal/anonymous at anonymous.invalid) State ROUTING going to sleep
2012-12-11 14:43:18.873181 [DEBUG] switch_core_state_machine.c:415 
(sofia/internal/anonymous at anonymous.invalid) Running State Change CS_EXECUTE
2012-12-11 14:43:18.873181 [DEBUG] switch_core_state_machine.c:477 
(sofia/internal/anonymous at anonymous.invalid) State EXECUTE
2012-12-11 14:43:18.873181 [DEBUG] mod_sofia.c:242 
sofia/internal/anonymous at anonymous.invalid SOFIA EXECUTE
2012-12-11 14:43:18.873181 [DEBUG] switch_core_state_machine.c:209 
sofia/internal/anonymous at anonymous.invalid Standard EXECUTE
EXECUTE sofia/internal/anonymous at anonymous.invalid set(call_direction=local)
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET [call_direction]=[local]
EXECUTE sofia/internal/anonymous at anonymous.invalid set(open=true)
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET [open]=[true]
EXECUTE sofia/internal/anonymous at anonymous.invalid 
hash(insert/192.168.20.245-spymap/anonymous/4e5c4699-aa22-4998-b7fa-b50a52c940fb)
EXECUTE sofia/internal/anonymous at anonymous.invalid 
hash(insert/192.168.20.245-last_dial/anonymous/1213)
EXECUTE sofia/internal/anonymous at anonymous.invalid 
hash(insert/192.168.20.245-last_dial/global/4e5c4699-aa22-4998-b7fa-b50a52c940fb)
EXECUTE sofia/internal/anonymous at anonymous.invalid set(RFC2822_DATE=Tue, 
11 Dec 2012 14:43:18 +0000)
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET [RFC2822_DATE]=[Tue, 11 
Dec 2012 14:43:18 +0000]
EXECUTE sofia/internal/anonymous at anonymous.invalid 
set(sip_ignore_reinvites=true)
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET [sip_ignore_reinvites]=[true]
EXECUTE sofia/internal/anonymous at anonymous.invalid 
set(absolute_codec_string='PCMA,PCMU')
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET 
[absolute_codec_string]=['PCMA,PCMU']
EXECUTE sofia/internal/anonymous at anonymous.invalid 
set(jitterbuffer_msec=600:600:60)
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET 
[jitterbuffer_msec]=[600:600:60]
EXECUTE sofia/internal/anonymous at anonymous.invalid 
set(sip_jitter_buffer_plc=false)
2012-12-11 14:43:18.873181 [DEBUG] mod_dptools.c:1344 
sofia/internal/anonymous at anonymous.invalid SET 
[sip_jitter_buffer_plc]=[false]
EXECUTE sofia/internal/anonymous at anonymous.invalid bridge(modem/13/a)
2012-12-11 14:43:18.873181 [DEBUG] switch_channel.c:1089 
sofia/internal/anonymous at anonymous.invalid EXPORTING[export_vars] 
[domain_name]=[192.168.20.245] to event
2012-12-11 14:43:18.873181 [DEBUG] switch_ivr_originate.c:2022 Parsing 
global variables
2012-12-11 14:43:18.873181 [DEBUG] mod_spandsp_modem.c:1417 Modem 
/dev/FS/FS10 [ONHOOK] - Changing state to ACQUIRED
2012-12-11 14:43:18.873181 [NOTICE] switch_channel.c:968 New Channel 
modem/10/a [a202aef1-1628-45e8-b720-9f5368aa4565]
2012-12-11 14:43:18.873181 [DEBUG] mod_spandsp_modem.c:769 modem/10/a 
setup codec L16/8000/20
2012-12-11 14:43:18.873181 [DEBUG] switch_channel.c:1135 EXPORT 
(export_vars) [rtp_autoflush_during_bridge]=[false]
2012-12-11 14:43:18.873181 [DEBUG] mod_spandsp_modem.c:896 (modem/10/a) 
State Change CS_NEW -> CS_INIT
2012-12-11 14:43:18.873181 [DEBUG] switch_core_session.c:1283 Send 
signal modem/10/a [BREAK]
2012-12-11 14:43:18.873181 [DEBUG] mod_spandsp_modem.c:581 modem/10/a 
CHANNEL KILL
2012-12-11 14:43:18.893118 [DEBUG] switch_core_state_machine.c:415 
(modem/10/a) Running State Change CS_INIT
2012-12-11 14:43:18.893118 [DEBUG] switch_core_state_machine.c:454 
(modem/10/a) State INIT
2012-12-11 14:43:18.893118 [DEBUG] mod_spandsp_modem.c:461 Modem 
/dev/FS/FS10 [ACQUIRED] - Changing state to RINGING
2012-12-11 14:43:18.893118 [DEBUG] mod_spandsp_modem.c:1131 Modem 
/dev/FS/FS10 [RINGING] - RNG 1


And another one:


EXECUTE sofia/internal/anonymous at anonymous.invalid bridge(modem/23/a)
2012-12-11 13:42:20.813107 [DEBUG] switch_channel.c:1089 
sofia/internal/anonymous at anonymous.invalid EXPORTING[export_vars] 
[domain_name]=[192.168.20.245] to event
2012-12-11 13:42:20.813107 [DEBUG] switch_ivr_originate.c:2022 Parsing 
global variables
2012-12-11 13:42:20.813107 [DEBUG] mod_spandsp_modem.c:1417 Modem 
/dev/FS/FS26 [ONHOOK] - Changing state to ACQUIRED
2012-12-11 13:42:20.813107 [NOTICE] switch_channel.c:968 New Channel 
modem/26/a [32872ae9-6f78-45b0-af56-4b18c9de7160]
2012-12-11 13:42:20.813107 [DEBUG] mod_spandsp_modem.c:769 modem/26/a 
setup codec L16/8000/20
2012-12-11 13:42:20.813107 [DEBUG] switch_channel.c:1135 EXPORT 
(export_vars) [rtp_autoflush_during_bridge]=[false]
2012-12-11 13:42:20.813107 [DEBUG] mod_spandsp_modem.c:896 (modem/26/a) 
State Change CS_NEW -> CS_INIT
2012-12-11 13:42:20.813107 [DEBUG] switch_core_session.c:1283 Send 
signal modem/26/a [BREAK]
2012-12-11 13:42:20.813107 [DEBUG] mod_spandsp_modem.c:581 modem/26/a 
CHANNEL KILL
2012-12-11 13:42:20.813107 [DEBUG] switch_core_state_machine.c:415 
(modem/26/a) Running State Change CS_INIT
2012-12-11 13:42:20.813107 [DEBUG] switch_core_state_machine.c:454 
(modem/26/a) State INIT
2012-12-11 13:42:20.813107 [DEBUG] mod_spandsp_modem.c:461 Modem 
/dev/FS/FS26 [ACQUIRED] - Changing state to RINGING
2012-12-11 13:42:20.813107 [DEBUG] mod_spandsp_modem.c:1131 Modem 
/dev/FS/FS26 [RINGING] - RNG 1
2012-12-11 13:42:20.833137 [DEBUG] mod_spandsp_modem.c:1038 Modem 
/dev/FS/FS26 [RINGING] - Answering
2012-12-11 13:42:20.833137 [DEBUG] mod_spandsp_modem.c:1040 Modem 
/dev/FS/FS26 [RINGING] - Changing state to ANSWERED