[OpenSIPS-Users] Serialforking failure, with lcr:parse_phostport: too many colons in udp:: 0

Bogdan-Andrei Iancu bogdan at voice-system.ro
Tue Sep 28 17:14:00 CEST 2010


Hi Taiso,

the load_contacts() and next_contact() are deprecated, better use the 
core functions:
    serialize_branches() 
http://www.opensips.org/Resources/DocsCoreFcn16#toc128
    next_branches() http://www.opensips.org/Resources/DocsCoreFcn16#toc112

which works in the same way.

Regards,
Bogdan

Taisto Qvist wrote:
> Hi folks,
>
> I am trying to recreate a serialforking scenario which I am receiving
> the following problems with.
> As far as I know(?), the config is just the same as last week when I
> used the same config and everything worked.
> Unfortunately I dont have the old config so I cant compare, but the 
> changes
> are so small that I cant figure out the differences.
>
> I am running the latest opensips 1.6.3 from svn.
> But I've tried a few other 1.6.3 versions with the same results.
>
> Heres part of the script:
> -------------------
>             lookup("location", "m");
>             switch ($retcode)
>             {
>                 case 1:
>                     log(2, "(lab2) - Contact found in location server, 
> rerouting.\n");
>                     if (!load_contacts())
>                     {
>                        log(1, "(lab2) - Unable to load contacts for 
> serial forking\n");
>                        t_reply("500", "Server Internal Error (Serial 
> fork)");
>                        exit;
>                     }
>                     if ( !next_contacts() )
>                     {
>                         t_reply("509", "Serial fork error");
>                         exit;
>                     }
>                     return(1);
> -------------------
> But the next_contacts() fails for some reason, and the error-printouts 
> in the log, doesnt tell
> me enough to know where to continue.
>
> Heres the debug printout:
> ----------------------------
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_val2bind: added val (1): len=8; type=254; is_null=0
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_do_prepared_query: doing BIND_PARAM in...
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_do_prepared_query: prepared statement has 1 
> columns in result
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_new_result: allocate 28 bytes for result set at 0x81cfc24
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_allocate_columns: allocate 16 bytes for result columns at 
> 0x81cc42c
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x81cc430)[0]=[username]
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_allocate_rows: allocate 28 bytes for result rows and 
> values at 0x81cfc50
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:db_mysql:db_mysql_str2val: converting STRING [jane]
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:uri:does_uri_exist: User in request uri does exist
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_free_columns: freeing result columns at 0x81cc42c
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_free_rows: freeing 1 rows
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:core:db_free_row: 
> freeing row values at 0x81cfc58
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_free_rows: freeing rows at 0x81cfc50
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:db_free_result: freeing result set at 0x81cfc24
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: (lab2) - Its a valid 
> local user
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:comp_scriptvar: int 20 : 0 / 0
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: (lab2) - Stateful LS 
> lookup()
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:registrar:lookup: 
> setting as ruri <sip:jane at 10.10.2.33:5060>
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:registrar:lookup: 
> looking for branches
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:registrar:lookup: 
> setting branch <sip:jane at 10.10.1.24:5061>
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: (lab2) - Contact 
> found in location server, rerouting.
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:lcr:socket2str: 
> <udp::>
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:lcr:load_contacts: Loaded contact <sip:jane at 10.10.1.24:5061   
> udp:: 0 > with q_flag <0>
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:lcr:socket2str: 
> <udp::>
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:lcr:load_contacts: Loaded contact <sip:jane at 10.10.2.33:5060   
> udp:: 0 > with q_flag <4>
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:lcr:next_contacts: Next contact is <sip:jane at 10.10.2.33:5060   
> udp:: 0 >
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> ERROR:lcr:parse_phostport:  too many colons in udp:: 0
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> ERROR:lcr:decode_branch_info: Parsing of socket info <udp::> failed
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> ERROR:lcr:next_contacts: Decoding of branch info 
> <sip:jane at 10.10.2.33:5060   udp:: 0 > failed
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:parse_headers: flags=ffffffffffffffff
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:core:check_ip_address: params 10.10.10.11, sip.core.net, 0
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:core:_shm_resize: 
> resize(0) called
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:tm:cleanup_uac_timers: RETR/FR timers reset
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:tm:set_timer: 
> relative timeout is 500000
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:tm:insert_timer_unsafe: [4]: 0xb5b6345c (159600000)
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:tm:set_timer: 
> relative timeout is 32
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: 
> DBG:tm:insert_timer_unsafe: [0]: 0xb5b63478 (191)
> Sep 28 16:12:21 sip-laptop2 opensips_lab2[2118]: DBG:tm:_reply_light: 
> reply sent out. buf=0x81cff60: SIP/2.0 5..., shmem=0xb5b61374:
> -------------------------
>
> Many thanks for any help/tips you can give, I'm kind of lost...
>
> Regards
> Taisto Qvist
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>   


-- 
Bogdan-Andrei Iancu
OpenSIPS Bootcamp
15 - 19 November 2010, Edison, New Jersey, USA
www.voice-system.ro




More information about the Users mailing list