it almost looks like set isn't a valid application... is mod_dptools loaded? On Apr 21, 2014, at 2:44 PM, Charles Bujold <cjbujold@accra.ca> wrote:
Came back from Easter and Freeswitch will not make or receive calls. Internally we get the following error and the same error appears when receiving a call. See Both examples. How can I fix?
Thanks
CJB
The Log shows for a Local call (trying to call ext 425 from ext 200):
1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 parsing [default->local_extension] continue=false 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Regex (PASS) [local_extension] destination_number(425) =~ /(^\d{2,7}$)/ break=on-false 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(dialed_extension=425) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action export(dialed_extension=425) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action limit(hash ${domain_name} 425 ${limit_max} ${limit_destination}) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action bind_meta_app(1 b s execute_extension::dx XML features) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action bind_meta_app(2 ab s record_session::/usr/local/freeswitch/recordings/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.wav) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action bind_meta_app(3 b s execute_extension::cf XML features) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(hangup_after_bridge=true) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(continue_on_fail=true) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)}) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid}) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action bridge(user/${user_data(${destination_number}@${domain_name} attr id)}@${domain_name}) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action answer() 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action sleep(1000) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(voicemail_action=save) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(voicemail_id=425) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action set(voicemail_profile=default) 1fd46aaa-c984-11e3-9e46-451b3981da25 Dialplan: sofia/internal/200@192.168.20.153 Action lua(app.lua voicemail) 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/200@192.168.20.153) State Change CS_ROUTING -> CS_EXECUTE 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_session.c:1351 Send signalsofia/internal/200@192.168.20.153 [BREAK] 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:471 (sofia/internal/200@192.168.20.153) State ROUTING going to sleep 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/200@192.168.20.153) Running State Change CS_EXECUTE 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/200@192.168.20.153) State EXECUTE 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] mod_sofia.c:243sofia/internal/200@192.168.20.153 SOFIA EXECUTE 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:209sofia/internal/200@192.168.20.153 Standard EXECUTE 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [ERR] switch_core_session.c:2626 Invalid Application 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [NOTICE] switch_core_session.c:2627 Hangupsofia/internal/200@192.168.20.153 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER] 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_channel.c:3187 Send signalsofia/internal/200@192.168.20.153 [KILL] 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_session.c:1351 Send signalsofia/internal/200@192.168.20.153 [BREAK] 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/200@192.168.20.153) State EXECUTE going to sleep 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/200@192.168.20.153) Running State Change CS_HANGUP 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/200@192.168.20.153) Callstate Change RINGING -> HANGUP 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/200@192.168.20.153) State HANGUP 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] mod_sofia.c:506 Channelsofia/internal/200@192.168.20.153 hanging up, cause: DESTINATION_OUT_OF_ORDER 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] mod_sofia.c:640 Responding to INVITE with: 502 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:48sofia/internal/200@192.168.20.153 Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/200@192.168.20.153) State HANGUP going to sleep 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:447 (sofia/internal/200@192.168.20.153) State Change CS_HANGUP -> CS_REPORTING 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_session.c:1351 Send signalsofia/internal/200@192.168.20.153 [BREAK] 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/200@192.168.20.153) Running State Change CS_REPORTING 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.018636 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/200@192.168.20.153) State REPORTING 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [DEBUG] switch_core_state_machine.c:92sofia/internal/200@192.168.20.153 Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/200@192.168.20.153) State REPORTING going to sleep 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [DEBUG] switch_core_state_machine.c:441 (sofia/internal/200@192.168.20.153) State Change CS_REPORTING -> CS_DESTROY 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [DEBUG] switch_core_session.c:1351 Send signalsofia/internal/200@192.168.20.153 [BREAK] 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [DEBUG] switch_core_session.c:1559 Session 7 (sofia/internal/200@192.168.20.153) Locked, Waiting on external entities 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [NOTICE] switch_core_session.c:1577 Session 7 (sofia/internal/200@192.168.20.153) Ended 1fd46aaa-c984-11e3-9e46-451b3981da25 2014-04-21 15:38:26.058686 [NOTICE] switch_core_session.c:1581 Close Channel sofia/internal/200@192.168.20.153 [CS_DESTROY]
The log shows for an external call:
698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 parsing [default->local_extension] continue=false 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Regex (PASS) [local_extension] destination_number(2020) =~ /(^\d{2,7}$)/ break=on-false 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(dialed_extension=2020) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action export(dialed_extension=2020) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action limit(hash ${domain_name} 2020 ${limit_max} ${limit_destination}) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action bind_meta_app(1 b s execute_extension::dx XML features) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action bind_meta_app(2 ab s record_session::/usr/local/freeswitch/recordings/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.wav) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action bind_meta_app(3 b s execute_extension::cf XML features) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(hangup_after_bridge=true) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(continue_on_fail=true) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)}) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid}) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action bridge(user/${user_data(${destination_number}@${domain_name} attr id)}@${domain_name}) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action answer() 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action sleep(1000) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(voicemail_action=save) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(voicemail_id=2020) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action set(voicemail_profile=default) 698ea576-c983-11e3-9e3e-451b3981da25 Dialplan: sofia/external/506855XXXX@72.55.158.153 Action lua(app.lua voicemail) 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:167 (sofia/external/506855XXXX@72.55.158.153) State Change CS_ROUTING -> CS_EXECUTE 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_session.c:1351 Send signalsofia/external/506855XXXX@72.55.158.153 [BREAK] 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:471 (sofia/external/506855XXXX@72.55.158.153) State ROUTING going to sleep 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:415 (sofia/external/506855XXXX@72.55.158.153) Running State Change CS_EXECUTE 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:478 (sofia/external/506855XXXX@72.55.158.153) State EXECUTE 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] mod_sofia.c:243sofia/external/506855XXXX@72.55.158.153 SOFIA EXECUTE 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:209sofia/external/506855XXXX@72.55.158.153 Standard EXECUTE 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [ERR] switch_core_session.c:2626 Invalid Application 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [NOTICE] switch_core_session.c:2627 Hangupsofia/external/506855XXXX@72.55.158.153 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER] 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_channel.c:3187 Send signalsofia/external/506855XXXX@72.55.158.153 [KILL] 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_session.c:1351 Send signalsofia/external/506855XXXX@72.55.158.153 [BREAK] 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:478 (sofia/external/506855XXXX@72.55.158.153) State EXECUTE going to sleep 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:415 (sofia/external/506855XXXX@72.55.158.153) Running State Change CS_HANGUP 698ea576-c983-11e3-9e3e-451b3981da25 2014-04-21 15:33:20.018627 [DEBUG] switch_core_state_machine.c:678 (sofia/external/506855XXXX@72.55.158.153) Callstate Change RINGING -> HANGUP