While troubleshooting a performance issue, we had a need to graph the number of seconds between full collections.<\/p>\n
We used what is below. This assumes you have added -XX:+PrintGCDetails and -XX:+PrintGCDateStamps as well as a file location for -Xloggc: to your startup script. You can use the output in Excel, or pipe it to another scripting language such as awk for additional dynamic reporting.<\/p>\n
The output fields are start time, finish time, seconds for collection to run, and the number of seconds since the completion and the previous cycle and the start of this one.<\/p>\n
\r\n#!\/usr\/bin\/env python\r\n\r\nimport datetime, time, sys\r\nstartTime = 0\r\nfinishTime = 0\r\ngap = -1\r\nfor line in open(sys.argv[1],\"r\"):\r\n if line.find(\"CMS-initial-mark:\") > -1:\r\n start = line.split(\" \")[0].split(\".\")[0]\r\n tmp = line.split(\" \")[0].split(\".\")[0]\r\n startTime = time.mktime(time.strptime(tmp,'%Y-%m-%dT%H:%M:%S'))\r\n if finishTime != 0:\r\n gap = startTime - finishTime\r\n elif line.find(\"CMS-concurrent-reset:\") > -1:\r\n finish = line.split(\" \")[0].split(\".\")[0]\r\n tmp = time.strptime(line.split(\" \")[0].split(\".\")[0],'%Y-%m-%dT%H:%M:%S')\r\n finishTime = time.mktime(tmp)\r\n duration = finishTime - startTime\r\n if gap != -1:\r\n print start,finish,duration, gap\r\n<\/pre>\n","protected":false},"excerpt":{"rendered":"While troubleshooting a performance issue, we had a need to graph the number of seconds between full collections. We used what is below. This assumes you have added -XX:+PrintGCDetails and -XX:+PrintGCDateStamps as well as a file location for -Xloggc: to…<\/p>\n