SOLVED Call forwarding issues

Status
Not open for further replies.
A

albert-g

Member
Aug 11, 2020
85
2
8
44
There are problems with call forwarding (extension forwarding and ring group forwarding). I set to forward call to external number, when I calling to this ring-group extension from inside (from other extension), forwarding is working, but when I am calling from outside number to the number which should go to ring-group, which will redirect to another external number, it is not working. So when it is:
local extension, calling -> ring-group extension -> redirect to external : is ok
outside number, calling -> outside number which direct to ring-group -> redirect to external: is not working

Ring group config looks like this:
1608119603157.png

The log contains "CALL_REJECTED"

Less: 
c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:40 sofia/external/EXTERNAL_NUMBER Standard INIT
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:48 (sofia/external/EXTERNAL_NUMBER) State Change CS_INIT -> CS_ROUTING
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:628 (sofia/external/EXTERNAL_NUMBER) State INIT going to sleep
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:585 (sofia/external/EXTERNAL_NUMBER) Running State Change CS_ROUTING (Cur 2 Tot 116)
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] sofia.c:7326 Channel sofia/external/EXTERNAL_NUMBER entering state [calling][0]
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:644 (sofia/external/EXTERNAL_NUMBER) State ROUTING
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] mod_sofia.c:154 sofia/external/EXTERNAL_NUMBER SOFIA ROUTING
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_ivr_originate.c:67 (sofia/external/EXTERNAL_NUMBER) State Change CS_ROUTING -> CS_CONSUME_MEDIA
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:644 (sofia/external/EXTERNAL_NUMBER) State ROUTING going to sleep
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:585 (sofia/external/EXTERNAL_NUMBER) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 116)
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:663 (sofia/external/EXTERNAL_NUMBER) State CONSUME_MEDIA
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.683675 [DEBUG] switch_core_state_machine.c:663 (sofia/external/EXTERNAL_NUMBER) State CONSUME_MEDIA going to sleep
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.743679 [DEBUG] sofia.c:7326 Channel sofia/external/EXTERNAL_NUMBER entering state [calling][0]
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] sofia.c:6501 Remote Reason: 21
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] sofia.c:7326 Channel sofia/external/EXTERNAL_NUMBER entering state [terminated][403]
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [NOTICE] sofia.c:8559 Hangup sofia/external/EXTERNAL_NUMBER [CS_CONSUME_MEDIA] [CALL_REJECTED]
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:585 (sofia/external/EXTERNAL_NUMBER) Running State Change CS_HANGUP (Cur 2 Tot 116)
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:848 (sofia/external/EXTERNAL_NUMBER) Callstate Change DOWN -> HANGUP
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:850 (sofia/external/EXTERNAL_NUMBER) State HANGUP
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] mod_sofia.c:453 Channel sofia/external/EXTERNAL_NUMBER hanging up, cause: CALL_REJECTED
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:60 sofia/external/EXTERNAL_NUMBER Standard HANGUP, cause: CALL_REJECTED
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:850 (sofia/external/EXTERNAL_NUMBER) State HANGUP going to sleep
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:620 (sofia/external/EXTERNAL_NUMBER) State Change CS_HANGUP -> CS_REPORTING
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:585 (sofia/external/EXTERNAL_NUMBER) Running State Change CS_REPORTING (Cur 2 Tot 116)
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:936 (sofia/external/EXTERNAL_NUMBER) State REPORTING
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:174 sofia/external/EXTERNAL_NUMBER Standard REPORTING, cause: CALL_REJECTED
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:936 (sofia/external/EXTERNAL_NUMBER) State REPORTING going to sleep
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:611 (sofia/external/EXTERNAL_NUMBER) State Change CS_REPORTING -> CS_DESTROY
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_session.c:1726 Session 116 (sofia/external/EXTERNAL_NUMBER) Locked, Waiting on external entities
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [NOTICE] switch_core_session.c:1744 Session 116 (sofia/external/EXTERNAL_NUMBER) Ended
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/EXTERNAL_NUMBER [CS_DESTROY]
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:739 (sofia/external/EXTERNAL_NUMBER) Running State Change CS_DESTROY (Cur 1 Tot 116)
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:749 (sofia/external/EXTERNAL_NUMBER) State DESTROY
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] mod_sofia.c:364 sofia/external/EXTERNAL_NUMBER SOFIA DESTROY
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:181 sofia/external/EXTERNAL_NUMBER Standard DESTROY
9c54ffdc-4a6f-4014-8598-cdc9de88961b 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:749 (sofia/external/EXTERNAL_NUMBER) State DESTROY going to sleep
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [INFO] mod_dptools.c:3631 Originate Failed.  Cause: CALL_REJECTED
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [NOTICE] switch_core_state_machine.c:386 sofia/external/anonymous@anonymous.invalid has executed the last dialplan instruction, hanging up.
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/external/anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING]
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:651 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:585 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_HANGUP (Cur 1 Tot 116)
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:848 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> HANGUP
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:850 (sofia/external/anonymous@anonymous.invalid) State HANGUP
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] mod_sofia.c:447 sofia/external/anonymous@anonymous.invalid Overriding SIP cause 480 with 403 from the other leg
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] mod_sofia.c:453 Channel sofia/external/anonymous@anonymous.invalid hanging up, cause: NORMAL_CLEARING
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 403
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:60 sofia/external/anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:850 (sofia/external/anonymous@anonymous.invalid) State HANGUP going to sleep
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:620 (sofia/external/anonymous@anonymous.invalid) State Change CS_HANGUP -> CS_REPORTING
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:585 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_REPORTING (Cur 1 Tot 116)
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.783679 [DEBUG] switch_core_state_machine.c:936 (sofia/external/anonymous@anonymous.invalid) State REPORTING
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:174 sofia/external/anonymous@anonymous.invalid Standard REPORTING, cause: NORMAL_CLEARING
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:936 (sofia/external/anonymous@anonymous.invalid) State REPORTING going to sleep
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:611 (sofia/external/anonymous@anonymous.invalid) State Change CS_REPORTING -> CS_DESTROY
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_session.c:1726 Session 115 (sofia/external/anonymous@anonymous.invalid) Locked, Waiting on external entities
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [NOTICE] switch_core_session.c:1744 Session 115 (sofia/external/anonymous@anonymous.invalid) Ended
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/anonymous@anonymous.invalid [CS_DESTROY]
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:739 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_DESTROY (Cur 0 Tot 116)
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:749 (sofia/external/anonymous@anonymous.invalid) State DESTROY
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] mod_sofia.c:364 sofia/external/anonymous@anonymous.invalid SOFIA DESTROY
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:181 sofia/external/anonymous@anonymous.invalid Standard DESTROY
ed565dd1-1026-4eb5-9c94-3a076c8fdb1f 2020-12-16 12:49:55.843681 [DEBUG] switch_core_state_machine.c:749 (sofia/external/anonymous@anonymous.invalid) State DESTROY going to sleep
P.S. I masked external forwarding number with "EXTERNAL_NUMBER"
 
Adrian Fretwell

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,498
413
83
Just guessing here. This may be to do with your gateway and what your SIP provider is expecting to see. Sometimes it is necessary to set the sip_invite_domain variable in the outbound route:

Screenshot from 2020-12-16 17-58-40.png
 
A

albert-g

Member
Aug 11, 2020
85
2
8
44
Tried with this config, but nothing changed, let me show you SNGREP result, maybe it more informative,
This one, when I am calling from extension 20 to the external number, and which is ok:

INVITE :::: 20@my_internal_domain :::: external_number@my_internal_domain ::: 9 ::: 192.168.11.yy:63020 ::: 192.168.11.xx:5060
INVITE ::: outbound_caller_number@FPBX_public_IP ::: external_number@siptrunk.ov ::: 10 192.168.11.xx:5080 ::: siptrunk_ip:5060

This one, when I am calling from outside, to external number which is target to the same extension 20, which should redirect to the same external number which I used on previous scenario:

INVITE ::: anonymous@anonymous.inval (???) ::: inbound_external_number@10.7.1.60(?? dont know what IP) ::: 4 ::: siptrunk_ip:5060 :: 192.168.11.xx:5080
INVITE ::: anonymous@FPBX_public_IP ::: external_redirectied_number@siptrunk.ov ::: 8 ::: 192.168.11.xx:5080 ::: siptrunk_ip:5060
 
Adrian Fretwell

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,498
413
83
This could be something your SIP Trunk provider doesn't like about the second INVITE. May be it's the "anonymous" As a diagnostic test you could try setting your outbound caller number to anonymous for extension 20 and re-try the first INVITE.

With packet captures it is always helpful to see the entire capture.
 
A

albert-g

Member
Aug 11, 2020
85
2
8
44
Anonymous couldn't help. but I found another interesting thing, on sngrep detail I saw this...

1608213175036.png
 
Adrian Fretwell

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,498
413
83
I would definately start by looking at the "403 Too many simultaneous". Again, looking at the headers in that 403 message may help you further.
 
  • Like
Reactions: albert-g
A

albert-g

Member
Aug 11, 2020
85
2
8
44
The problem was solved, and it was related to SIP provider, since there was only one channel activated, after activating 2nd one, this was fixed.
Thanks a lot for your advises.
 
Status
Not open for further replies.
Top Bottom