[OpenSIPS-Users] Contact Header Crash
Antonio Frongillo
frog831 at gmail.com
Fri Jan 16 11:48:45 CET 2009
You're welcome.
I really appreciate if you or someone from the seas module guys will keep me
up to date about when the fix will be available and which kind of fix they
will do on the module (put the size in the configuration file of OpenSips,
make it bigger but keep it configurable only in the source code).
Thanks in advance,
Antonio
2009/1/16 Bogdan-Andrei Iancu <bogdan at voice-system.ro>
> Hi Antonio,
>
> Thanks for trouble shooting - you are correct, the module should be proofed
> to such issues (like insufficient buffer) and not crash. I will fwd this to
> the people working on this module.
>
> regards,
> Bogdan
>
> Antonio Frongillo wrote:
>
>> 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 <mailto:
>> 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
>> <mailto: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
>> <mailto: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> <mailto: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>
>> <mailto: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>
>> <mailto: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 <mailto: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/20090116/3c56c64d/attachment-0001.htm
More information about the Users
mailing list