BloodyDeed wrote:
I think this mapchange thing is a bug on 64bit Linux, there are a few admins with this issues two and I dont think somebody found a fix for that. But it also doesnt look like this is causing more issues.
This is what happenes just before that "delay".. mprotect is called
Code: Select all
[pid 31815] 0.000035 times({tms_utime=32523, tms_stime=231, tms_cutime=0, tms_cstime=0}) = 1607539251
[pid 31815] 0.000068 mprotect(0x2aaac5ac3000, 114688, PROT_READ|PROT_WRITE) = 0
[pid 31815] 0.008068 mprotect(0x2aaac5adf000, 524288, PROT_READ|PROT_WRITE) = 0
[pid 31818] 0.007957 <... select resumed> ) = 0 (Timeout)
[pid 31818] 0.000039 select(22, [21], NULL, NULL, {3, 0} <unfinished ...>
[pid 31818] 0.004946 <... select resumed> ) = 0 (Timeout)
<<< BREAK where CPU load goes high and server sits doing nothing for 1min
after this weird wait time it will continue load map without problem and does it pretty fast >>>
[pid 31818] 0.000041 select(22, [21], NULL, NULL, {3, 0} <unfinished ...>
[pid 31818] 0.006002 <... select resumed> ) = 0 (Timeout)
[pid 31818] 0.000045 select(22, [21], NULL, NULL, {3, 0} <unfinished ...>
[pid 31818] 0.002997 <... select resumed> ) = 0 (Timeout)
[pid 31818] 0.000044 select(22, [21], NULL, NULL, {3, 0} <unfinished ...>
[pid 31815] 0.006507 munmap(0x2aaab1756000, 2105344) = 0
[pid 31815] 0.001656 close(23) = 0
[pid 31815] 0.000043 munmap(0x2aaaaaab1000, 4096) = 0
[pid 31815] 0.000712 open("mods/pr/gamelogicinit.con", O_RDONLY) = 23
[pid 31815] 0.000064 lseek(23, 0, SEEK_CUR) = 0
[pid 31815] 0.000030 lseek(23, 0, SEEK_END) = 323
[pid 31815] 0.000028 lseek(23, 0, SEEK_SET) = 0
[pid 31815] 0.000128 read(23, "rem *** Put non level specific i"..., 323) = 323
and also cpu timings of bf2 server including mapchange:
Code: Select all
% time seconds usecs/call calls errors syscall
------ ----------- ----------- --------- --------- ----------------
57.84 0.001657 0 32991 read
33.58 0.000962 0 42967 lseek
4.47 0.000128 0 601 brk
1.64 0.000047 0 120 sendto
1.26 0.000036 0 1109 1102 open
0.49 0.000014 1 17 13 stat
0.38 0.000011 0 262 rt_sigaction
0.35 0.000010 0 1319 1210 recvfrom
0.00 0.000000 0 52 write
0.00 0.000000 0 7 close
0.00 0.000000 0 10 fstat
0.00 0.000000 0 6 mmap
0.00 0.000000 0 1 mprotect
0.00 0.000000 0 5 munmap
0.00 0.000000 0 1 ioctl
0.00 0.000000 0 3080 select
0.00 0.000000 0 1420 nanosleep
0.00 0.000000 0 2 socket
0.00 0.000000 0 1212 1212 accept
0.00 0.000000 0 2 bind
0.00 0.000000 0 2 setsockopt
0.00 0.000000 0 1 clone
0.00 0.000000 0 4 fcntl
0.00 0.000000 0 397 times
------ ----------- ----------- --------- --------- ----------------
100.00 0.002865 85588 3537 total
?
??:
