This is SR 3-2883893441.
This is an issue after CU applied IDR for fix to 6999988
Main data repository
logon any of the cores2 server
/cores_data/pool-1/data3/3-2883678011/
6u23/IDR baseline Run
/cores_data/pool-1/data3/3-2883678011/6u23-IDR-Baseline-Data
This is run with 6u23, bug fix, and Maxtenuringthreshold of 15 and CMSIntiatingFraction of 70.
(c) For the observation made by customer that they see 2 500ms and 1 600ms pause,
qt about where is the 2 500ms and 1 600ms pause, this is found in the parenIDR ie (b) 6u23/IDR baseline Run
a ParNew line entry always had a time measurement that was close to the longeest applicaiton thread stopped line... but in this case they don't match. Now, I can tell you that the application is actually paused for this period of time, because we have a delay detection facility in our application and we are seeing it hit for these periods of time as well. 27313.464: [GC 27313.464: [ParNew: 44288K->4864K(44288K), 0.0263310 secs] 990540K->958012K(1479936K), 0.0265060 secs] [Times: user=0.23 sys=0.00, real=0.02 secs]
Total time for which application threads were stopped: 0.0277370 seconds
Total time for which application threads were stopped: 0.0010190 seconds
Total time for which application threads were stopped: 0.0003290 seconds
Total time for which application threads were stopped: 0.0002740 seconds
Total time for which application threads were stopped: 0.0008720 seconds
Total time for which application threads were stopped: 0.5893170 seconds
27316.525: [GC 27316.525: [ParNew: 44288K->4864K(44288K), 0.0238070 secs] 997436K->966005K(1479936K), 0.0239510 secs] [Times: user=0.20 sys=0.00, real=0.02 secs]
52522.207: [GC 52522.207: [ParNew: 42995K->4513K(44288K), 0.0073580 secs] 897515K->859725K(1479936K), 0.0075040 secs] [Times: user=0.07 sys=0.01, real=0.00 secs]
Total time for which application threads were stopped: 0.0081810 seconds
Total time for which application threads were stopped: 0.0010920 seconds
Total time for which application threads were stopped: 0.0003610 seconds
Total time for which application threads were stopped: 0.0004110 seconds
Total time for which application threads were stopped: 0.0013790 seconds
Total time for which application threads were stopped: 0.5940850 seconds
52523.941: [GC 52523.941: [ParNew: 43937K->4864K(44288K), 0.0141900 secs] 899149K->862578K(1479936K), 0.0143850 secs] [Times: user=0.14 sys=0.00, real=0.01 secs]
63323.810: [GC 63323.810: [ParNew: 43593K->4864K(44288K), 0.0184870 secs] 1015331K->980459K(1479936K), 0.0186860 secs] [Times: user=0.18 sys=0.00, real=0.02 secs]
Total time for which application threads were stopped: 0.0196980 seconds
Total time for which application threads were stopped: 0.0013560 seconds
Total time for which application threads were stopped: 0.6170320 seconds
63326.734: [GC 63326.734: [ParNew: 44288K->4864K(44288K), 0.0286870 secs] 1019883K->988137K(1479936K), 0.0288930 secs] [Times: user=0.24 sys=0.00, real=0.02 secs]
This is an issue after CU applied IDR for fix to 6999988
Main data repository
logon any of the cores2 server
/cores_data/pool-1/data3/3-2883678011/
6u23/IDR baseline Run
/cores_data/pool-1/data3/3-2883678011/6u23-IDR-Baseline-Data
This is run with 6u23, bug fix, and Maxtenuringthreshold of 15 and CMSIntiatingFraction of 70.
(c) For the observation made by customer that they see 2 500ms and 1 600ms pause,
qt about where is the 2 500ms and 1 600ms pause, this is found in the parenIDR ie (b) 6u23/IDR baseline Run
a ParNew line entry always had a time measurement that was close to the longeest applicaiton thread stopped line... but in this case they don't match. Now, I can tell you that the application is actually paused for this period of time, because we have a delay detection facility in our application and we are seeing it hit for these periods of time as well. 27313.464: [GC 27313.464: [ParNew: 44288K->4864K(44288K), 0.0263310 secs] 990540K->958012K(1479936K), 0.0265060 secs] [Times: user=0.23 sys=0.00, real=0.02 secs]
Total time for which application threads were stopped: 0.0277370 seconds
Total time for which application threads were stopped: 0.0010190 seconds
Total time for which application threads were stopped: 0.0003290 seconds
Total time for which application threads were stopped: 0.0002740 seconds
Total time for which application threads were stopped: 0.0008720 seconds
Total time for which application threads were stopped: 0.5893170 seconds
27316.525: [GC 27316.525: [ParNew: 44288K->4864K(44288K), 0.0238070 secs] 997436K->966005K(1479936K), 0.0239510 secs] [Times: user=0.20 sys=0.00, real=0.02 secs]
52522.207: [GC 52522.207: [ParNew: 42995K->4513K(44288K), 0.0073580 secs] 897515K->859725K(1479936K), 0.0075040 secs] [Times: user=0.07 sys=0.01, real=0.00 secs]
Total time for which application threads were stopped: 0.0081810 seconds
Total time for which application threads were stopped: 0.0010920 seconds
Total time for which application threads were stopped: 0.0003610 seconds
Total time for which application threads were stopped: 0.0004110 seconds
Total time for which application threads were stopped: 0.0013790 seconds
Total time for which application threads were stopped: 0.5940850 seconds
52523.941: [GC 52523.941: [ParNew: 43937K->4864K(44288K), 0.0141900 secs] 899149K->862578K(1479936K), 0.0143850 secs] [Times: user=0.14 sys=0.00, real=0.01 secs]
63323.810: [GC 63323.810: [ParNew: 43593K->4864K(44288K), 0.0184870 secs] 1015331K->980459K(1479936K), 0.0186860 secs] [Times: user=0.18 sys=0.00, real=0.02 secs]
Total time for which application threads were stopped: 0.0196980 seconds
Total time for which application threads were stopped: 0.0013560 seconds
Total time for which application threads were stopped: 0.6170320 seconds
63326.734: [GC 63326.734: [ParNew: 44288K->4864K(44288K), 0.0286870 secs] 1019883K->988137K(1479936K), 0.0288930 secs] [Times: user=0.24 sys=0.00, real=0.02 secs]