Internal calls on the same phone don't work in a fresh FusionPbx installation

Status
Not open for further replies.
D

Daniele

Member
Feb 1, 2017
45
0
6
43
I just installed a fresh fusionpbx in a local machine with Debian 8. I was able to register 3 extensions: 2 on a phone Grandstream GXP2100 and 1 on another phone GXP1782.

On the first phone I registered extension 100 and 102 on the first phone, extension 101 on second phone.

I'm able to call 100 -> 101, 102 -> 101, but I'm not able to make calls between 100 and 102 in both directions.

These is how phones are registered:

User Agent LAN IP IP Port Hostname Status Ping
100@192.168.185.123 Grandstream GXP2100 0.0.0.0 192.168.185.202 192.168.185.202 5062 pbx Registered(UDP)(unknown) exp(2017-06-29 11:02:10) expsecs(2767) 59.01

101@192.168.185.123 Grandstream GXP1782 1.0.1.8 192.168.185.206 192.168.185.206 5062 pbx Registered(UDP)(unknown) exp(2017-06-29 11:00:13) expsecs(2650) 4.83

102@192.168.185.123 Grandstream GXP2100 0.0.0.0 192.168.185.202 192.168.185.202 5064 pbx Registered(UDP)(unknown) exp(2017-06-29 11:02:11) expsecs(2768) 0.00
Click to expand...

Like you can see extensions registered on the same phone use different ports (5062 and 5064). Here logs of what happened:

Code: 
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100@192.168.185.123) State DESTROY going to sleep
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:181 sofia/internal/100@192.168.185.123 Standard DESTROY
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] mod_sofia.c:343 sofia/internal/100@192.168.185.123 SOFIA DESTROY
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100@192.168.185.123) State DESTROY
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/100@192.168.185.123) Running State Change CS_DESTROY (Cur 0 Tot 16)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/100@192.168.185.123 [CS_DESTROY]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [NOTICE] switch_core_session.c:1682 Session 15 (sofia/internal/100@192.168.185.123) Ended
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_session.c:1664 Session 15 (sofia/internal/100@192.168.185.123) Locked, Waiting on external entities
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/100@192.168.185.123) State Change CS_REPORTING -> CS_DESTROY
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100@192.168.185.123) State REPORTING going to sleep
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.862837 [DEBUG] switch_core_state_machine.c:174 sofia/internal/100@192.168.185.123 Standard REPORTING, cause: NORMAL_CLEARING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100@192.168.185.123) State REPORTING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@192.168.185.123) Running State Change CS_REPORTING (Cur 1 Tot 16)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/100@192.168.185.123) State Change CS_HANGUP -> CS_REPORTING
95aea633-788c-4fb1-81c5-a259d397e3ab
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup):
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100@192.168.185.123) State HANGUP going to sleep
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:60 sofia/internal/100@192.168.185.123 Standard HANGUP, cause: NORMAL_CLEARING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] mod_sofia.c:438 Channel sofia/internal/100@192.168.185.123 hanging up, cause: NORMAL_CLEARING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] mod_sofia.c:432 sofia/internal/100@192.168.185.123 Overriding SIP cause 480 with 486 from the other leg
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100@192.168.185.123) State HANGUP
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/100@192.168.185.123) Callstate Change ACTIVE -> HANGUP
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@192.168.185.123) Running State Change CS_HANGUP (Cur 1 Tot 16)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100@192.168.185.123) State EXECUTE going to sleep
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_session.c:2814 sofia/internal/100@192.168.185.123 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_cpp.cpp:1112 sofia/internal/100@192.168.185.123 destroy/unlink session from object
2017-06-29 10:17:14.702836 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f73c010aec0 released.
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_cpp.cpp:721 CoreSession::hangup
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_core_session.c:2814 sofia/internal/100@192.168.185.123 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-no_answer_no_vm.wav
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [DEBUG] mod_hash.c:297 Usage for 192.168.185.123_102 is now 0
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:14.702836 [NOTICE] sofia.c:1012 Hangup sofia/internal/100@192.168.185.123 [CS_EXECUTE] [NORMAL_CLEARING]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:13.802833 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 playback(/usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-no_answer_no_vm.wav)
2017-06-29 10:17:13.782833 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f73c010aec0 Connected.
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 lua(app.lua voicemail)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:13.762837 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [voicemail_profile]=[default]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(voicemail_profile=default)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:13.762837 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [voicemail_id]=[102]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(voicemail_id=102)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:13.762837 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [voicemail_action]=[save]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(voicemail_action=save)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:13.422836 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.942831 [DEBUG] sofia.c:7048 Channel sofia/internal/100@192.168.185.123 entering state [ready][200]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 sleep(1000)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] sofia.c:7048 Channel sofia/internal/100@192.168.185.123 entering state [completed][200]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_channel.c:3773 (sofia/internal/100@192.168.185.123) Callstate Change RINGING -> ACTIVE
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/100@192.168.185.123] has been answered
95aea633-788c-4fb1-81c5-a259d397e3ab
95aea633-788c-4fb1-81c5-a259d397e3ab a=sendrecv
95aea633-788c-4fb1-81c5-a259d397e3ab a=ptime:20
95aea633-788c-4fb1-81c5-a259d397e3ab a=fmtp:101 0-16
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:101 telephone-event/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:8 PCMA/8000
95aea633-788c-4fb1-81c5-a259d397e3ab m=audio 18536 RTP/AVP 8 101
95aea633-788c-4fb1-81c5-a259d397e3ab t=0 0
95aea633-788c-4fb1-81c5-a259d397e3ab c=IN IP4 192.168.185.123
95aea633-788c-4fb1-81c5-a259d397e3ab s=FreeSWITCH
95aea633-788c-4fb1-81c5-a259d397e3ab o=FreeSWITCH 1498705696 1498705697 IN IP4 192.168.185.123
95aea633-788c-4fb1-81c5-a259d397e3ab v=0
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/100@192.168.185.123:
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_core_media.c:7205 sofia/internal/100@192.168.185.123 Set rtp dtmf delay to 40
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_core_media.c:7182 sofia/internal/100@192.168.185.123 Set 2833 dtmf receive payload to 101
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_core_media.c:7175 sofia/internal/100@192.168.185.123 Set 2833 dtmf send payload to 101
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/internal/100@192.168.185.123] 192.168.185.123 port 18536 -> 192.168.185.202 port 10004 codec: 8 ms: 20
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 answer()
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_cpp.cpp:1112 sofia/internal/100@192.168.185.123 destroy/unlink session from object
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(originate_disposition, USER_BUSY)
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 lua(app.lua failure_handler)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [INFO] mod_dptools.c:3418 Originate Failed. Cause: USER_BUSY
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 17 [USER_BUSY]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [user] cause: [USER_BUSY]
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/102@192.168.185.202:5064) State DESTROY going to sleep
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [DEBUG] switch_core_state_machine.c:181 sofia/internal/102@192.168.185.202:5064 Standard DESTROY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [DEBUG] mod_sofia.c:343 sofia/internal/102@192.168.185.202:5064 SOFIA DESTROY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/102@192.168.185.202:5064) State DESTROY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/102@192.168.185.202:5064) Running State Change CS_DESTROY (Cur 1 Tot 16)
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/102@192.168.185.202:5064 [CS_DESTROY]
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.762829 [NOTICE] switch_core_session.c:1682 Session 16 (sofia/internal/102@192.168.185.202:5064) Ended
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.762829 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 17 [USER_BUSY]
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_session.c:1664 Session 16 (sofia/internal/102@192.168.185.202:5064) Locked, Waiting on external entities
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/102@192.168.185.202:5064) State Change CS_REPORTING -> CS_DESTROY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/102@192.168.185.202:5064) State REPORTING going to sleep
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:174 sofia/internal/102@192.168.185.202:5064 Standard REPORTING, cause: USER_BUSY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/102@192.168.185.202:5064) State REPORTING
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/102@192.168.185.202:5064) Running State Change CS_REPORTING (Cur 2 Tot 16)
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/102@192.168.185.202:5064) State Change CS_HANGUP -> CS_REPORTING
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/102@192.168.185.202:5064) State HANGUP going to sleep
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:60 sofia/internal/102@192.168.185.202:5064 Standard HANGUP, cause: USER_BUSY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] mod_sofia.c:438 Channel sofia/internal/102@192.168.185.202:5064 hanging up, cause: USER_BUSY
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/102@192.168.185.202:5064) State HANGUP
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/102@192.168.185.202:5064) Callstate Change DOWN -> HANGUP
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/102@192.168.185.202:5064) Running State Change CS_HANGUP (Cur 2 Tot 16)
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [NOTICE] sofia.c:8237 Hangup sofia/internal/102@192.168.185.202:5064 [CS_CONSUME_MEDIA] [USER_BUSY]
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.742828 [DEBUG] sofia.c:7048 Channel sofia/internal/102@192.168.185.202:5064 entering state [terminated][486]
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/102@192.168.185.202:5064) State CONSUME_MEDIA going to sleep
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/102@192.168.185.202:5064) State CONSUME_MEDIA
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/102@192.168.185.202:5064) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 16)
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/102@192.168.185.202:5064) State ROUTING going to sleep
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/102@192.168.185.202:5064) State Change CS_ROUTING -> CS_CONSUME_MEDIA
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] mod_sofia.c:143 sofia/internal/102@192.168.185.202:5064 SOFIA ROUTING
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/102@192.168.185.202:5064) State ROUTING
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] sofia.c:7048 Channel sofia/internal/102@192.168.185.202:5064 entering state [calling][0]
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/102@192.168.185.202:5064) Running State Change CS_ROUTING (Cur 2 Tot 16)
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/102@192.168.185.202:5064) State INIT going to sleep
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/102@192.168.185.202:5064) State Change CS_INIT -> CS_ROUTING
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:40 sofia/internal/102@192.168.185.202:5064 Standard INIT
886b78ec-b614-4537-9e11-d6062e48a0fc
886b78ec-b614-4537-9e11-d6062e48a0fc a=sendrecv
886b78ec-b614-4537-9e11-d6062e48a0fc a=ptime:20
886b78ec-b614-4537-9e11-d6062e48a0fc a=rtpmap:13 CN/8000
886b78ec-b614-4537-9e11-d6062e48a0fc a=fmtp:101 0-16
886b78ec-b614-4537-9e11-d6062e48a0fc a=rtpmap:101 telephone-event/8000
886b78ec-b614-4537-9e11-d6062e48a0fc a=rtpmap:3 GSM/8000
886b78ec-b614-4537-9e11-d6062e48a0fc a=rtpmap:9 G722/8000
886b78ec-b614-4537-9e11-d6062e48a0fc a=rtpmap:0 PCMU/8000
886b78ec-b614-4537-9e11-d6062e48a0fc a=rtpmap:8 PCMA/8000
886b78ec-b614-4537-9e11-d6062e48a0fc m=audio 27034 RTP/AVP 8 0 9 3 101 13
886b78ec-b614-4537-9e11-d6062e48a0fc t=0 0
886b78ec-b614-4537-9e11-d6062e48a0fc c=IN IP4 192.168.185.123
886b78ec-b614-4537-9e11-d6062e48a0fc s=FreeSWITCH
886b78ec-b614-4537-9e11-d6062e48a0fc o=FreeSWITCH 1498697198 1498697199 IN IP4 192.168.185.123
886b78ec-b614-4537-9e11-d6062e48a0fc v=0
886b78ec-b614-4537-9e11-d6062e48a0fc Local SDP:
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] sofia_glue.c:1295 sofia/internal/102@192.168.185.202:5064 sending invite version: 1.6.18 -35-6e79667 64bit
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] mod_sofia.c:90 sofia/internal/102@192.168.185.202:5064 SOFIA INIT
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/102@192.168.185.202:5064) State INIT
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/102@192.168.185.202:5064) Running State Change CS_INIT (Cur 2 Tot 16)
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [DEBUG] mod_sofia.c:4819 (sofia/internal/102@192.168.185.202:5064) State Change CS_NEW -> CS_INIT
886b78ec-b614-4537-9e11-d6062e48a0fc 2017-06-29 10:17:12.522830 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/102@192.168.185.202:5064 [886b78ec-b614-4537-9e11-d6062e48a0fc]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [domain_name]=[192.168.185.123] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [origination_callee_id_name]=[102] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [dialed_extension]=[102] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [domain_name]=[192.168.185.123] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [domain_name]=[192.168.185.123] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [origination_callee_id_name]=[102] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [dialed_extension]=[102] to event
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1250 sofia/internal/100@192.168.185.123 EXPORTING[export_vars] [domain_name]=[192.168.185.123] to event
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 bridge(user/102@192.168.185.123)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.522830 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[192.168.185.123]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 export(domain_name=192.168.185.123)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [api_hangup_hook]=[lua app.lua hangup]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(api_hangup_hook=lua app.lua hangup)
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 hash(insert/192.168.185.123-last_dial//95aea633-788c-4fb1-81c5-a259d397e3ab)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [called_party_call_group]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(called_party_call_group=)
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 hash(insert/192.168.185.123-last_dial_ext/102/95aea633-788c-4fb1-81c5-a259d397e3ab)
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 hash(insert/192.168.185.123-call_return/102/100)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [continue_on_fail]=[true]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(continue_on_fail=true)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [hangup_after_bridge]=[true]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(hangup_after_bridge=true)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_hash.c:196 Usage for 192.168.185.123_102 is now 1/5
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] switch_limit.c:126 incr called: 192.168.185.123_102 max:5, interval:0
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 limit(hash 192.168.185.123 102 5 error/user_busy)
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 eval(not_secure)
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 hash(insert/192.168.185.123-last_dial/100/102)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [RFC2822_DATE]=[Thu, 29 Jun 2017 10:17:12 +0200]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(RFC2822_DATE=Thu, 29 Jun 2017 10:17:12 +0200)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[102]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 export(origination_callee_id_name=102)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [call_direction]=[local]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(call_direction=local)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [missed_call_data]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(missed_call_data=)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [missed_call_app]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(missed_call_app=)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.502832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [call_timeout]=[30]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(call_timeout=30)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.482829 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [do_not_disturb]=[false]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(do_not_disturb=false)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.482829 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_user_not_registered_destination]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_user_not_registered_destination=)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.482829 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_user_not_registered_enabled]=[false]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_user_not_registered_enabled=false)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.482829 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_no_answer_destination]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_no_answer_destination=)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.462835 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_no_answer_enabled]=[false]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_no_answer_enabled=false)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.462835 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_busy_destination]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_busy_destination=)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.462835 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_busy_enabled]=[false]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_busy_enabled=false)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.462835 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_all_destination]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_all_destination=)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.462835 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [forward_all_enabled]=[false]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(forward_all_enabled=false)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [hold_music]=[local_stream://default]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(hold_music=local_stream://default)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [extension_uuid]=[58cf6ce6-83f4-4334-addc-0359332df8fc]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(extension_uuid=58cf6ce6-83f4-4334-addc-0359332df8fc)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] switch_core_state_machine.c:328 sofia/internal/100@192.168.185.123 Standard EXECUTE
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] mod_sofia.c:198 sofia/internal/100@192.168.185.123 SOFIA EXECUTE
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100@192.168.185.123) State EXECUTE
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@192.168.185.123) Running State Change CS_EXECUTE (Cur 1 Tot 15)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100@192.168.185.123) State ROUTING going to sleep
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/100@192.168.185.123) State Change CS_ROUTING -> CS_EXECUTE
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action lua(app.lua voicemail)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(voicemail_profile=default)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(voicemail_id=${destination_number})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(voicemail_action=save)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action sleep(1000)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action answer()
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->voicemail] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action lua(app.lua failure_handler)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action bridge(user/${destination_number}@${domain_name})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action export(domain_name=${domain_name})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(api_hangup_hook=lua app.lua hangup)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(continue_on_fail=true)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(hangup_after_bridge=true)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [local_extension] () =~ // break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[102]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 export(dialed_extension=102)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action export(dialed_extension=${destination_number}) INLINE
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->local_extension] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->call_screen] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [talking clock date] destination_number(102) =~ /^\*9171$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->talking clock date] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [talking clock time] destination_number(102) =~ /^\*9170$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->talking clock time] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [talking clock date and time] destination_number(102) =~ /^\*9172$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->talking clock date and time] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->clear_sip_auto_answer] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [follow-me] destination_number(102) =~ /^\*21$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->follow-me] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call-forward] destination_number(102) =~ /^\*74$/ break=on-true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call-forward] destination_number(102) =~ /^\*73$/ break=on-true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call-forward] destination_number(102) =~ /^\*72$/ break=on-true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->call-forward] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [do-not-disturb] destination_number(102) =~ /^\*79$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [do-not-disturb] destination_number(102) =~ /^\*78$|\*363$/ break=on-true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [do-not-disturb] destination_number(102) =~ /^\*77$/ break=on-true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->do-not-disturb] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [operator-forward] destination_number(102) =~ /^\*000$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->operator-forward] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [operator] destination_number(102) =~ /^0$|^operator$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->operator] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [valet_park] destination_number(102) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [valet_park] destination_number(102) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [valet_park] destination_number(102) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->valet_park] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [wake-up] destination_number(102) =~ /^\*(925)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->wake-up] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [directory] destination_number(102) =~ /^\*411$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->directory] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [recordings] destination_number(102) =~ /^\*(732)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->recordings] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [hold_music] destination_number(102) =~ /^\*9664$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->hold_music] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [tone_stream] destination_number(102) =~ /^\*9198$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->tone_stream] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->is_secure] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [milliwatt] destination_number(102) =~ /^\*9197$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->milliwatt] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 ANTI-Action eval(not_secure)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->is_zrtp_secure] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [echo] destination_number(102) =~ /^\*9196$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->echo] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [delay_echo] destination_number(102) =~ /^\*9195$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->delay_echo] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [cf] destination_number(102) =~ /^cf$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->cf] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [vmain_user] destination_number(102) =~ /^\*97$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->vmain_user] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [is_transfer] destination_number(102) =~ /^is_transfer$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->is_transfer] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [xfer_vm] destination_number(102) =~ /^xfer_vm$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->xfer_vm] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [vmain] destination_number(102) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->vmain] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [send_to_voicemail] destination_number(102) =~ /^\*99(\d{2,10})$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->send_to_voicemail] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [Fax-to-email] destination_number(102) =~ /^400$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->Fax-to-email] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [extension-to-voicemail] destination_number(102) =~ /^100$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [extension-to-voicemail] username(100) =~ /^100$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->extension-to-voicemail] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [att_xfer] destination_number(102) =~ /^att_xfer$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->att_xfer] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [dx] destination_number(102) =~ /^dx$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->dx] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [intercept-ext-polycom] destination_number(102) =~ /^\*97(\d+)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->intercept-ext-polycom] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [intercept-ext] destination_number(102) =~ /^\*\*(\d+)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->intercept-ext] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [extension_queue] destination_number(102) =~ /^\*800(.*)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->extension_queue] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call_return] destination_number(102) =~ /^\*69$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->call_return] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call_privacy] destination_number(102) =~ /^\*67(\d+)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->call_privacy] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [eavesdrop] destination_number(102) =~ /^\*33(\d{2,7})$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->eavesdrop] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [page-extension] destination_number(102) =~ /^\*8(\d{2,7})$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->page-extension] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [group-intercept] destination_number(102) =~ /^\*8$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->group-intercept] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [Coda-operatori] destination_number(102) =~ /^700$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [Coda-operatori] ${caller_id_name}(100) =~ /^([^#]+#)(.*)$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->Coda-operatori] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [agent_status_id] destination_number(102) =~ /^\*23$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->agent_status_id] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [agent_status] destination_number(102) =~ /^\*22$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->agent_status] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [Tel_italia] destination_number(102) =~ /^(\d{6,17})$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->Tel_italia] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [speed_dial] destination_number(102) =~ /^\*0(.*)$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->speed_dial] continue=false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [redial] () =~ // break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [redial] destination_number(102) =~ /^(redial|\*870)$/ break=on-true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->redial] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [from_user_record]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(from_user_record=)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.442831 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [from_user_exists]=[true]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(from_user_exists=true)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.422832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [user_record]=[UNDEF]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(user_record=)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_record] () =~ // break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->user_record] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action export(origination_callee_id_name=${destination_number})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [variables] () =~ // break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->variables] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 ANTI-Action set(call_direction=local)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->call-direction] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.422832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [call_screen_enabled]=[false]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(call_screen_enabled=false)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid})
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.422832 [DEBUG] mod_dptools.c:1530 SET sofia/internal/100@192.168.185.123 [user_exists]=[true]
95aea633-788c-4fb1-81c5-a259d397e3ab EXECUTE sofia/internal/100@192.168.185.123 set(user_exists=true)
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 Regex (PASS) [user_exists] () =~ // break=on-false
95aea633-788c-4fb1-81c5-a259d397e3ab Dialplan: sofia/internal/100@192.168.185.123 parsing [192.168.185.123->user_exists] continue=true
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [INFO] mod_dialplan_xml.c:637 Processing 100 <100>->102 in context 192.168.185.123
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:236 sofia/internal/100@192.168.185.123 Standard ROUTING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] mod_sofia.c:143 sofia/internal/100@192.168.185.123 SOFIA ROUTING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100@192.168.185.123) State ROUTING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_channel.c:2249 (sofia/internal/100@192.168.185.123) Callstate Change DOWN -> RINGING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@192.168.185.123) Running State Change CS_ROUTING (Cur 1 Tot 15)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100@192.168.185.123) State INIT going to sleep
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/100@192.168.185.123) State Change CS_INIT -> CS_ROUTING
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:40 sofia/internal/100@192.168.185.123 Standard INIT
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] mod_sofia.c:90 sofia/internal/100@192.168.185.123 SOFIA INIT
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100@192.168.185.123) State INIT
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@192.168.185.123) Running State Change CS_INIT (Cur 1 Tot 15)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] sofia.c:7471 (sofia/internal/100@192.168.185.123) State Change CS_NEW -> CS_INIT
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4763 sofia/internal/100@192.168.185.123 Set 2833 dtmf send payload to 101 recv payload to 101
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_codec.c:111 sofia/internal/100@192.168.185.123 Original read codec set to PCMA:8
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:3057 Set Codec sofia/internal/100@192.168.185.123 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G726-32:2:8000:20:0:1]/[GSM:3:8000:20:13200:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G722:9:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:97:8000:20:0:1]/[GSM:3:8000:20:13200:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:97:8000:20:0:1]/[G722:9:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
95aea633-788c-4fb1-81c5-a259d397e3ab
95aea633-788c-4fb1-81c5-a259d397e3ab a=ptime:20
95aea633-788c-4fb1-81c5-a259d397e3ab a=fmtp:101 0-15
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:101 telephone-event/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:2 G726-32/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=fmtp:97 mode=30
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:97 iLBC/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:9 G722/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=fmtp:18 annexb=no
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:18 G729/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:0 PCMU/8000
95aea633-788c-4fb1-81c5-a259d397e3ab a=rtpmap:8 PCMA/8000
95aea633-788c-4fb1-81c5-a259d397e3ab m=audio 10004 RTP/AVP 8 0 18 9 97 2 101
95aea633-788c-4fb1-81c5-a259d397e3ab t=0 0
95aea633-788c-4fb1-81c5-a259d397e3ab c=IN IP4 192.168.185.202
95aea633-788c-4fb1-81c5-a259d397e3ab s=SIP Call
95aea633-788c-4fb1-81c5-a259d397e3ab o=100 8001 8000 IN IP4 192.168.185.202
95aea633-788c-4fb1-81c5-a259d397e3ab v=0
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] sofia.c:7058 Remote SDP:
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] sofia.c:7048 Channel sofia/internal/100@192.168.185.123 entering state [received][100]
2017-06-29 10:17:12.402837 [DEBUG] sofia.c:10008 IP 192.168.185.202 Rejected by acl "domains". Falling back to Digest auth.
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.402837 [DEBUG] sofia.c:9837 sofia/internal/100@192.168.185.123 receiving invite from 192.168.185.202:5062 version: 1.6.18 -35-6e79667 64bit
2017-06-29 10:17:12.402837 [DEBUG] sofia.c:2442 Re-attaching to session 95aea633-788c-4fb1-81c5-a259d397e3ab
2017-06-29 10:17:12.122837 [DEBUG] sofia.c:2334 detaching session 95aea633-788c-4fb1-81c5-a259d397e3ab
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.122837 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/100@192.168.185.123) State NEW
2017-06-29 10:17:12.122837 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [102@192.168.185.123] from ip 192.168.185.202
2017-06-29 10:17:12.122837 [DEBUG] sofia.c:10008 IP 192.168.185.202 Rejected by acl "domains". Falling back to Digest auth.
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.122837 [DEBUG] sofia.c:9837 sofia/internal/100@192.168.185.123 receiving invite from 192.168.185.202:5062 version: 1.6.18 -35-6e79667 64bit
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.122837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100@192.168.185.123) Running State Change CS_NEW (Cur 1 Tot 15)
95aea633-788c-4fb1-81c5-a259d397e3ab 2017-06-29 10:17:12.122837 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/100@192.168.185.123 [95aea633-788c-4fb1-81c5-a259d397e3ab]

Seems the extension reply with BUSY, but these phone permit to manage several calls at the same time and it works because I tried in the past with Asterisk.

Am I doing something wrong, or is it needed some specific settings to make this work?

Thanks
 
jsteel

jsteel

New Member
Feb 9, 2017
28
3
3
53
If I understand correctly, you have SIP registration for ext 100 and ext 102 on the same physical phone and you cannot dial 100-->102 or 102-->100? The phone is going to be busy when you go off-hook to dial. Why would you ever need to call from 100 to 102 or vice versa if they are registered on the same phone or are you really looking for BLF functionality?
 
D

Daniele

Member
Feb 1, 2017
45
0
6
43
Yes you got right. In my phone I can manage 4 SIP lines, so if I'm speaking I still can see others line blinking if someone calls. This works with Asterisk for example. Instead with Fusion+FS doesn't work. I receive immediatly a BUSY. If you say that this behaviour is normal, I'm fine.

I was worried to have make some mistakes.

Thanks
 
jsteel

jsteel

New Member
Feb 9, 2017
28
3
3
53
It sounds like you are talking about having BLF lights on your phone. For example, you are extension 100 and you want to see the status of extension 102 on your phone (i.e. a blinking light on your phone when extension 102 is ringing or a solid light when extension 102 is talking). This is not done with SIP registrations, you have to use Accounts->Devices, and populate the "Keys" field on the device that you want the BLF to be on.
 
D

Daniele

Member
Feb 1, 2017
45
0
6
43
Thanks for the clarification but I'm not talking about BLF. I'm speaking about what the phone calls "lines". Each line could be an extension of a different PBX for example.
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,044
565
113
Daniele, I'm still not sure what exactly you are trying to achieve here but what I am absolutely certain of is this: The phone is sending the 486 busy signal so whatever the problem is, its nothing at all to do with the PBX or its configuration. Though you have said no when jsteel mentioned it, I think what you are actually looking for is BLF, otherwise, I cannot work out why you would possibly want multiple extensions registered to the same phone.
 
Status
Not open for further replies.
Top Bottom