JDK-6487194 : Stress gc tests fail with timeout with -XX:+UseParallelOldGC
  • Type: Bug
  • Component: hotspot
  • Sub-Component: gc
  • Affected Version: 6
  • Priority: P3
  • Status: Closed
  • Resolution: Duplicate
  • OS: solaris
  • CPU: sparc
  • Submitted: 2006-10-27
  • Updated: 2012-02-02
  • Resolved: 2007-06-28
Related Reports
Duplicate :  
Description
During weekly execution for b103 following GC stress tests failed with timeout:
	gc/vector/CircularListLow  execute_positive 
	gc/vector/LinearListLow  execute_positive 

Part of log for test 'CircularListLow' obtained with -XX:+PrintGCDetails:
[Full GC [PSYoungGen: 58688K->58580K(117440K)] [ParOldGen: 137095K->136891K(225280K)] 195783K->195472K(342720K) [PSPermGen: 2576K->2576K(24576K)], 15.1784248 secs]
[GC [PSYoungGen: 117332K->58688K(117440K)] 254224K->254563K(342720K), 4.7710120 secs]
[Full GC [PSYoungGen: 58688K->58580K(117440K)] [ParOldGen: 195875K->195640K(311296K)] 254563K->254221K(428736K) [PSPermGen: 2576K->2576K(24576K)], 15.9133711 secs]
[GC [PSYoungGen: 117332K->58688K(117440K)] 312973K->313312K(428736K), 7.2484888 secs]
[Full GC [PSYoungGen: 58688K->58580K(117440K)] [ParOldGen: 254624K->254390K(352256K)] 313312K->312970K(469696K) [PSPermGen: 2576K->2576K(24576K)], 17.3586941 secs]
[GC [PSYoungGen: 117332K->58688K(117440K)] 371722K->372062K(469696K), 6.5165064 secs]
[Full GC [PSYoungGen: 58688K->58580K(117440K)] [ParOldGen: 313374K->313139K(352256K)] 372062K->371719K(469696K) [PSPermGen: 2576K->2576K(24576K)], 22.8003188 secs]
Iteration: 1
[Full GC [PSYoungGen: 117332K->0K(117440K)] [ParOldGen: 313139K->47198K(233472K)] 430471K->47198K(350912K) [PSPermGen: 2579K->2579K(24576K)], 4.1680265 secs]
[GC [PSYoungGen: 58752K->58688K(117440K)] 105950K->106062K(350912K), 1.7017497 secs]
[GC [PSYoungGen: 117440K->58688K(117440K)] 164814K->165046K(350912K), 4.5650666 secs]
[GC [PSYoungGen: 117440K->58688K(117440K)] 223798K->224030K(350912K), 3.6623916 secs]
[Full GC [PSYoungGen: 58688K->0K(117440K)] [ParOldGen: 165342K->223446K(352256K)] 224030K->223446K(469696K) [PSPermGen: 2579K->2579K(24576K)], 22.6637319 secs]
[GC [PSYoungGen: 58752K->58688K(117440K)] 282198K->282310K(469696K), 2.3911565 secs]
[GC [PSYoungGen: 117440K->58688K(117440K)] 341062K->341294K(469696K), 4.7940441 secs]
[Full GC [PSYoungGen: 58688K->0K(117440K)] [ParOldGen: 282606K->340944K(352256K)] 341294K->340944K(469696K) [PSPermGen: 2579K->2579K(24576K)], 20.2495083 secs]
Iteration: 2
[Full GC [PSYoungGen: 58752K->0K(117440K)] [ParOldGen: 340944K->16425K(290816K)] 399696K->16425K(408256K) [PSPermGen: 2579K->2579K(24576K)], 1.8530507 secs]
[GC [PSYoungGen: 58752K->58688K(117440K)] 75177K->75289K(408256K), 1.6256450 secs]
[GC [PSYoungGen: 117440K->58688K(117440K)] 134041K->134273K(408256K), 3.5813268 secs]
[GC [PSYoungGen: 117440K->58688K(117440K)] 193025K->193257K(408256K), 4.8494084 secs]
[GC [PSYoungGen: 117440K->58688K(117440K)] 252009K->252241K(408256K), 4.2659997 secs]
[Full GC [PSYoungGen: 58688K->0K(117440K)] [ParOldGen: 193553K->251422K(352256K)] 252241K->251422K(469696K) [PSPermGen: 2579K->2579K(24576K)], 19.9804641 secs]

With -XX:+UseParallelOldGC tests can't complete in 30min, without this options tests take < 1min.

To reproduce:
	ssh vm-b1600s-2.sfbay
	cd /net/vmsqe.sfbay.sun.com/export/weekly/mustang/results/1.6.0-rc-b103/ServerVM/64BITSOLSPARC5.10/batch/VM1/vm.gc-26-WEEKLYmtg-VM1-ServerVM-batch-64BITSOLSPARC5.10-2006-10-26-03-29-03/ResultDir/CircularListLow
	sh rerun.sh
Another group of tests failed because of timeout:
	nsk/monitoring/stress/classload/unload001  execute_positive 
	nsk/monitoring/stress/classload/unload002  execute_positive 
	nsk/monitoring/stress/classload/unload003  execute_positive 
	nsk/monitoring/stress/classload/unload004  execute_positive 
	nsk/monitoring/stress/classload/unload005  execute_positive 
	nsk/monitoring/stress/classload/unload006  execute_positive 

This tests failed on vm-v100-01.sfbay. I rerun test 'unload001' and it takes ~50min with -XX:+UseParallelOldGC and ~8min without this flag.

To reproduce:
	ssh vm-v100-01.sfbay
	cd /net/vmsqe.sfbay.sun.com/export/weekly/mustang/results/1.6.0-rc-b103/ServerVM/64BITSOLSPARC5.10/batch/VM1/nsk.monitoring-26-WEEKLYmtg-VM1-ServerVM-batch-64BITSOLSPARC5.10-2006-10-26-02-56-11/ResultDir/unload001
	sh rerun.sh
There was error in directories - '/CUR_DIR/' was changed to '/1.6.0-rc-b103/'.

Comments
EVALUATION Same as 6330863; once the heap becomes nearly full, we do a full gc for each allocation.
28-06-2007