Hi,
I wanted to bring to your attention an issue I’ve been encountering with SIP calls in our setup. We are using a 3CX PBX and Janus for SIP communication.
Recently, I set up a queue in 3CX and registered a SIP extension using Janus’s siptest.html. The issue arises when I attempt to make calls to the queue number.
For the first two attempts, everything works as expected, and I can receive the calls without any issues. However, on the third call attempt, I receive a “486 Busy Here” response, and the call is not successfully established.
I would appreciate your assistance in identifying the root cause of this problem and finding a solution to ensure that we can consistently receive calls to the queue without encountering the “486 Busy Here” response on the third attempt.
Please let me know if you need any additional information or logs to investigate this issue further. Your help in resolving this matter would be greatly appreciated.
Thank you for your attention to this matter.
nta_incoming_destroy: 0x7fc87c0143c0
[Thu Nov 2 18:18:58 2023] [1001][nua_i_invite]: 100 Trying
[Thu Nov 2 18:18:58 2023] Received offerless INVITE
[Thu Nov 2 18:18:58 2023] [1001] Call status change: [idle]-->[invited]
[Thu Nov 2 18:18:58 2023] Someone is inviting us in a call:
(no SDP)[Thu Nov 2 18:18:58 2023] [6561097387062684] Sending event to transport...
[Thu Nov 2 18:18:58 2023] >> Pushing event to peer: 0 (Success)
[Thu Nov 2 18:18:58 2023] [1001][nua_i_state]: 100 Trying, call state [received]
[Thu Nov 2 18:18:58 2023] [1001][nua_i_state]: 180 Ringing, call state [early]
[Thu Nov 2 18:19:03 2023] [1001][nua_i_cancel]: 200 OK
nta_incoming_destroy: 0x7fc87c010fd0
[Thu Nov 2 18:19:03 2023] [1001][nua_i_state]: 487 Request Terminated, call state [terminated]
[Thu Nov 2 18:19:03 2023] [1001] Call status change: [invited]-->[idle]
[Thu Nov 2 18:19:03 2023] [6561097387062684] Sending event to transport...
[Thu Nov 2 18:19:03 2023] >> Pushing event: 0 (Success)
[Thu Nov 2 18:19:03 2023] [1001][nua_i_terminated]: 487 Request Terminated
[Thu Nov 2 18:19:03 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:03 2023] Transport task pool, serving request
[Thu Nov 2 18:19:03 2023] [6561097387062684] There's a message for JANUS SIP plugin
[Thu Nov 2 18:19:03 2023] [ERR] [plugins/janus_sip.c:janus_sip_handler:4157] Wrong state (not established/inviting? status=idle)
[Thu Nov 2 18:19:03 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:03 2023] Transport task pool, serving request
[Thu Nov 2 18:19:03 2023] [6561097387062684] There's a message for JANUS SIP plugin
[Thu Nov 2 18:19:03 2023] [ERR] [plugins/janus_sip.c:janus_sip_handler:4157] Wrong state (not established/inviting? status=idle)
[Thu Nov 2 18:19:03 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:03 2023] Transport task pool, serving request
[Thu Nov 2 18:19:03 2023] [6561097387062684] There's a message for JANUS SIP plugin
[Thu Nov 2 18:19:03 2023] [ERR] [plugins/janus_sip.c:janus_sip_handler:4157] Wrong state (not established/inviting? status=idle)
[Thu Nov 2 18:19:03 2023] [6561097387062684] Plugin asked to hangup PeerConnection: sending alert
[Thu Nov 2 18:19:03 2023] [6561097387062684] Hanging up PeerConnection because of a Close PC
[Thu Nov 2 18:19:03 2023] [6561097387062684] Telling the plugin about the hangup (JANUS SIP plugin)
[Thu Nov 2 18:19:03 2023] [janus.plugin.sip-0x7fc8a40033c0] No WebRTC media anymore
[Thu Nov 2 18:19:03 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:03 2023] Got a keep-alive on session 396827330696559
incoming_reclaim: 0x7fc87c010fd0
[Thu Nov 2 18:19:09 2023] [1001][nua_i_invite]: 100 Trying
[Thu Nov 2 18:19:09 2023] Received offerless INVITE
[Thu Nov 2 18:19:09 2023] [1001] Call status change: [idle]-->[invited]
[Thu Nov 2 18:19:09 2023] Someone is inviting us in a call:
(no SDP)[Thu Nov 2 18:19:09 2023] [6561097387062684] Sending event to transport...
[Thu Nov 2 18:19:09 2023] >> Pushing event to peer: 0 (Success)
[Thu Nov 2 18:19:09 2023] [1001][nua_i_state]: 100 Trying, call state [received]
[Thu Nov 2 18:19:09 2023] [1001][nua_i_state]: 180 Ringing, call state [early]
nta_incoming_destroy: 0x7fc87c0109f0
[Thu Nov 2 18:19:19 2023] [1001][nua_i_cancel]: 200 OK
[Thu Nov 2 18:19:19 2023] [1001][nua_i_state]: 487 Request Terminated, call state [terminated]
[Thu Nov 2 18:19:19 2023] [1001] Call status change: [invited]-->[idle]
[Thu Nov 2 18:19:19 2023] [6561097387062684] Sending event to transport...
[Thu Nov 2 18:19:19 2023] >> Pushing event: 0 (Success)
[Thu Nov 2 18:19:19 2023] [1001][nua_i_terminated]: 487 Request Terminated
[Thu Nov 2 18:19:19 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:19 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:19 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:19 2023] Transport task pool, serving request
[Thu Nov 2 18:19:19 2023] [6561097387062684] There's a message for JANUS SIP plugin
[Thu Nov 2 18:19:19 2023] [ERR] [plugins/janus_sip.c:janus_sip_handler:4157] Wrong state (not established/inviting? status=idle)
[Thu Nov 2 18:19:19 2023] Transport task pool, serving request
[Thu Nov 2 18:19:19 2023] Transport task pool, serving request
[Thu Nov 2 18:19:19 2023] [6561097387062684] There's a message for JANUS SIP plugin
[Thu Nov 2 18:19:19 2023] [6561097387062684] There's a message for JANUS SIP plugin
[Thu Nov 2 18:19:19 2023] [ERR] [plugins/janus_sip.c:janus_sip_handler:4157] Wrong state (not established/inviting? status=idle)
[Thu Nov 2 18:19:19 2023] [ERR] [plugins/janus_sip.c:janus_sip_handler:4157] Wrong state (not established/inviting? status=idle)
incoming_reclaim: 0x7fc87c0143c0
incoming_reclaim: 0x7fc87c0109f0
[Thu Nov 2 18:19:24 2023] [1001][nua_i_invite]: 100 Trying
[Thu Nov 2 18:19:24 2023] Busy... maybe a helper can help?
[Thu Nov 2 18:19:24 2023] Already in a call (busy, status=idle)
[Thu Nov 2 18:19:24 2023] [6561097387062684] Sending event to transport...
[Thu Nov 2 18:19:24 2023] >> Pushing event to peer: 0 (Success)
nta_incoming_destroy: 0x7fc87c01a080
[Thu Nov 2 18:19:24 2023] [1001][nua_i_state]: 100 Trying, call state [received]
[Thu Nov 2 18:19:24 2023] [1001][nua_i_state]: 486 Busy Here, call state [terminated]
[Thu Nov 2 18:19:24 2023] [6561097387062684] Sending event to transport...
[Thu Nov 2 18:19:24 2023] >> Pushing event: 0 (Success)
[Thu Nov 2 18:19:24 2023] [1001][nua_i_terminated]: 486 Busy Here
[Thu Nov 2 18:19:28 2023] Got a Janus API request from janus.transport.websockets (0x7fc8a4003180)
[Thu Nov 2 18:19:28 2023] Got a keep-alive on session 396827330696559
incoming_reclaim: 0x7fc87c01a080