[OpenSIPS-Users] need some help using opensips 3.0 cluster module .
Sasmita Panda
spanda at 3clogic.com
Thu Aug 1 07:39:13 EDT 2019
Lets move back to the previous problem which is more important for me .
When I am dialing out sip2 (user) from even though its present in the
memory but its giving 404 Not Found .
Attached the dbug log below .
*Thanks & Regards*
*Sasmita Panda*
*Senior Network Testing and Software Engineer*
*3CLogic , ph:07827611765*
On Tue, Jul 30, 2019 at 5:49 PM Liviu Chircu <liviu at opensips.org> wrote:
> Do you get any errors in the logs? Can you connect from console with
> that user/password, just
> to confirm access works? It looks like a configuration issue. If you
> can't figure it out,
> please provide full debug logs.
>
> Liviu Chircu
> OpenSIPS Developer
> http://www.opensips-solutions.com
>
> On 30.07.2019 14:49, Sasmita Panda wrote:
> > This is the common configuration for every node . But why its
> > deviating for node 1 to save the data in local mysql location DB .
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20190801/7dab5844/attachment-0001.html>
-------------- next part --------------
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.x:47049 with source id: 3 and cluster id: 1
DBG:clusterer:handle_internal_msg: Received ping reply from node [3]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b811c8, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 1. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.y:51896 with source id: 2 and cluster id: 1
DBG:clusterer:handle_internal_msg: Received ping reply from node [2]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:proto_bin:bin_read_req: Using the global ( per process ) buff
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b811c8, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 0. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.y:51896 with source id: 2 and cluster id: 1
DBG:core:handle_worker: read response= 7f9a17b814b0, 1, fd -1 from 36 (22084)
DBG:clusterer:handle_internal_msg: Replied to ping from node [2]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:proto_bin:bin_read_req: Using the global ( per process ) buff
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b81a80, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 0. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.x:47049 with source id: 3 and cluster id: 1
DBG:core:handle_worker: read response= 7f9a17b81798, 1, fd -1 from 37 (22085)
DBG:clusterer:handle_internal_msg: Replied to ping from node [3]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:handle_worker: read response= 7f9a17b81798, 1, fd -1 from 37 (22085)
DBG:clusterer:do_action_trans_5: Sent ping to node [3]
DBG:core:handle_worker: read response= 7f9a17b814b0, 1, fd -1 from 37 (22085)
DBG:clusterer:do_action_trans_5: Sent ping to node [2]
DBG:proto_bin:bin_read_req: Using the global ( per process ) buff
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b811c8, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 1. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.y:51896 with source id: 2 and cluster id: 1
DBG:clusterer:handle_internal_msg: Received ping reply from node [2]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:proto_bin:bin_read_req: Using the global ( per process ) buff
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b81a80, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 1. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.x:47049 with source id: 3 and cluster id: 1
DBG:clusterer:handle_internal_msg: Received ping reply from node [3]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:udp_read_req: probing packet received len = 2
message repeated 2 times: [ DBG:core:udp_read_req: probing packet received len = 2]
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:udp_read_req: probing packet received len = 2
DBG:core:udp_read_req: probing packet received len = 2
DBG:proto_bin:bin_read_req: Using the global ( per process ) buff
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b811c8, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 0. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.y:51896 with source id: 2 and cluster id: 1
Aug 1 10:57:52 ip-192-168-0-185 /usr/local/sbin/opensips[22122]: DBG:core:handle_worker: read response= 7f9a17b814b0, 1, fd -1 from 36 (22084)
DBG:clusterer:handle_internal_msg: Replied to ping from node [2]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:proto_bin:bin_read_req: Using the global ( per process ) buff
DBG:proto_bin:bin_parse_headers: received a COMPLETE message
DBG:proto_bin:bin_handle_req: completely received a message
DBG:proto_bin:bin_handle_req: Nothing more to read on TCP conn 0x7f9a17b81a80, currently in state 0
DBG:core:call_callbacks: binary Packet CMD: 0. Capability: clusterer-internal
DBG:clusterer:bin_rcv_cl_packets: received clusterer message from: 192.168.0.x:47049 with source id: 3 and cluster id: 1
Aug 1 10:57:52 ip-192-168-0-185 /usr/local/sbin/opensips[22122]: DBG:core:handle_worker: read response= 7f9a17b81798, 1, fd -1 from 37 (22085)
DBG:clusterer:handle_internal_msg: Replied to ping from node [3]
DBG:proto_bin:bin_read_req: tcp_read_req end
DBG:core:parse_msg: SIP Request:
DBG:core:parse_msg: method: <INVITE>
DBG:core:parse_msg: uri: <sip:sip2 at opensips-p2p.com:5508>
DBG:core:parse_msg: version: <SIP/2.0>
DBG:core:parse_headers: flags=2
DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=6
DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKPj171bdd0470ec4960ad64fb646f0e2fb6>; state=16
DBG:core:parse_via: end of header reached, state=5
DBG:core:parse_headers: via found, flags=2
DBG:core:parse_headers: this is the first via
DBG:core:receive_msg: After parse_msg...
DBG:core:receive_msg: preparing to run routing scripts...
DBG:core:parse_headers: flags=100
DBG:maxfwd:is_maxfwd_present: value = 70
DBG:core:parse_headers: flags=8
DBG:core:_parse_to: end of header reached, state=9
DBG:core:_parse_to: display={}, ruri={sip:sip2 at opensips-p2p.com:5508}
DBG:core:get_hdr_field: <To> [47]; uri=[sip:sip2 at opensips-p2p.com:5508]
DBG:core:get_hdr_field: to body [sip:sip2 at opensips-p2p.com:5508#015#012]
DBG:sipmsgops:has_totag: no totag
DBG:core:parse_headers: flags=78
DBG:core:get_hdr_field: cseq <CSeq>: <6334> <INVITE>
DBG:tm:t_lookup_request: start searching: hash=5923, isACK=0
DBG:tm:matching_3261: RFC3261 transaction matching failed
DBG:tm:t_lookup_request: no transaction found
DBG:sipmsgops:has_totag: no totag
DBG:dialog:w_create_dialog: bye on timeout activated
DBG:core:parse_to_param: tag=e629b3cd48304276af4a568694a28437
DBG:core:_parse_to: end of header reached, state=29
DBG:core:_parse_to: display={"sip3"}, ruri={sip:sip3 at opensips-p2p.com:5508}
DBG:dialog:build_new_dlg: new dialog 0x7f9a17b852a8 (c=dcecbf8b2e5a415685f8f96b365b8192,f=sip:sip3 at opensips-p2p.com:5508,t=sip:sip2 at opensips-p2p.com:5508,ft=e629b3cd48304276af4a568694a28437) on hash 2841
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:core:get_hdr_field: content_length=226
DBG:core:get_hdr_field: found end of header
DBG:dialog:update_leg_info: route_set , contact sip:sip3 at 192.168.0.a:58178, cseq 6334 and bind_addr udp:192.168.0.z:5508
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:dialog:dlg_update_leg_info: set leg 0 for 0x7f9a17b852a8: tag=<e629b3cd48304276af4a568694a28437> rcseq=<0>
DBG:dialog:link_dlg: ref dlg 0x7f9a17b852a8 with 4 -> 4 in h_entry 0x7f9a17b1db88 - 2841
DBG:rr:add_rr_param: adding (;did=91b.db7975f1)
DBG:rr:add_rr_param: second RR lump found
DBG:rr:add_rr_param: second RR lump found
INVITE from outside, doing global lookup 192.168.0.a m 1st line
INVITE from outside, doing global lookup mg 2nd line
DBG:registrar:parse_lookup_flags: final flags: 9
DBG:usrloc:cdb_load_urecord_locations: querying AoR sip2 at opensips-p2p.com
DBG:cachedb_mongodb:mongo_con_query: find all in opensipsDB.userlocation
DBG:cachedb_mongodb:mongo_con_query: using filter: { "$query" : { "aor" : "sip2 at opensips-p2p.com" } }
DBG:cachedb_mongodb:mongo_con_query: result count: 1
DBG:usrloc:cdb_load_urecord_locations: res.count: 1
DBG:usrloc:cdb_load_urecord_locations: skipping my own SIP addr (192.168.0.z)
DBG:registrar:select_contacts: ct: sip:sip2 at 192.168.0.a:55979
INVITE from outside, when user wont exists 192.168.0.a mg
DBG:tm:t_newtran: transaction on entrance=(nil)
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:core:parse_headers: flags=78
DBG:tm:t_lookup_request: start searching: hash=5923, isACK=0
DBG:tm:matching_3261: RFC3261 transaction matching failed
DBG:tm:t_lookup_request: no transaction found
DBG:tm:run_reqin_callbacks: trans=0x7f9a17b86150, callback type 1, id 0 entered
DBG:dialog:dlg_onreq: t hash_index = 5923, t label = 881409022
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:tm:_reply_light: reply sent out. buf=0x7f9a3776c828: SIP/2.0 1..., shmem=0x7f9a17ba7360: SIP/2.0 1
DBG:tm:_reply_light: finished
DBG:core:parse_headers: flags=ffffffffffffffff
DBG:tm:run_trans_callbacks: trans=0x7f9a17b86150, callback type 64, id 0 entered
DBG:core:evi_param_set: adding string param
message repeated 4 times: [ DBG:core:evi_param_set: adding string param]
DBG:core:evi_param_set: adding int param
DBG:core:evi_param_set: adding int param
DBG:core:destroy_avp_list: destroying list (nil)
DBG:dialog:next_state_dlg: dialog 0x7f9a17b852a8 changed from state 1 to state 5, due event 4
DBG:dialog:dlg_onreply: dialog 0x7f9a17b852a8 failed (negative reply)
DBG:dialog:unref_dlg: unref dlg 0x7f9a17b852a8 with 1 -> 3 in entry 0x7f9a17b1db88
DBG:tm:cleanup_uac_timers: RETR/FR timers reset
DBG:tm:set_timer: relative timeout is 500000
DBG:tm:insert_timer_unsafe: [4]: 0x7f9a17b86298 (80472200000)
DBG:tm:insert_timer_unsafe: [0]: 0x7f9a17b862c8 (80476)
DBG:tm:_reply_light: reply sent out. buf=0x7f9a3776ca08: SIP/2.0 4..., shmem=0x7f9a17ba7360: SIP/2.0 4
DBG:tm:run_trans_callbacks: trans=0x7f9a17b86150, callback type 128, id 3 entered
DBG:tm:_reply_light: finished
DBG:tm:t_unref: UNREF_UNSAFE: [0x7f9a17b86150] after is 0
DBG:dialog:unref_dlg: unref dlg 0x7f9a17b852a8 with 1 -> 2 in entry 0x7f9a17b1db88
DBG:core:destroy_avp_list: destroying list (nil)
DBG:core:receive_msg: cleaning up
DBG:core:parse_msg: SIP Request:
DBG:core:parse_msg: method: <ACK>
DBG:core:parse_msg: uri: <sip:sip2 at opensips-p2p.com:5508>
DBG:core:parse_msg: version: <SIP/2.0>
DBG:core:parse_headers: flags=2
DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=6
DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKPj171bdd0470ec4960ad64fb646f0e2fb6>; state=16
DBG:core:parse_via: end of header reached, state=5
DBG:core:parse_headers: via found, flags=2
DBG:core:parse_headers: this is the first via
DBG:core:receive_msg: After parse_msg...
DBG:core:receive_msg: preparing to run routing scripts...
DBG:sl:sl_filter_ACK: too late to be a local ACK!
DBG:core:parse_headers: flags=100
DBG:maxfwd:is_maxfwd_present: value = 70
DBG:core:parse_headers: flags=8
DBG:core:parse_to_param: tag=23ad15fcb5d626ff836c18f6c335ec78-ef2a
DBG:core:_parse_to: end of header reached, state=29
DBG:core:_parse_to: display={}, ruri={sip:sip2 at opensips-p2p.com:5508}
DBG:core:get_hdr_field: <To> [89]; uri=[sip:sip2 at opensips-p2p.com:5508]
DBG:core:get_hdr_field: to body [sip:sip2 at opensips-p2p.com:5508]
DBG:sipmsgops:has_totag: totag found
DBG:core:parse_headers: flags=78
DBG:core:get_hdr_field: cseq <CSeq>: <6334> <ACK>
DBG:tm:t_lookup_request: start searching: hash=5923, isACK=1
DBG:tm:matching_3261: RFC3261 transaction matched, tid=Pj171bdd0470ec4960ad64fb646f0e2fb6
DBG:tm:t_lookup_request: REF_UNSAFE:[0x7f9a17b86150] after is 1
DBG:tm:t_lookup_request: transaction found (T=0x7f9a17b86150)
DBG:tm:cleanup_uac_timers: RETR/FR timers reset
DBG:tm:insert_timer_unsafe: [2]: 0x7f9a17b861d0 (80476)
DBG:tm:do_t_cleanup: transaction 0x7f9a17b86150 already updated! Skipping update!
DBG:tm:t_unref: UNREF_UNSAFE: [0x7f9a17b86150] after is 0
DBG:core:destroy_avp_list: destroying list (nil)
DBG:core:receive_msg: cleaning up
More information about the Users
mailing list