<div dir="ltr">Got some more info.<div><br></div><div><b>This is the first call that worked fine:</b></div><div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: Recebida asterisk - Tentando entrar na fila fila-1</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: using call flow 0x7fd851022270</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: cid=<"fila-1 551122223333"></div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:new_cc_call: created call 0x7fd85104c8b0</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:print_call_list: [] - 0x7fd85104c8b0</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: avg_call_duration=0.00 queued_calls=0 logedin_agents=1</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: ETA for new call(0x7fd85104c8b0) is 0</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:cc_call_state_machine: selecting AGENT 0x7fd851022a38 (<a href="mailto:1000@plat5.localdomain.com">1000@plat5.localdomain.com</a>)</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:cc_call_state_machine: moved to TOAGENT from 0, out=0x7fd851022a50</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: new destination for call(0x7fd85104c8b0) is <a href="http://sip:1000@plat5.localdomain.com:5060">sip:1000@plat5.localdomain.com:5060</a> (state=3)</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:call_center:set_call_leg: call 0x7fd85104c8b0 moving to <a href="http://sip:1000@plat5.localdomain.com:5060">sip:1000@plat5.localdomain.com:5060</a> , state 3</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:get_scenario_id_list: scenario id = call center</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:udh_to_uri: user:host:port [fila-1][10.10.10.10][5060]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: hash index [205]:</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: id [0]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: new tuple [0x7fd85104c980]->[205.0]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:t_newtran: transaction on entrance=(nil)</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=78</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:t_lookup_request: start searching: hash=21517, isACK=0</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:matching_3261: RFC3261 transaction matching failed</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:t_lookup_request: no transaction found</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:run_reqin_callbacks: trans=0x7fd85104cec0, callback type 1, id 0 entered</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:_shm_resize: resize(0) called</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:_reply_light: reply sent out. buf=0x7fd858dd32c8: SIP/2.0 1..., shmem=0x7fd8510501a0: SIP/2.0 1</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:_reply_light: finished</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:t_ref_cell: REF_UNSAFE:[0x7fd85104cec0] after is 2</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_entities:server_new: new server entity[0x7fd85104cc58]: callid=[<a href="http://7f0e81024a47462530aeb80b4345ddbc@10.10.10.10:5070">7f0e81024a47462530aeb80b4345ddbc@10.10.10.10:5070</a>] tag=[as5263b362] param=[205.0] dlg->uas_tran=[0x7fd85104cec0]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_create_new_entity: new entity type [0] [0x7fd851050400]->[B2B.94.465.1535569466]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2b_scenario_parse_uri: URI of type param</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2b_scenario_parse_uri: URI value taken from a parameter [<a href="http://sip:1000@plat5.localdomain.com:5060">sip:1000@plat5.localdomain.com:5060</a>]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2b_scenario_parse_uri: URI value = [<a href="http://sip:1000@plat5.localdomain.com:5060">sip:1000@plat5.localdomain.com:5060</a>]</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:MD5StringArray: MD5 calculated: e597e6de7f998db0ad2ccad6fec41d4e</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:b2b_entities:generate_tag: from_tag = e597e6de7f998db0ad2ccad6fec41d4e-7fe2</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:tm:t_uac: next_hop=<<a href="http://sip:1000@plat5.localdomain.com:5060">sip:1000@plat5.localdomain.com:5060</a>></div><div>Aug 29 16:04:27 plat5 /sbin/opensips[24890]: DBG:core:mk_proxy: doing DNS lookup...</div></div><div><br></div><div>Now, the only agent is in a call. </div><div><br></div><div><b>This is the second call that had the problem:</b></div><div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: Recebida asterisk - Tentando entrar na fila fila-1</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: using call flow 0x7fd851022270</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: cid=<"fila-1 551122223333"></div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:new_cc_call: created call 0x7fd8510524a8</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:print_call_list: [] - 0x7fd8510524a8</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:print_call_list: [205.0] - 0x7fd85104c8b0</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: avg_call_duration=0.00 queued_calls=0 logedin_agents=1</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: ETA for new call(0x7fd8510524a8) is 0</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:cc_call_state_machine: selecting QUEUE</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:cc_queue_push_call: QUEUE - adding call 0x7fd8510524a8</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:cc_queue_push_call: adding call on pos 0 (already 1 calls), l=(nil) h=(nil)</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: new destination for call(0x7fd8510524a8) is (state=2)</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: ** onhold++ Not to agent [0x7fd8510524a8]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:set_call_leg: call 0x7fd8510524a8 moving to , state 2</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:get_scenario_id_list: scenario id = call center</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:udh_to_uri: user:host:port [fila-1][10.10.10.10][5060]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: Fewer parameters, expected [1] received [0]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: hash index [205]:</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: id [0]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: id [1]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_insert_new: new tuple [0x7fd851056680]->[205.1]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:t_newtran: transaction on entrance=(nil)</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=78</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:t_lookup_request: start searching: hash=41757, isACK=0</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:matching_3261: RFC3261 transaction matching failed</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:t_lookup_request: no transaction found</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:run_reqin_callbacks: trans=0x7fd851046cb0, callback type 1, id 0 entered</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:parse_headers: flags=ffffffffffffffff</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:core:_shm_resize: resize(0) called</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:_reply_light: reply sent out. buf=0x7fd858dd2160: SIP/2.0 1..., shmem=0x7fd851052878: SIP/2.0 1</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:_reply_light: finished</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:t_ref_cell: REF_UNSAFE:[0x7fd851046cb0] after is 2</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:server_new: new server entity[0x7fd851056830]: callid=[<a href="http://060e63f73ff9d6c935047ce31296d0d9@10.10.10.10:5070">060e63f73ff9d6c935047ce31296d0d9@10.10.10.10:5070</a>] tag=[as19cfceed] param=[205.1] dlg->uas_tran=[0x7fd851046cb0]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_create_new_entity: new entity type [0] [0x7fd851052aa8]->[B2B.71.64.1535569477]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2b_scenario_parse_uri: URI of type param</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2b_scenario_parse_uri: URI value taken from a parameter []</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2b_scenario_parse_uri: URI value = []</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: ERROR:b2b_logic:b2b_process_scenario_init: Failed to get the value for the b2b client ruri</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_logic:b2bl_delete: Delete record [0x7fd851056680]->[205.1], hash_index=[205], local_index=[1]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_parse_key: hash_index = [71] - local_index= [64]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_search_htable_next_dlg: entering with start=(nil), table=0x7fd850ff26d0, hash=71, label=64</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_search_htable_next_dlg: searching callid 53[<a href="http://060e63f73ff9d6c935047ce31296d0d9@10.10.10.10:5070">060e63f73ff9d6c935047ce31296d0d9@10.10.10.10:5070</a>]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_search_htable_next_dlg: searching totag 20[B2B.71.64.1535569477]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_search_htable_next_dlg: searching fromtag 10[as19cfceed]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_search_htable_next_dlg: Match for server dlg [0x7fd851056830] dlg->uas_tran=[0x7fd851046cb0]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:b2b_entities:b2b_entity_delete: Deleted dlg [0x7fd851056830]->[B2B.71.64.1535569477] with dlginfo [0x7fd851055f80]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:tm:t_unref_cell: UNREF_UNSAFE: [0x7fd851046cb0] after is 1</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: ERROR:call_center:set_call_leg: failed to init new b2bua call (empty ID received)</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: ERROR:call_center:w_handle_call: failed to set new destination for call</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:w_handle_call: ** onhold-- Error [0x7fd8510524a8]</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:print_call_list: [205.0] - 0x7fd85104c8b0</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: DBG:call_center:free_cc_call: free call 0x7fd8510524a8, []</div><div>Aug 29 16:04:38 plat5 /sbin/opensips[24890]: Falha entrando na fila - erronum: -1</div></div><div><br></div><div><br></div></div><br><div class="gmail_quote"><div dir="ltr">On Mon, Aug 27, 2018 at 6:15 PM Daniel Zanutti <<a href="mailto:daniel.zanutti@gmail.com">daniel.zanutti@gmail.com</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr">Trying to configure the call center modules, but found a problem when there is no agents available.<div><br></div><div>If there is 1 agent available, call is sent to him with no problem:</div><div><br></div><div><div>Aug 27 18:11:00 plat5 /sbin/opensips[23575]: Recebida asterisk - Tentando entrar na fila fila-1</div><div>Aug 27 18:11:00 plat5 /sbin/opensips[23575]: Entrou na fila com sucesso (fila-1)!</div><div>Aug 27 18:11:01 plat5 /sbin/opensips[23569]: incoming reply</div></div><div><br></div><div>But when there is no agent available, opensips refuses:</div><div><div>Aug 27 18:11:07 plat5 /sbin/opensips[23569]: Recebida asterisk - Tentando entrar na fila fila-1</div><div>Aug 27 18:11:07 plat5 /sbin/opensips[23569]: ERROR:b2b_logic:b2b_process_scenario_init: Failed to get the value for the b2b client ruri</div><div>Aug 27 18:11:07 plat5 /sbin/opensips[23569]: ERROR:call_center:set_call_leg: failed to init new b2bua call (empty ID received)</div><div>Aug 27 18:11:07 plat5 /sbin/opensips[23569]: ERROR:call_center:w_handle_call: failed to set new destination for call</div><div>Aug 27 18:11:07 plat5 /sbin/opensips[23569]: errnum: -1</div></div><div><br></div><div>Error -1 means flowID is invalid, but I sent the same value on both calls.</div><div><br></div><div>This is the call: </div><div><br></div><div>cc_handle_call("$rU")<br></div><div><br></div><div>I'm using Opensips 2.4.2 with Debian 8.11.</div><div><br></div><div>Am I missing something or found a bug?</div><div><br></div><div>Thanks</div></div>
</blockquote></div>