fuimaz / spserver

Automatically exported from code.google.com/p/spserver
Other
0 stars 0 forks source link

testecho killed by ubuntu #47

Open GoogleCodeExporter opened 9 years ago

GoogleCodeExporter commented 9 years ago
If you are not sure whether this is a bug, please go to
http://groups.google.com/group/spserver to request help.

What steps will reproduce the problem?
1. run testecho(change sp_session uint16_t to unsigned int).
2. use a multi-thread client in other client to do connect, send,recv (don't 
close fd until program exit.
3. about 100,000 connections, ubuntu looks very slowly, and killed testecho.

I use top. there is 700M memory free(Total 2G), and CPU only takes 30%. 

What is the expected output? What do you see instead?
   work normally.
   testecho was killed by system.

What version of the product are you using? On what operating system?
0.9.5 under ubuntu 10.10

Please provide any additional information below.
Jul 18 21:00:24 opensips kernel: [10762.781159] Mem-Info:
Jul 18 21:00:24 opensips kernel: [10762.781160] DMA per-cpu:
Jul 18 21:00:24 opensips kernel: [10762.781162] CPU    0: hi:    0, btch:   1 
usd:   0
Jul 18 21:00:24 opensips kernel: [10762.781164] CPU    1: hi:    0, btch:   1 
usd:   0
Jul 18 21:00:24 opensips kernel: [10762.781166] Normal per-cpu:
Jul 18 21:00:24 opensips kernel: [10762.781168] CPU    0: hi:  186, btch:  31 
usd:  40
Jul 18 21:00:24 opensips kernel: [10762.781170] CPU    1: hi:  186, btch:  31 
usd:  30
Jul 18 21:00:24 opensips kernel: [10762.781171] HighMem per-cpu:
Jul 18 21:00:24 opensips kernel: [10762.781173] CPU    0: hi:  186, btch:  31 
usd: 155
Jul 18 21:00:24 opensips kernel: [10762.781175] CPU    1: hi:  186, btch:  31 
usd:  21
Jul 18 21:00:24 opensips kernel: [10762.781178] active_anon:46948 
inactive_anon:16673 isolated_anon:0
Jul 18 21:00:24 opensips kernel: [10762.781180]  active_file:11359 
inactive_file:20878 isolated_file:0
Jul 18 21:00:24 opensips kernel: [10762.781181]  unevictable:0 dirty:14 
writeback:0 unstable:0
Jul 18 21:00:24 opensips kernel: [10762.781182]  free:190739 
slab_reclaimable:19024 slab_unreclaimable:57150
Jul 18 21:00:24 opensips kernel: [10762.781183]  mapped:13250 shmem:9981 
pagetables:1152 bounce:0
Jul 18 21:00:24 opensips kernel: [10762.781188] DMA free:3524kB min:64kB 
low:80kB high:96kB active_anon:0kB inactive_anon:0kB active_file:0kB 
inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB 
present:15808kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB 
slab_reclaimable:356kB slab_unreclaimable:1084kB kernel_stack:0kB 
pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 
all_unreclaimable? yes
Jul 18 21:00:24 opensips kernel: [10762.781194] lowmem_reserve[]: 0 865 1980 
1980
Jul 18 21:00:24 opensips kernel: [10762.781201] Normal free:3664kB min:3728kB 
low:4660kB high:5592kB active_anon:0kB inactive_anon:0kB active_file:220kB 
inactive_file:40kB unevictable:0kB isolated(anon):0kB isolated(file):0kB 
present:885944kB mlocked:0kB dirty:0kB writeback:0kB mapped:4kB shmem:0kB 
slab_reclaimable:75740kB slab_unreclaimable:227516kB kernel_stack:2456kB 
pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:450 
all_unreclaimable? yes
Jul 18 21:00:24 opensips kernel: [10762.781207] lowmem_reserve[]: 0 0 8922 8922
Jul 18 21:00:24 opensips kernel: [10762.781214] HighMem free:755768kB min:512kB 
low:1712kB high:2912kB active_anon:187792kB inactive_anon:66692kB 
active_file:45216kB inactive_file:83472kB unevictable:0kB isolated(anon):0kB 
isolated(file):0kB present:1142020kB mlocked:0kB dirty:56kB writeback:0kB 
mapped:52996kB shmem:39924kB slab_reclaimable:0kB slab_unreclaimable:0kB 
kernel_stack:0kB pagetables:4608kB unstable:0kB bounce:0kB writeback_tmp:0kB 
pages_scanned:0 all_unreclaimable? no
Jul 18 21:00:24 opensips kernel: [10762.781220] lowmem_reserve[]: 0 0 0 0
Jul 18 21:00:24 opensips kernel: [10762.781223] DMA: 5*4kB 4*8kB 5*16kB 10*32kB 
14*64kB 17*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 3524kB
Jul 18 21:00:24 opensips kernel: [10762.781232] Normal: 228*4kB 66*8kB 5*16kB 
1*32kB 1*64kB 0*128kB 2*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3664kB
Jul 18 21:00:24 opensips kernel: [10762.781239] HighMem: 564*4kB 637*8kB 
784*16kB 488*32kB 364*64kB 101*128kB 40*256kB 14*512kB 5*1024kB 3*2048kB 
160*4096kB = 755768kB
Jul 18 21:00:24 opensips kernel: [10762.781248] 42223 total pagecache pages
Jul 18 21:00:24 opensips kernel: [10762.781249] 0 pages in swap cache
Jul 18 21:00:24 opensips kernel: [10762.781251] Swap cache stats: add 0, delete 
0, find 0/0
Jul 18 21:00:24 opensips kernel: [10762.781253] Free swap  = 6141948kB
Jul 18 21:00:24 opensips kernel: [10762.781254] Total swap = 6141948kB
Jul 18 21:00:24 opensips kernel: [10762.781254] Total swap = 6141948kB
Jul 18 21:00:24 opensips kernel: [10762.784591] 515079 pages RAM
Jul 18 21:00:24 opensips kernel: [10762.784593] 287754 pages HighMem
Jul 18 21:00:24 opensips kernel: [10762.784595] 9031 pages reserved
Jul 18 21:00:24 opensips kernel: [10762.784596] 82449 pages shared
Jul 18 21:00:24 opensips kernel: [10762.784597] 294015 pages non-shared
Jul 18 21:00:24 opensips kernel: [10762.784600] Out of memory: kill process 
1369 (gnome-session) score 65119 or a child
Jul 18 21:00:24 opensips kernel: [10762.784604] Killed process 1521 
(gnome-panel) vsz:86088kB, anon-rss:3644kB, file-rss:13840kB
Jul 18 21:00:24 opensips kernel: [10762.855130] Xorg invoked oom-killer: 
gfp_mask=0xd0, order=0, oom_adj=0
Jul 18 21:00:24 opensips kernel: [10762.855134] Xorg cpuset=/ mems_allowed=0
Jul 18 21:00:24 opensips kernel: [10762.855137] Pid: 1049, comm: Xorg Not 
tainted 2.6.35-30-generic #54-Ubuntu
Jul 18 21:00:24 opensips kernel: [10762.855139] Call Trace:
Jul 18 21:00:24 opensips kernel: [10762.855146]  [<c01dd51a>] 
dump_header+0x7a/0xb0
Jul 18 21:00:24 opensips kernel: [10762.855149]  [<c01dd5ac>] 
oom_kill_process+0x5c/0x160
Jul 18 21:00:24 opensips kernel: [10762.855151]  [<c01ddb19>] ? 
select_bad_process+0xa9/0xe0
Jul 18 21:00:24 opensips kernel: [10762.855154]  [<c01ddba1>] 
__out_of_memory+0x51/0xb0
Jul 18 21:00:24 opensips kernel: [10762.855156]  [<c01ddc58>] 
out_of_memory+0x58/0xd0
Jul 18 21:00:24 opensips kernel: [10762.855159]  [<c01e0b86>] 
__alloc_pages_slowpath+0x496/0x4b0
Jul 18 21:00:24 opensips kernel: [10762.855162]  [<c01e0d0f>] 
__alloc_pages_nodemask+0x16f/0x1c0
Jul 18 21:00:24 opensips kernel: [10762.855164]  [<c01e0d7c>] 
__get_free_pages+0x1c/0x30
Jul 18 21:00:24 opensips kernel: [10762.855167]  [<c02299c1>] 
__pollwait+0xa1/0xe0
Jul 18 21:00:24 opensips kernel: [10762.855169]  [<c0229985>] ? 
__pollwait+0x65/0xe0
Jul 18 21:00:24 opensips kernel: [10762.855173]  [<c0571b1c>] 
unix_poll+0x1c/0xa0
Jul 18 21:00:24 opensips kernel: [10762.855176]  [<c04e72f4>] 
sock_poll+0x14/0x20
Jul 18 21:00:24 opensips kernel: [10762.855178]  [<c022962b>] 
do_select+0x37b/0x670
Jul 18 21:00:24 opensips kernel: [10762.855181]  [<c03535dd>] ? 
kobject_put+0x1d/0x50
Jul 18 21:00:24 opensips kernel: [10762.855183]  [<c0229920>] ? 
__pollwait+0x0/0xe0
Jul 18 21:00:24 opensips kernel: [10762.855185]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855188]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855190]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855192]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855194]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855196]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855198]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855200]  [<c0229a00>] ? 
pollwake+0x0/0x60
Jul 18 21:00:24 opensips kernel: [10762.855202]  [<c0229a00>] ? 
pollwake+0x0/0x60

Jul 18 21:00:24 opensips kernel: [10762.855223]  [<c0229fb0>] 
core_sys_select+0x140/0x240
Jul 18 21:00:24 opensips kernel: [10762.855226]  [<c016b2da>] ? 
hrtimer_try_to_cancel+0x3a/0xc0
Jul 18 21:00:24 opensips kernel: [10762.855229]  [<c0227bb2>] ? 
vfs_ioctl+0x32/0xb0
Jul 18 21:00:24 opensips kernel: [10762.855231]  [<c022a2b1>] 
sys_select+0x31/0xc0
Jul 18 21:00:24 opensips kernel: [10762.855233]  [<c0165660>] ? 
sys_clock_gettime+0x50/0xa0
Jul 18 21:00:24 opensips kernel: [10762.855236]  [<c05cc254>] 
syscall_call+0x7/0xb
Jul 18 21:00:24 opensips kernel: [10762.855239]  [<c05c0000>] ? 
pcibios_setup+0x93/0x3ac
Jul 18 21:00:24 opensips kernel: [10762.855241] Mem-Info:
Jul 18 21:00:24 opensips kernel: [10762.855242] DMA per-cpu:
Jul 18 21:00:24 opensips kernel: [10762.855244] CPU    0: hi:    0, btch:   1 
usd:   0
Jul 18 21:00:24 opensips kernel: [10762.855245] CPU    1: hi:    0, btch:   1 
usd:   0
Jul 18 21:00:24 opensips kernel: [10762.855247] Normal per-cpu:
Jul 18 21:00:24 opensips kernel: [10762.855248] CPU    0: hi:  186, btch:  31 
usd:  31
Jul 18 21:00:24 opensips kernel: [10762.855250] CPU    1: hi:  186, btch:  31 
usd:  30
Jul 18 21:00:24 opensips kernel: [10762.855251] HighMem per-cpu:
Jul 18 21:00:24 opensips kernel: [10762.855252] CPU    0: hi:  186, btch:  31 
usd: 175
Jul 18 21:00:24 opensips kernel: [10762.855254] CPU    1: hi:  186, btch:  31 
usd:   0
Jul 18 21:00:24 opensips kernel: [10762.855257] active_anon:42267 
inactive_anon:16487 isolated_anon:0
Jul 18 21:00:24 opensips kernel: [10762.855258]  active_file:12010 
inactive_file:18540 isolated_file:33
Jul 18 21:00:24 opensips kernel: [10762.855259]  unevictable:0 dirty:14 
writeback:0 unstable:0
Jul 18 21:00:24 opensips kernel: [10762.855259]  free:197553 
slab_reclaimable:19004 slab_unreclaimable:57150
Jul 18 21:00:24 opensips kernel: [10762.855260]  mapped:12134 shmem:9764 
pagetables:935 bounce:0
Jul 18 21:00:24 opensips kernel: [10762.855265] DMA free:3524kB min:64kB 
low:80kB high:96kB active_anon:0kB inactive_anon:0kB active_file:0kB 
inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB 
present:15808kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB 
slab_reclaimable:356kB slab_unreclaimable:1084kB kernel_stack:0kB 
pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 
all_unreclaimable? no
Jul 18 21:00:24 opensips kernel: [10762.855268] lowmem_reserve[]: 0 865 1980 
1980
Jul 18 21:00:24 opensips kernel: [10762.855274] Normal free:3684kB min:3728kB 
low:4660kB high:5592kB active_anon:0kB inactive_anon:0kB active_file:96kB 
inactive_file:236kB unevictable:0kB isolated(anon):0kB isolated(file):132kB 
present:885944kB mlocked:0kB dirty:0kB writeback:0kB mapped:4kB shmem:0kB 
slab_reclaimable:75660kB slab_unreclaimable:227516kB kernel_stack:2456kB 
pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:559 
all_unreclaimable? no
Jul 18 21:00:24 opensips kernel: [10762.855278] lowmem_reserve[]: 0 0 8922 8922
Jul 18 21:00:24 opensips kernel: [10762.855284] HighMem free:783004kB min:512kB 
low:1712kB high:2912kB active_anon:169068kB inactive_anon:65948kB 
active_file:47944kB inactive_file:73924kB unevictable:0kB isolated(anon):0kB 
isolated(file):0kB present:1142020kB mlocked:0kB dirty:56kB writeback:0kB 
mapped:48532kB shmem:39056kB slab_reclaimable:0kB slab_unreclaimable:0kB 
kernel_stack:0kB pagetables:3740kB unstable:0kB bounce:0kB writeback_tmp:0kB 
pages_scanned:0 all_unreclaimable? no
Jul 18 21:00:24 opensips kernel: [10762.855287] lowmem_reserve[]: 0 0 0 0
Jul 18 21:00:24 opensips kernel: [10762.855290] DMA: 5*4kB 4*8kB 5*16kB 10*32kB 
14*64kB 17*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 3524kB
Jul 18 21:00:24 opensips kernel: [10762.855298] Normal: 207*4kB 67*8kB 11*16kB 
1*32kB 1*64kB 0*128kB 2*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3684kB

Original issue reported on code.google.com by vivid333...@gmail.com on 19 Jul 2011 at 2:13