Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-7017742

safepoint pause anomalies

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Not an Issue
    • Icon: P3 P3
    • 6-pool
    • 6u23
    • hotspot
    • x86
    • linux

      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]

            poonam Poonam Bajaj Parhar
            lkchow Lawrence Chow
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Created:
              Updated:
              Resolved:
              Imported:
              Indexed: