|
From: Richard Frith-M. <ri...@br...> - 2006-01-16 12:27:16
|
On 16 Jan 2006, at 11:44, Tom Hughes wrote: > In message <BBC...@br...> > Richard Frith-Macdonald <ri...@br...> wrote: > >> On 16 Jan 2006, at 10:52, Tom Hughes wrote: >> >>> In message <88F...@br...> >>> Richard Frith-Macdonald <ri...@br...> wrote: >>> >>>> I just tried adding '--error-limit=no' to the command line to start >>>> tomcat. It generates a bigger log file ... but the log file has >>>> stopped growing, though the valgrind/tomcat process looks like >>>> it is >>>> in a loop of some sort (ie a 'ps' shows it in an 'R' state). >>>> The log file stopped growing at 10:13, but it's now 10:40 and 'ps' >>>> shows the the process has now used over 28 minutes of CPU time. >>> >>> Well what does strace say it is doing? What about --trace- >>> syscalls=yes? >> >> I hadn't thought of that ... looks like a signal handling problem of >> some sort ... after adding '--trace-syscalls=yes' I get >> 'SYSCALL[8149,1]( 15) rt_sigreturn ( ) --> [pre-success] Success >> (0x0)' >> repeated indefinitely at the end of the log file. The log file to >> that point is about 900KB. > > Looks like a signal handling issue then - add --trace-signals=yes > and see what that gets you. OK ... segmentation violation ... but I don't know where/why. Does the following (the last six lines are the ones that repeat forever) make any sense? SYSCALL[29972,1](202) sys_futex ( 0x141E5244, 1, 1, 0x508CB10, 0x141E5240 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x141E5240, 0, 2, 0x0, 0x141E4200 ) --> [async] ... SYSCALL[29972,1](202) ... [async] --> Success(0x1) SYSCALL[29972,1](202) sys_futex ( 0x141E5240, 1, 1, 0x508CB10, 0x141E5240 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x141E5240, 1, 1, 0x0, 0x141E4200 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x477FFE0, 0, 2, 0x0, 0x477FFE0 ) -- > [async] ... SYSCALL[29972,1](202) ... [async] --> Success(0x1) SYSCALL[29972,1](202) sys_futex ( 0x477FFE0, 1, 1, 0x508CB10, 0x141E5240 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x477FFE0, 1, 1, 0x50C6070, 0x477FFE0 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6]( 96) sys_gettimeofday ( 0x147C6010, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,6]( 96) sys_gettimeofday ( 0x147C6010, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x141E5244, 0, 9, 0x0, 0x141E4200 ) --> [async] ... SYSCALL[29972,1](202) ... [async] --> Success(0x1) ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70E354CD: ??? ==29972== Address 0x7FEFF7B08 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC49: ??? ==29972== by 0x70E35503: ??? ==29972== Address 0x7FEFFCA60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC50: ??? ==29972== by 0x70E35503: ??? ==29972== Address 0x7FEFFBA60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC57: ??? ==29972== by 0x70E35503: ??? ==29972== Address 0x7FEFFAA60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC5E: ??? ==29972== by 0x70E35503: ??? ==29972== Address 0x7FEFF9A60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC65: ??? ==29972== by 0x70E35503: ??? ==29972== Address 0x7FEFF8A60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC6C: ??? ==29972== by 0x70E35503: ??? ==29972== Address 0x7FEFF7A60 is not stack'd, malloc'd or (recently) free'd SYSCALL[29972,1](202) sys_futex ( 0x141E5244, 1, 1, 0x508CB10, 0x141E5240 ) --> [async] ... SYSCALL[29972,10](202) ... [async] --> Failure(0x6E) SYSCALL[29972,10](202) sys_futex ( 0x14BC9E78, 1, 1, 0x14BC9DD8, 0x14BC9E78 ) --> [async] ... SYSCALL[29972,10](202) ... [async] --> Success(0x0) SYSCALL[29972,10]( 96) sys_gettimeofday ( 0x14BC9E90, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,10]( 96) sys_gettimeofday ( 0x14BC9E00, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,10](228) sys_clock_gettime( 0, 0x14BC9DD8 )[sync] --> Success(0x0) SYSCALL[29972,10](202) sys_futex ( 0x15415C64, 0, 1, 0x14BC9DD8, 0x14BC9E78 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x141E5240, 0, 2, 0x0, 0x141E4200 ) --> [async] ... SYSCALL[29972,1](202) ... [async] --> Success(0x1) SYSCALL[29972,1](202) sys_futex ( 0x141E5240, 1, 1, 0x508CB10, 0x141E5240 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x141E5240, 1, 1, 0x0, 0x141E4200 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x477FFE0, 0, 2, 0x0, 0x477FFE0 ) -- > [async] ... SYSCALL[29972,1](202) ... [async] --> Success(0x1) SYSCALL[29972,1](202) sys_futex ( 0x477FFE0, 1, 1, 0x508CB10, 0x141E5240 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x477FFE0, 1, 1, 0x50C6070, 0x477FFE0 ) --> [async] ... SYSCALL[29972,6](202) ... [async] --> Success(0x0) SYSCALL[29972,6]( 96) sys_gettimeofday ( 0x147C6010, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,6]( 96) sys_gettimeofday ( 0x147C6010, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,6](202) sys_futex ( 0x141E5244, 0, 11, 0x0, 0x141E4200 ) --> [async] ... SYSCALL[29972,1](202) ... [async] --> Success(0x1) ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70E3524D: ??? ==29972== Address 0x7FEFF7B08 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC49: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFFCA88 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC50: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFFBA88 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC57: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFFAA88 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC5E: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFF9A88 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC65: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFF8A88 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC6C: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFF7A88 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70E31840: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFF7A78 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70E31F80: ??? ==29972== by 0x70E3527B: ??? ==29972== Address 0x7FEFF7A48 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70E33B4D: ??? ==29972== Address 0x7FEFF7B08 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC49: ??? ==29972== by 0x70E33B7F: ??? ==29972== Address 0x7FEFFCA60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC50: ??? ==29972== by 0x70E33B7F: ??? ==29972== Address 0x7FEFFBA60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC57: ??? ==29972== by 0x70E33B7F: ??? ==29972== Address 0x7FEFFAA60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC5E: ??? ==29972== by 0x70E33B7F: ??? ==29972== Address 0x7FEFF9A60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC65: ??? ==29972== by 0x70E33B7F: ??? ==29972== Address 0x7FEFF8A60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid write of size 4 ==29972== at 0x70DDDC6C: ??? ==29972== by 0x70E33B7F: ??? ==29972== Address 0x7FEFF7A60 is not stack'd, malloc'd or (recently) free'd ==29972== ==29972== Invalid read of size 8 ==29972== at 0x70E3F260: ??? ==29972== Address 0x8 is not stack'd, malloc'd or (recently) free'd --29972-- signal 11 arrived ... si_code=1, EIP=0x70E3F260, eip=0x405C4F34B --29972-- SIGSEGV: si_code=1 faultaddr=0x8 tid=1 ESP=0x7FEFFDB10 seg=0x0-0x3FFFFFF --29972-- delivering signal 11 (SIGSEGV):1 to thread 1 --29972-- push_signal_frame (thread 1): signal 11 ==29972== ==29972== Conditional jump or move depends on uninitialised value(s) ==29972== at 0x4E31AEB: SharedRuntime::continuation_for_implicit_exception(JavaThread*, unsigned char*, SharedRuntime::ImplicitExceptionKind) (in /usr/local/ jdk1.5.0_06/jre/lib/amd64/server/libjvm.so) ==29972== by 0x4DCA625: JVM_handle_linux_signal (in /usr/local/ jdk1.5.0_06/jre/lib/amd64/server/libjvm.so) ==29972== by 0x4DC812D: signalHandler(int, siginfo*, void*) (in / usr/local/jdk1.5.0_06/jre/lib/amd64/server/libjvm.so) ==29972== by 0x432B79F: (within /lib/libpthread-2.3.5.so) SYSCALL[29972,1]( 15) rt_sigreturn ( )--29972-- VG_(signal_return) (thread 1): isRT=1 valid magic; RIP=0x70E3F260 --> [pre-success] Success(0x0) --29972-- signal 11 arrived ... si_code=1, EIP=0x70E3F260, eip=0x405C4F34B --29972-- SIGSEGV: si_code=1 faultaddr=0x8 tid=1 ESP=0x7FEFFDB10 seg=0x0-0x3FFFFFF --29972-- delivering signal 11 (SIGSEGV):1 to thread 1 --29972-- push_signal_frame (thread 1): signal 11 SYSCALL[29972,1]( 15) rt_sigreturn ( )--29972-- VG_(signal_return) (thread 1): isRT=1 valid magic; RIP=0x70E3F260 --> [pre-success] Success(0x0) --29972-- signal 11 arrived ... si_code=1, EIP=0x70E3F260, eip=0x405C4F34B --29972-- SIGSEGV: si_code=1 faultaddr=0x8 tid=1 ESP=0x7FEFFDB10 seg=0x0-0x3FFFFFF --29972-- delivering signal 11 (SIGSEGV):1 to thread 1 --29972-- push_signal_frame (thread 1): signal 11 SYSCALL[29972,10](202) ... [async] --> Failure(0x6E) SYSCALL[29972,10](202) sys_futex ( 0x14BC9E78, 1, 1, 0x14BC9DD8, 0x14BC9E78 ) --> [async] ... SYSCALL[29972,10](202) ... [async] --> Success(0x0) SYSCALL[29972,10]( 96) sys_gettimeofday ( 0x14BC9E90, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,10]( 96) sys_gettimeofday ( 0x14BC9E00, 0x0 )[sync] --> Success(0x0) SYSCALL[29972,10](228) sys_clock_gettime( 0, 0x14BC9DD8 )[sync] --> Success(0x0) SYSCALL[29972,10](202) sys_futex ( 0x48071D4, 0, 1, 0x14BC9DD8, 0x14BC9E78 ) --> [async] ... SYSCALL[29972,1]( 15) rt_sigreturn ( )--29972-- VG_(signal_return) (thread 1): isRT=1 valid magic; RIP=0x70E3F260 --> [pre-success] Success(0x0) --29972-- signal 11 arrived ... si_code=1, EIP=0x70E3F260, eip=0x405C4F34B --29972-- SIGSEGV: si_code=1 faultaddr=0x8 tid=1 ESP=0x7FEFFDB10 seg=0x0-0x3FFFFFF --29972-- delivering signal 11 (SIGSEGV):1 to thread 1 --29972-- push_signal_frame (thread 1): signal 11 |