[OpenSIPS-Users] Contact Header Crash

Antonio Frongillo antonio at frogweb.it
Thu Jan 15 22:27:03 CET 2009


Hi Bogdan,

I went deeper in the analysis and I find out that the problem was that the
SIP message is too long and makes Seas crash.

I found the parameter ENCODED_MSG_SIZE in "seas.h" that is set to 3200 by
default.
Setting this parameter to a larger value it seems that all is going fine.

What do you think about this issue? It seems not a good behavior that the
OpenSips server crash with this kind of messages.
Is it possible to release a patch that fix this or for example makes the
maximum size of the payload configurable from the opensips configuration
file?

Thanks in advance,
Antonio

2009/1/15 Antonio Frongillo <antonio at frogweb.it>

> Hi Bogdan,
> I made a deeper analysis on the problem and I think that the error (the seg
> fault on SEAS module) is caused by incoming messages with a BIG Content.
>
> Both 200 OK with big payload and NOTIFY makes OpenSips seas module to crash
> in different points (seeing the core generated) and randomly (it doesn't
> crash for each message, also identical messages some times works and other
> times crashes).
>
> Could it be that when Seas try to read the BIG Content it wrongly manages
> the memory and write in an illegal memory address (so sometimes works and
> other times go in seg fault) ????
>
> Below you can find the logs of OpenSips and a couple of core dump.
>
> Jan 15 17:31:31 [31065] DBG:core:tcp_send: tcp connection found
> (0xb614d6a0), acquiring fd
> Jan 15 17:31:31 [31065] DBG:core:tcp_send: c= 0xb614d6a0, n=8
> Jan 15 17:31:31 [31061] DBG:core:handle_ser_child: read response= b614d6a0,
> 1, fd -1 from 1 (31042)
> Jan 15 17:31:31 [31065] DBG:core:tcp_send: after receive_fd: c= 0xb614d6a0
> n=4 fd=13
> Jan 15 17:31:31 [31065] DBG:core:tcp_send: sending...
> Jan 15 17:31:31 [31065] DBG:core:tcp_send: after write: c= 0xb614d6a0
> n=1915 fd=13
> Jan 15 17:31:31 [31065] DBG:core:tcp_send: buf=
> SERVICE sip:max.ambrogi at hmcint.local:5060;transport=tcp SIP/2.0
> Via: SIP/2.0/TCP 192.168.5.59;branch=z9hG4bKa21a.c42740c2.0
> To: sip:max.ambrogi at hmcint.local:5060;transport=tcp
> From: sip:max.ambrogi at hmcint.local
> ;transport=tcp;tag=AB62C6E82E8FD0E5FE0D61C590FF8064
> CSeq: 1 SERVICE
> Call-ID: 2835F715D923D84438CD30BFCB886F6A at 192.168.5.59
> Content-Length: 1064
> Max-Forwards: 70
> Contact: <sip:192.168.5.59:5060;transport=tcp;AppId=.sip2msipGW>;methods="INVITE,
> MESSAGE, INFO, OPTIONS, BYE, CANCEL, NOTIFY, ACK,
> REFER";proxy=replace;+sip.instance="<urn:uuid:8ceb9439-8543-4acd-8691-d2ef85926f22>"
> Proxy-Authorization: NTLM qop="auth",realm="SIP Communications
> Service",opaque="23E04879",crand="94867859",cnum="25",targetname="HMCINTOCS.HMCINT.LOCAL",response="01000000000000006A61B6F3C230F2DF"
> Content-Type: application/msrtc-category-publish+xml
>
> <publish xmlns="http://schemas.microsoft.com/2006/09/sip/rich-presence"><publications
> uri="sip:max.ambrogi at hmcint.local"><publication categoryName="state"
> instance="603979776" container="3" version="0" expireType="time"
> expires="0"/><publication categoryName="state" instance="603979776"
> container="2" version="0" expireType="time" expires="0"/><publication
> categoryName="state" instance="536870912" container="3" version="141"
> expireType="static"><state xmlns="
> http://schemas.microsoft.com/2006/09/sip/state" manual="true" xmlns:xsi="
> http://www.w3.org/2001/XMLSchema-instance"
> xsi:type="userState"><availability>3500</availability><endpointLocation></endpointLocation></state></publication><publication
> categoryName="state" instance="536870912" container="2" version="141"
> expireType="static"><state xmlns="
> http://schemas.microsoft.com/2006/09/sip/state" manual="true" xmlns:xsi="
> http://www.w3.org/2001/XMLSchema-instance"
> xsi:type="userState"><availability>3500</availability><endpointLocation></endpointLocation></state></publication></publications></publish>
> Jan 15 17:31:31 [31065] DBG:tm:set_timer: relative timeout is 30
> Jan 15 17:31:31 [31065] DBG:tm:insert_timer_unsafe: [0]: 0xb6160ed0 (230)
> Jan 15 17:31:31 [31065] DBG:seas:process_input: (Action
> dispatched,buffer.len=0)
> Jan 15 17:31:31 [31053] DBG:core:tcp_read_req: content-length= 3935
> Jan 15 17:31:31 [31053] DBG:core:parse_msg: SIP Reply  (status):
> Jan 15 17:31:31 [31053] DBG:core:parse_msg:  version: <SIP/2.0>
> Jan 15 17:31:31 [31053] DBG:core:parse_msg:  status:  <200>
> Jan 15 17:31:31 [31053] DBG:core:parse_msg:  reason:  <OK>
> Jan 15 17:31:31 [31053] DBG:core:parse_headers: flags=2
> Jan 15 17:31:31 [31053] DBG:core:get_hdr_field: content_length=3935
> Jan 15 17:31:31 [31053] DBG:core:parse_to_param: transport=tcp
> Jan 15 17:31:31 [31053] DBG:core:parse_to_param:
> tag=F38CBA48BA483432B3C92CFD2033D7D1
> Jan 15 17:31:31 [31053] DBG:core:parse_to: end of header reached, state=29
> Jan 15 17:31:31 [31053] DBG:core:parse_to: display={},
> ruri={sip:max.ambrogi at hmcint.local:5060}
> Jan 15 17:31:31 [31053] DBG:core:get_hdr_field: <To> [86];
> uri=[sip:max.ambrogi at hmcint.local:5060]
> Jan 15 17:31:31 [31053] DBG:core:get_hdr_field: to body
> [sip:max.ambrogi at hmcint.local:5060]
> Jan 15 17:31:31 [31053] DBG:core:get_hdr_field: cseq <CSeq>: <1> <SERVICE>
> Jan 15 17:31:31 [31053] DBG:core:parse_via_param: found param type 232,
> <branch> = <z9hG4bKa21a.c42740c2.0>; state=6
> Jan 15 17:31:31 [31053] DBG:core:parse_via_param: found param type 238,
> <ms-received-port> = <44569>; state=6
> Jan 15 17:31:31 [31053] DBG:core:parse_via_param: found param type 238,
> <ms-received-cid> = <C700>; state=16
> Jan 15 17:31:31 [31053] DBG:core:parse_via: end of header reached, state=5
> Jan 15 17:31:31 [31053] DBG:core:parse_headers: via found, flags=2
> Jan 15 17:31:31 [31053] DBG:core:parse_headers: this is the first via
> Jan 15 17:31:31 [31053] DBG:core:receive_msg: After parse_msg...
> Jan 15 17:31:31 [31053] DBG:core:comp_scriptvar: str 20 : 200
> Jan 15 17:31:31 [31053] DBG:core:forward_reply: found module tm, passing
> reply to it
> Jan 15 17:31:31 [31053] DBG:tm:t_check: start=0xffffffff
> Jan 15 17:31:31 [31053] DBG:core:parse_headers: flags=22
> Jan 15 17:31:31 [31053] DBG:tm:t_reply_matching: hash 41258 label 738488908
> branch 0
> Jan 15 17:31:31 [31053] DBG:tm:t_reply_matching: REF_UNSAFE: after is 1
> Jan 15 17:31:31 [31053] DBG:tm:t_reply_matching: reply matched
> (T=0xb6160d68)!
> Jan 15 17:31:31 [31053] DBG:tm:t_check: end=0xb6160d68
> Jan 15 17:31:31 [31053] DBG:tm:reply_received: org. status uas=0, uac[0]=0
> local=2 is_invite=0)
> Jan 15 17:31:31 [31053] DBG:tm:t_should_relay_response: T_code=0,
> new_code=200
> Jan 15 17:31:31 [31053] DBG:tm:local_reply: branch=0, save=0, winner=0
> Jan 15 17:31:31 [31053] DBG:tm:local_reply: local transaction completed
> Jan 15 17:31:31 [31053] DBG:tm:run_trans_callbacks: trans=0xb6160d68,
> callback type 256, id 0 entered
> Jan 15 17:31:31 [31053] DBG:seas:uac_cb: reply to UAC Transaction for
> AS:shootist code: 200
> Jan 15 17:31:31 [31053] DBG:seas:uac_cb: transaction 0xb6160d68
> Nr_of_outgoings:1 is_Local:y
> Jan 15 17:31:31 [31053] DBG:seas:uac_cb: UAC[0].last_received=200
> Jan 15 17:31:31 [31053] DBG:core:parse_headers: flags=ffffffffffffffff
> Jan 15 17:31:31 [31053] DBG:core:get_hdr_field: found end of header
> Jan 15 17:31:31 [31053] DBG:core:parse_headers: flags=ffffffffffffffff
> Jan 15 17:31:31 [31053] DBG:core:parse_to_param: transport=tcp
> Jan 15 17:31:31 [31053] DBG:core:parse_to_param:
> tag=AB62C6E82E8FD0E5FE0D61C590FF8064
> Jan 15 17:31:31 [31053] DBG:core:parse_to: end of header reached, state=29
> Jan 15 17:31:31 [31053] DBG:core:parse_to: display={"Max Ambrogi"},
> ruri={sip:max.ambrogi at hmcint.local}
> Jan 15 17:31:31 [31053] DBG:seas:encode_msg: msglen = 4605,msg starts at
> 154
> Jan 15 17:31:31 [31053] DBG:tm:cleanup_uac_timers: RETR/FR timers reset
> Jan 15 17:31:31 [31053] DBG:tm:insert_timer_unsafe: [2]: 0xb6160db0 (205)
> Jan 15 17:31:31 [31053] DBG:tm:t_unref: UNREF_UNSAFE: after is 0
> Jan 15 17:31:31 [31053] DBG:core:destroy_avp_list: destroying list (nil)
> Jan 15 17:31:31 [31053] DBG:core:receive_msg: cleaning up
> Jan 15 17:31:31 [-1241746112] DBG:seas:dispatch_relay: Event relaied to
> shootist AS
> Jan 15 17:31:31 [-1241746112] DBG:seas:dispatch_relay: Event type UAC_REQ
> Jan 15 17:31:31 [-1241746112] DBG:seas:dispatcher_main_loop: Scanning data
> from 1 AS
> Jan 15 17:31:31 [-1241746112] DBG:seas:dispatcher_main_loop: Scanning data
> from 0 uncomplete AS
> Jan 15 17:31:31 [-1241746112] INFO:seas:dispatcher_main_loop: polling [2
> ServSock] [1 pipe] [1 App Servers] [0 Uncomplete AS]
> Jan 15 17:31:35 [31051] DBG:tm:timer_routine: timer routine:2,tl=0xb615ff60
> next=0xb6160db0, timeout=205
> Jan 15 17:31:35 [31051] DBG:tm:wait_handler: removing 0xb615ff18 from table
> Jan 15 17:31:35 [31051] DBG:tm:delete_cell: delete transaction 0xb615ff18
> Jan 15 17:31:36 [31041] INFO:core:handle_sigs: child process 31051 exited
> by a signal 11
> Jan 15 17:31:36 [31041] INFO:core:handle_sigs: core was generated
> Jan 15 17:31:36 [31041] INFO:core:handle_sigs: terminating due to SIGCHLD
> Jan 15 17:31:36 [-1241746112] INFO:seas:seas_sighandler: INFO: signal 15
> received
> Jan 15 17:31:36 [31042] heap size= 1048576
>  used= 52968, used+overhead=72856, free=975720
>  max used (+overhead)= 72856
> dumpingJan 15 17:31:36 [31044] INFO:core:sig_usr: signal 15 received
> Memory status (pkg):
> fm_status (0x8194f20):
>  heap size= 1048576
>  used= 49024, used+overhead=68904, free=979672
>  max used (+overhead)= 68904
> dumping free list:
> hash =   1 fragments no.:     1, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =  42 fragments no.:     1, unused:     0
>                  bucket size:       336 -       336 (first       336)
> hash =  53 fragments no.:     1, unused:     0
>                  bucket size:       424 -       424 (first       424)
> hash =  56 fragments no.:     1, unused:     0
>                  bucket size:       448 -       448 (first       448)
> hash = 2054 fragments no.:     1, unused:     0
>                  bucket size:    524288 -   1048576 (first    978496)
> TOTAL:      5 free fragments = 979712 free bytes
> -----------------------------
> ping free list:
> hash =   1 fragments no.:     1, unused:     0
>                  bucket sJan 15 17:31:36 [31049] INFO:core:sig_usr: signal
> 15 received
> Memory status (pkg):
> fm_status (0x8194f20):
>  heap size= 1048576
>  used= 49024, used+overhead=68904, free=979672
>  max used (+overhead)= 68904
> dumping free list:
> hash =   1 fragments no.:     1, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =  42 fragments no.:     1, Jan 15 17:31:36 [31052] INFO:core:sig_usr:
> signal 15 received
> Memory status (pkg):
> fm_status (0x8194f20Jan 15 17:31:36 [31054] INFO:core:sig_usr: signal 15
> received
> Memory status (pkg):
> fm_status (0x8194f20):
>  heap size= 1048576
>  used= 99160, used+overhead=119056, free=929520
>  max used (+overhead)= 119056
> dumping free list:
> hash =   1 fragments no.:     1, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =   7 fragments no.:     1, unused:     0
>                  bucket size:        56 -        56 (first        56)
> hash =  42 fragments no.:     1, unused:     0
>                  bucket size:       336 -       336 (first       336)
> hash =  53 fragments no.:     1, unused:     0
>                  bucket size:       424 -      Jan 15 17:31:36 [31059]
> INFO:core:sig_usr: signal 15 received
> Memory status (pkg):
> fm_status (0x8194f20):
>  heap size= 1048576
>  used= 99160, used+overhead=119056, free=929520
>  max used (+overhead)= 119056
> dumping free list:
> hash =   1 fragments no.:     1, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hashJan 15 17:31:36 [31065] INFO:seas:seas_sighandler: INFO: signal 15
> received
> fm_status (0x8194f20)hash =   1 fragments no.:     1, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =   7 fragments no.:     1, unused:     0
>                  bucket size:        56 -        56 (first        56)
> hash =  42 fragments no.:     1, unused:     0
>                  bucket size:       336 -       336 (first       336)
> hash =  53 fragments no.:     1, unused:     0
>                  bucket size:       424 -       424 (first       424)
> hash =  56 fragments no.:     1, unused:     0
>                  bucket size:       448 -       448 (first       448)
> hash = 2054 fragments no.:     1, unused:     0
>                  bucket size:    524288 -   1048576 (first    928288)
> TOTAL:      6 free fragments = 929560 free bytes
> -----------------------------
> Jan 15 17:31:36 [-1241746112] INFO:seas:seas_sighandler: Child stopped or
> terminated
> Jan 15 17:31:36 [31041] DBG:xlog:destroy: destroy module...
> Jan 15 17:31:36 [31041] DBG:tm:tm_shutdown: tm_shutdown : start
> Jan 15 17:31:36 [31041] DBG:tm:unlink_timer_lists: emptying DELETE list
> Jan 15 17:31:36 [31041] DBG:tm:tm_shutdown: emptying hash table
> Jan 15 17:31:36 [31041] DBG:tm:run_trans_callbacks: trans=0xb6160d68,
> callback type 2048, id 1 entered
> Jan 15 17:31:36 [31041] DBG:tm:tm_shutdown: releasing timers
> Jan 15 17:31:36 [31041] DBG:tm:tm_shutdown: removing semaphores
> Jan 15 17:31:36 [31041] DBG:tm:tm_shutdown: destroying callback lists
> Jan 15 17:31:36 [31041] DBG:tm:tm_shutdown: tm_shutdown : done
> Jan 15 17:31:36 [31041] DBG:core:destroy_tls: entered
> Memory status (pkg):
> fm_status (0x8194f20):
>  heap size= 1048576
>  used= 33728, used+overhead=53600, free=994976
>  max used (+overhead)= 60648
> dumping free list:
> hash =   1 fragments no.:     2, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =   2 fragments no.:    17, unused:     0
>                  bucket size:        16 -        16 (first        16)
> hash =   3 fragments no.:     2, unused:     0
>                  bucket size:        24 -        24 (first        24)
> hash =   5 fragments no.:     4, unused:     0
>                  bucket size:        40 -        40 (first        40)
> hash =   7 fragments no.:     1, unused:     0
>                  bucket size:        56 -        56 (first        56)
> hash =  10 fragments no.:     2, unused:     0
>                  bucket size:        80 -        80 (first        80)
> hash =  42 fragments no.:     1, unused:     0
>                  bucket size:       336 -       336 (first       336)
> hash =  53 fragments no.:     1, unused:     0
>                  bucket size:       424 -       424 (first       424)
> hash =  56 fragments no.:     1, unused:     0
>                  bucket size:       448 -       448 (first       448)
> hash = 800 fragments no.:     1, unused:     0
>                  bucket size:      6400 -      6400 (first      6400)
> hash = 2054 fragments no.:     1, unused:     0
>                  bucket size:    524288 -   1048576 (first    986696)
> TOTAL:     33 free fragments = 995016 free bytes
> -----------------------------
> Memory status (shm):
> fm_status (0xb5fc1000):
>  heap size= 33554432
>  used= 2593372002, used+overhead=2593389770, free=1735131958
>  max used (+overhead)= 1714504
> dumping free list:
> hash =   0 fragments no.:     2, unused:     0
>                  bucket size:         0 -         0 (first         0)
> hash =   1 fragments no.:    48, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =   2 fragments no.:     7, unused:     0
>                  bucket size:        16 -        16 (first        16)
> hash =   3 fragments no.:     6, unused:     0
>                  bucket size:        24 -        24 (first        24)
> hash =   4 fragments no.:    46, unused:     0
>                  bucket size:        32 -        32 (first        32)
> hash =   5 fragments no.:     4, unused:     0
>                  bucket size:        40 -        40 (first        40)
> hash =   6 fragments no.:     4, unused:     0
>                  bucket size:        48 -        48 (first        48)
> hash =   8 fragments no.:     2, unused:     0
>                  bucket size:        64 -        64 (first        64)
> hash =  16 fragments no.:     1, unused:     0
>                  bucket size:       128 -       128 (first       128)
> hash =  18 fragments no.:     1, unused:     0
>                  bucket size:       144 -       144 (first       144)
> hash =  64 fragments no.:     1, unused:     0
>                  bucket size:       512 -       512 (first       512)
>
> [root at OCS log]# less opensips.log
> [root at OCS log]# less opensips.log
> hash =  56 fragments no.:     1, unused:     0
>                  bucket size:       448 -       448 (first       448)
> hash = 800 fragments no.:     1, unused:     0
>                  bucket size:      6400 -      6400 (first      6400)
> hash = 2054 fragments no.:     1, unused:     0
>                  bucket size:    524288 -   1048576 (first    986696)
> TOTAL:     33 free fragments = 995016 free bytes
> -----------------------------
> Memory status (shm):
> fm_status (0xb5fc1000):
>  heap size= 33554432
>  used= 2593372002, used+overhead=2593389770, free=1735131958
>  max used (+overhead)= 1714504
> dumping free list:
> hash =   0 fragments no.:     2, unused:     0
>                  bucket size:         0 -         0 (first         0)
> hash =   1 fragments no.:    48, unused:     0
>                  bucket size:         8 -         8 (first         8)
> hash =   2 fragments no.:     7, unused:     0
>                  bucket size:        16 -        16 (first        16)
> hash =   3 fragments no.:     6, unused:     0
>                  bucket size:        24 -        24 (first        24)
> hash =   4 fragments no.:    46, unused:     0
>                  bucket size:        32 -        32 (first        32)
> hash =   5 fragments no.:     4, unused:     0
>                  bucket size:        40 -        40 (first        40)
> hash =   6 fragments no.:     4, unused:     0
>                  bucket size:        48 -        48 (first        48)
> hash =   8 fragments no.:     2, unused:     0
>                  bucket size:        64 -        64 (first        64)
> hash =  16 fragments no.:     1, unused:     0
>                  bucket size:       128 -       128 (first       128)
> hash =  18 fragments no.:     1, unused:     0
>                  bucket size:       144 -       144 (first       144)
> hash =  64 fragments no.:     1, unused:     0
>
>
> CORE DUMP 1:
>
> #0  0x006edf21 in run_trans_callbacks (type=2048, trans=0xb615ff18,
> req=0x0, rpl=0x0, code=0) at t_hooks.c:204
> 204                     if ( (cbp->types)&type ) {
> (gdb) bt
> #0  0x006edf21 in run_trans_callbacks (type=2048, trans=0xb615ff18,
> req=0x0, rpl=0x0, code=0) at t_hooks.c:204
> #1  0x006db1ad in free_cell (dead_cell=0xb615ff18) at h_table.c:124
> #2  0x006f0464 in delete_cell (p_cell=0xb615ff18, unlock=1) at timer.c:239
> #3  0x006f1479 in timer_routine (ticks=205, attr=0x0) at timer.c:409
> #4  0x080c5a41 in start_timer_processes () at timer.c:280
> #5  0x0806ba43 in main (argc=2, argv=0xbfea9784) at main.c:815
>
>
> CORE DUMP 2:
>
> #0  0x080d578e in fm_status (qm=0xb5fc1000) at mem/f_malloc.c:512
> 512                     for (f=qm->free_hash[h].first,j=0; f;
> (gdb) bt
> #0  0x080d578e in fm_status (qm=0xb5fc1000) at mem/f_malloc.c:512
> #1  0x08067018 in cleanup (show_status=1) at main.c:352
> #2  0x08067a18 in handle_sigs () at main.c:519
> #3  0x0806bb6e in main (argc=2, argv=0xbfea9784) at main.c:861
>
>
>
>
>
>
> 2009/1/15 Bogdan-Andrei Iancu <bogdan at voice-system.ro>
>
>> Hi Antonio,
>>
>> There is a SEG FAULT happening:
>>   INFO:seas:dispatcher_main_loop: child process 20783 exited by a signal
>> 11
>>
>> I guess 20783 is a SEAS specific module - could you check in the logs what
>> are the last messages from this processes ? or do you have  a core file from
>> it?
>>
>> Regards,
>> Bogdan
>>
>> Antonio Frongillo wrote:
>>
>>>
>>>
>>> 2009/1/15 Antonio Frongillo <antonio at frogweb.it <mailto:
>>> antonio at frogweb.it>>
>>>
>>>
>>>    I'm trying to use OpenSips (configured with WeSip) to communicate
>>>    with Microsoft OCS 2007.
>>>    I'm able to sends REGISTER and SUBSCRIBE to OCS from a Sip Servlet
>>>    without any problem.
>>>    Randomly (or at least it seems) the 200 OK returned by the
>>>    SUBSCRIBE, or one of the NOTIFYs sent by OCS after the
>>>    subscription makes OpenSips to crash:
>>>
>>>    Jan 15 11:33:42 [1933272943] INFO:seas:seas_sighandler: Child
>>>    stopped or terminated
>>>    Jan 15 11:33:42 [1933272943] INFO:seas:dispatcher_main_loop: child
>>>    process 20783 exited by a signal 11
>>>    Jan 15 11:33:42 [1933272943] WARNING:seas:dispatcher_main_loop:
>>>    client [shootist] leaving (Action Dispatcher Process died !)
>>>    Jan 15 11:33:42 [1933272943] INFO:seas:dispatcher_main_loop:
>>>    polling [2 ServSock] [1 pipe] [0 App Servers] [0 Uncomplete AS]
>>>    Jan 15 11:33:43 [20779] DBG:core:handle_tcp_child: dead tcp child
>>>    0 (pid 20771, no 0) (shutting down?)
>>>    Jan 15 11:33:43 [20779] DBG:core:io_watch_del: io_watch_del
>>>    (0x8174e80, 15, -1, 0x0) fd_no=16 called
>>>    Jan 15 11:33:43 [20779] CRITICAL:core:receive_fd: EOF on 17
>>>    Jan 15 11:33:43 [20779] DBG:core:handle_ser_child: dead child 7,
>>>    pid 20771 (shutting down?)
>>>    Jan 15 11:33:43 [20779] DBG:core:io_watch_del: io_watch_del
>>>    (0x8174e80, 17, -1, 0x0) fd_no=15 called
>>>
>>>
>>>    The SUBSCRIBE from OCS returns this 200 OK:
>>>
>>>    SIP/2.0 200 OK
>>>    Contact:
>>>
>>>  <sip:OCSPool01.HMCINT.LOCAL;transport=tcp;ms-fe=HMCINTOCS.HMCINT.LOCAL>
>>>    Authentication-Info: NTLM
>>>    rspauth="0100000070B14E017817E85DC230F2DF", srand="B91383E2",
>>>    snum="2", opaque="0FFEF1C7", qop="auth",
>>>    targetname="HMCINTOCS.HMCINT.LOCAL", realm="SIP Communications
>>>    Service"
>>>    Content-Length: 8452
>>>    From: "Uma
>>>    Thurman"<sip:uma.thurman at hmcint.local
>>> >;transport=tcp;tag=D7DE4E07A70DEB0AF902F3F3ABBBA6D1
>>>    To: sip:uma.thurman at hmcint.local:5060;transport=tcp;tag=33280080
>>>    Call-ID: A19293DAF5E2116DE5D31992A298736
>>>    CSeq: 1 SUBSCRIBE
>>>    Via: SIP/2.0/TCP
>>>
>>>  10.107.109.108;branch=z9hG4bK398e.5338e871.0;ms-received-port=54613;ms-received-cid=6E00
>>>    Expires: 43200
>>>    Require: eventlist
>>>    Content-Type: application/vnd-microsoft-roaming-self+xml
>>>    Event: vnd-microsoft-roaming-self
>>>    subscription-state: active;expires=43200
>>>    ms-piggyback-cseq: 1
>>>    Supported: ms-benotify, ms-piggyback-first-notify
>>>
>>>    <roamingData
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/roaming-self"
>>>    xmlns:cat="http://schemas.microsoft.com/2006/09/sip/categories"
>>>    xmlns:con="http://schemas.microsoft.com/2006/09/sip/containers"
>>>    xmlns:sub="
>>> http://schemas.microsoft.com/2006/09/sip/presence-subscribers">
>>>    <categories
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/categories"
>>>    uri="sip:uma.thurman at hmcint.local">
>>>    <category name="calendarData" instance="0"
>>>    publishTime="2008-12-03T13:19:10.797" container="32000"
>>>    version="1" expireType="static"/>
>>>    <category name="calendarData" instance="0"
>>>    publishTime="2008-12-03T13:19:10.797" container="100" version="1"
>>>    expireType="static"/>
>>>    <category name="contactCard" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="32000"
>>>    version="4" expireType="static">
>>>    <contactCard
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/contactcard" >
>>>    <identity >
>>>    <name >
>>>    <displayName >
>>>    Uma Thurman</displayName>
>>>    </name>
>>>    <email >
>>>    uma.thurman at hmcint.local</email>
>>>    </identity>
>>>    </contactCard>
>>>    </category>
>>>    <category name="contactCard" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="400" version="4"
>>>    expireType="static">
>>>    <contactCard
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/contactcard" >
>>>    <identity >
>>>    <name >
>>>    <displayName >
>>>    Uma Thurman</displayName>
>>>    </name>
>>>    <email >
>>>    uma.thurman at hmcint.local</email>
>>>    </identity>
>>>    </contactCard>
>>>    </category>
>>>    <category name="contactCard" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="300" version="4"
>>>    expireType="static">
>>>    <contactCard
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/contactcard" >
>>>    <identity >
>>>    <name >
>>>    <displayName >
>>>    Uma Thurman</displayName>
>>>    </name>
>>>    <email >
>>>    uma.thurman at hmcint.local</email>
>>>    </identity>
>>>    </contactCard>
>>>    </category>
>>>    <category name="contactCard" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="200" version="4"
>>>    expireType="static">
>>>    <contactCard
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/contactcard" >
>>>    <identity >
>>>    <name >
>>>    <displayName >
>>>    Uma Thurman</displayName>
>>>    </name>
>>>    <email >
>>>    uma.thurman at hmcint.local</email>
>>>    </identity>
>>>    </contactCard>
>>>    </category>
>>>    <category name="contactCard" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="100" version="4"
>>>    expireType="static">
>>>    <contactCard
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/contactcard" >
>>>    <identity >
>>>    <name >
>>>    <displayName >
>>>    Uma Thurman</displayName>
>>>    </name>
>>>    <email >
>>>    uma.thurman at hmcint.local</email>
>>>    </identity>
>>>    </contactCard>
>>>    </category>
>>>    <category name="contactCard" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="0" version="4"
>>>    expireType="static">
>>>    <contactCard
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/contactcard" >
>>>    <identity >
>>>    <name >
>>>    <displayName >
>>>    Uma Thurman</displayName>
>>>    </name>
>>>    <email >
>>>    uma.thurman at hmcint.local</email>
>>>    </identity>
>>>    </contactCard>
>>>    </category>
>>>    <category name="note" instance="0"
>>>    publishTime="2008-12-03T13:19:10.797" container="32000"
>>>    version="1" expireType="static"/>
>>>    <category name="note" instance="0"
>>>    publishTime="2008-12-03T13:19:10.797" container="100" version="1"
>>>    expireType="static"/>
>>>    <category name="state" instance="0"
>>>    publishTime="2008-12-03T13:19:10.797" container="32000"
>>>    version="1" expireType="static">
>>>    <state xmlns="http://schemas.microsoft.com/2006/09/sip/state"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    manual="false" xsi:type="aggregateState" ><availability
>>>    >18500</availability><endpointLocation ></endpointLocation></state>
>>>    </category>
>>>    <category name="state" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="400" version="1"
>>>    expireType="static">
>>>    <state xsi:type="aggregateState" lastActive="2009-01-14T15:06:10"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/state
>>> "><availability>18000</availability></state>
>>>    </category>
>>>    <category name="state" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="300" version="1"
>>>    expireType="static">
>>>    <state xsi:type="aggregateState" lastActive="2009-01-14T15:06:10"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/state
>>> "><availability>18000</availability></state>
>>>    </category>
>>>    <category name="state" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="200" version="1"
>>>    expireType="static">
>>>    <state xsi:type="aggregateState"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/state
>>> "><availability>18000</availability></state>
>>>    </category>
>>>    <category name="state" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="100" version="1"
>>>    expireType="static">
>>>    <state xsi:type="aggregateState"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/state
>>> "><availability>18000</availability></state>
>>>    </category>
>>>    <category name="state" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="3" version="1"
>>>    expireType="static">
>>>    <state xsi:type="aggregateState" lastActive="2009-01-14T15:06:10"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/state
>>> "><availability>18000</availability></state>
>>>    </category>
>>>    <category name="state" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="2" version="1"
>>>    expireType="static">
>>>    <state xsi:type="aggregateState" lastActive="2009-01-14T15:06:10"
>>>    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/state
>>> "><availability>18000</availability></state>
>>>    </category>
>>>    <category name="routing" instance="0"
>>>    publishTime="2008-12-03T13:19:10.707" container="32000"
>>>    version="1" expireType="static">
>>>    <routing xmlns="http://schemas.microsoft.com/02/2006/sip/routing"
>>>    name="rtcdefault" version="1" ><preamble ><flags
>>>    name="clientflags" value="block" ></flags></preamble></routing>
>>>    </category>
>>>    <category name="legacyInterop" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="32000"
>>>    version="4" expireType="static">
>>>    <legacyInterop availability="18500" />
>>>    </category>
>>>    <category name="legacyInterop" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="400" version="1"
>>>    expireType="static">
>>>    <legacyInterop availability="18000" />
>>>    </category>
>>>    <category name="legacyInterop" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="300" version="1"
>>>    expireType="static">
>>>    <legacyInterop availability="18000" />
>>>    </category>
>>>    <category name="legacyInterop" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="200" version="1"
>>>    expireType="static">
>>>    <legacyInterop availability="18000" />
>>>    </category>
>>>    <category name="legacyInterop" instance="0"
>>>    publishTime="2009-01-14T15:06:10.723" container="100" version="1"
>>>    expireType="static">
>>>    <legacyInterop availability="18000" />
>>>    </category>
>>>    <category name="services" instance="0"
>>>    publishTime="2008-12-03T13:19:10.797" container="32000"
>>>    version="1" expireType="static">
>>>    <services xmlns="http://schemas.microsoft.com/2006/09/sip/service"
>>>    ></services>
>>>    </category>
>>>    <category name="userInformation" instance="0"
>>>    publishTime="2008-12-10T10:12:32.427" container="1" version="1"
>>>    expireType="static">
>>>    <userInformation
>>>    xmlns="
>>> http://schemas.microsoft.com/2006/09/sip/options/userInformation"
>>>    ><phones ><phone type="work" ><readOnly
>>>    >true</readOnly><displayString >306</displayString><uri
>>>    >tel:306;phone-context=dialstring</uri></phone><phone
>>>    type="mobile" ></phone><phone type="home" ></phone><phone
>>>    type="other" ></phone></phones></userInformation>
>>>    </category>
>>>    <category name="userProperties" instance="0"
>>>    publishTime="2008-12-03T13:18:55.133" container="1" version="4"
>>>    expireType="static">
>>>    <userProperties >
>>>    <lines >
>>>    <line lineType="Uc" >
>>>    tel:306</line>
>>>    </lines>
>>>    <telephonyMode >
>>>    Uc</telephonyMode>
>>>    </userProperties>
>>>    </category>
>>>    </categories>
>>>    <containers
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/containers">
>>>    <container id="32000" version="0"/>
>>>    <container id="400" version="0"/>
>>>    <container id="300" version="0"/>
>>>    <container id="200" version="1">
>>>    <member type="sameEnterprise"/>
>>>    </container>
>>>    <container id="100" version="1">
>>>    <member type="federated"/>
>>>    </container>
>>>    <container id="3" version="0"/>
>>>    <container id="2" version="0"/>
>>>    <container id="1" version="0"/>
>>>    <container id="0" version="0">
>>>    <member type="everyone"/>
>>>    </container>
>>>    </containers>
>>>    <subscribers
>>>    xmlns="http://schemas.microsoft.com/2006/09/sip/presence-subscribers
>>> "/>
>>>    </roamingData>
>>>
>>>
>>>
>>>
>>>    This message makes OpenSips crash on
>>>
>>>    2009/1/15 Bogdan-Andrei Iancu <bogdan at voice-system.ro
>>>    <mailto:bogdan at voice-system.ro>>
>>>
>>>        Hi Antonio,
>>>
>>>        Could you detail a bit please ? who is receiving the 200 OK
>>>        and from where... What is the actual error? Also please
>>>        describe your scenario.
>>>
>>>        Regards,
>>>        Bogdan
>>>
>>>        Antonio Frongillo wrote:
>>>
>>>            Hi,
>>>            I'm experiencing an error in receiving 200 OK of REGISTER
>>>            requests where are set several Contact Header.
>>>            According to Sip Servlet specification it should be
>>>            possible to receive messages with more than one Contact
>>>            Header, actually I saw that with 8 Contact Header OpenSips
>>>            crash always, with 2-3 Contact Header it seems to be stable.
>>>
>>>            Could it be a bug or I'm missing something?
>>>
>>>            Thanks in advance
>>>            Antonio
>>>
>>>  ------------------------------------------------------------------------
>>>
>>>            _______________________________________________
>>>            Users mailing list
>>>            Users at lists.opensips.org <mailto:Users at lists.opensips.org>
>>>            http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>>
>>>
>>> ------------------------------------------------------------------------
>>>
>>> _______________________________________________
>>> 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/20090115/22b9247b/attachment-0001.htm 


More information about the Users mailing list