Created
December 17, 2015 02:18
-
-
Save mattparlane/fb98509caefbfc96ff96 to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
<?xml version="1.0" ?> | |
<verbosegc xmlns="http://www.ibm.com/j9/verbosegc" version="Preview"> | |
<exclusive-start id="1" timestamp="2015-12-17T02:16:21.389" intervalms="42.208"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="2" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:21.389" intervalms="42.392" /> | |
<cycle-start id="3" type="global" contextid="0" timestamp="2015-12-17T02:16:21.389" intervalms="42.510" /> | |
<gc-start id="4" type="global" contextid="3" timestamp="2015-12-17T02:16:21.389"> | |
<mem-info id="5" free="0" total="4194304" percent="0"> | |
<mem type="tenure" free="0" total="4194304" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="4192632" > | |
<allocated-bytes non-tlh="16416" tlh="4176216" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="4192632" /> | |
</allocation-stats> | |
<gc-op id="6" type="mark" timems="2.367" contextid="3" timestamp="2015-12-17T02:16:21.392"> | |
<trace-info objectcount="20646" scancount="17574" scanbytes="704640" /> | |
</gc-op> | |
<gc-op id="7" type="scan" timems="1.969" contextid="3" timestamp="2015-12-17T02:16:21.392" /> | |
<gc-op id="8" type="finalize" timems="0.590" contextid="3" timestamp="2015-12-17T02:16:21.392" /> | |
<finalizer-duration-micros none="0" object="1" class="10" module="1" float="0" string="0" regexp="0" array="0" hash="1" struct="10" bignum="0" file="12" data="18" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="0" fixnum="0" omrbuf="0" undef="0" node="0" iclass="0" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=0 table-2=0 table-3=0 table-4=1 table-5=1 table-6=1 table-7=1 table-8=1 table-9=1 table-10=1 table-11=1 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=84 /> | |
<free-frozen-strings-micros=250 /> | |
<cpu-millis scan=1.955 finalize=0.499 /> | |
<cpu-parallelism scan=0.9931 finalize=0.8466 /> | |
<gc-op id="9" type="sweep" timems="0.194" contextid="3" timestamp="2015-12-17T02:16:21.392" /> | |
<gc-end id="10" type="global" contextid="3" durationms="3.472" usertimems="3.496" systemtimems="0.000" timestamp="2015-12-17T02:16:21.393" activeThreads="1"> | |
<mem-info id="11" free="2233624" total="4194304" percent="53"> | |
<mem type="tenure" free="2233624" total="4194304" percent="53" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="12" type="global" contextid="3" timestamp="2015-12-17T02:16:21.393" /> | |
<allocation-satisfied id="13" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="14" timestamp="2015-12-17T02:16:21.393" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="15" timestamp="2015-12-17T02:16:21.393" durationms="4.286" /> | |
<exclusive-start id="16" timestamp="2015-12-17T02:16:21.412" intervalms="23.555"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="17" threadId="00007F2F3137CFD0" totalBytesRequested="8208" timestamp="2015-12-17T02:16:21.412" intervalms="23.538" /> | |
<cycle-start id="18" type="global" contextid="0" timestamp="2015-12-17T02:16:21.413" intervalms="23.541" /> | |
<gc-start id="19" type="global" contextid="18" timestamp="2015-12-17T02:16:21.413"> | |
<mem-info id="20" free="201320" total="4194304" percent="4"> | |
<mem type="tenure" free="201320" total="4194304" percent="4" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="2009264" > | |
<allocated-bytes non-tlh="46328" tlh="1962936" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="2009264" /> | |
</allocation-stats> | |
<gc-op id="21" type="mark" timems="3.660" contextid="18" timestamp="2015-12-17T02:16:21.417"> | |
<trace-info objectcount="27053" scancount="23093" scanbytes="926304" /> | |
</gc-op> | |
<gc-op id="22" type="scan" timems="3.081" contextid="18" timestamp="2015-12-17T02:16:21.417" /> | |
<gc-op id="23" type="finalize" timems="0.859" contextid="18" timestamp="2015-12-17T02:16:21.417" /> | |
<finalizer-duration-micros none="0" object="0" class="13" module="1" float="0" string="0" regexp="1" array="0" hash="0" struct="15" bignum="0" file="11" data="41" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="1" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=1 table-2=1 table-3=1 table-4=1 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=106 /> | |
<free-frozen-strings-micros=376 /> | |
<cpu-millis scan=2.898 finalize=0.651 /> | |
<cpu-parallelism scan=0.9405 finalize=0.7577 /> | |
<gc-op id="24" type="sweep" timems="0.232" contextid="18" timestamp="2015-12-17T02:16:21.417" /> | |
<gc-end id="25" type="global" contextid="18" durationms="4.967" usertimems="4.256" systemtimems="0.000" timestamp="2015-12-17T02:16:21.418" activeThreads="1"> | |
<mem-info id="26" free="1467848" total="4194304" percent="34"> | |
<mem type="tenure" free="1467848" total="4194304" percent="34" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="27" type="global" contextid="18" timestamp="2015-12-17T02:16:21.418" /> | |
<allocation-satisfied id="28" threadId="00007F2F313786E0" bytesRequested="8208" /> | |
<af-end id="29" timestamp="2015-12-17T02:16:21.418" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="30" timestamp="2015-12-17T02:16:21.418" durationms="5.816" /> | |
<exclusive-start id="31" timestamp="2015-12-17T02:16:21.437" intervalms="24.539"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="32" threadId="00007F2F3137CFD0" totalBytesRequested="4120" timestamp="2015-12-17T02:16:21.437" intervalms="24.575" /> | |
<cycle-start id="33" type="global" contextid="0" timestamp="2015-12-17T02:16:21.437" intervalms="24.597" /> | |
<gc-start id="34" type="global" contextid="33" timestamp="2015-12-17T02:16:21.437"> | |
<mem-info id="35" free="85704" total="4194304" percent="2"> | |
<mem type="tenure" free="85704" total="4194304" percent="2" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="1357688" > | |
<allocated-bytes non-tlh="80040" tlh="1277648" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="1357688" /> | |
</allocation-stats> | |
<gc-op id="36" type="mark" timems="3.530" contextid="33" timestamp="2015-12-17T02:16:21.441"> | |
<trace-info objectcount="25063" scancount="21112" scanbytes="845184" /> | |
</gc-op> | |
<gc-op id="37" type="scan" timems="2.892" contextid="33" timestamp="2015-12-17T02:16:21.441" /> | |
<gc-op id="38" type="finalize" timems="0.840" contextid="33" timestamp="2015-12-17T02:16:21.441" /> | |
<finalizer-duration-micros none="0" object="1" class="14" module="1" float="0" string="0" regexp="0" array="1" hash="0" struct="1" bignum="0" file="5" data="7" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="1" fixnum="0" omrbuf="0" undef="0" node="0" iclass="1" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=59 /> | |
<free-frozen-strings-micros=536 /> | |
<cpu-millis scan=2.855 finalize=0.763 /> | |
<cpu-parallelism scan=0.9873 finalize=0.9083 /> | |
<gc-op id="39" type="sweep" timems="0.220" contextid="33" timestamp="2015-12-17T02:16:21.442" /> | |
<heap-resize id="40" type="expand" space="tenure" amount="1048576" count="1" timems="0.053" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.442" /> | |
<gc-end id="41" type="global" contextid="33" durationms="4.741" usertimems="4.236" systemtimems="0.000" timestamp="2015-12-17T02:16:21.442" activeThreads="1"> | |
<mem-info id="42" free="2229016" total="5242880" percent="42"> | |
<mem type="tenure" free="2229016" total="5242880" percent="42" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="43" type="global" contextid="33" timestamp="2015-12-17T02:16:21.442" /> | |
<allocation-satisfied id="44" threadId="00007F2F313786E0" bytesRequested="4120" /> | |
<af-end id="45" timestamp="2015-12-17T02:16:21.442" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="46" timestamp="2015-12-17T02:16:21.442" durationms="5.553" /> | |
<exclusive-start id="47" timestamp="2015-12-17T02:16:21.472" intervalms="35.379"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="48" threadId="00007F2F3137CFD0" totalBytesRequested="88" timestamp="2015-12-17T02:16:21.472" intervalms="35.321" /> | |
<cycle-start id="49" type="global" contextid="0" timestamp="2015-12-17T02:16:21.472" intervalms="35.274" /> | |
<gc-start id="50" type="global" contextid="49" timestamp="2015-12-17T02:16:21.473"> | |
<mem-info id="51" free="0" total="5242880" percent="0"> | |
<mem type="tenure" free="0" total="5242880" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="2182824" > | |
<allocated-bytes non-tlh="89808" tlh="2093016" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="2182824" /> | |
</allocation-stats> | |
<gc-op id="52" type="mark" timems="4.099" contextid="49" timestamp="2015-12-17T02:16:21.477"> | |
<trace-info objectcount="30259" scancount="24889" scanbytes="996504" /> | |
</gc-op> | |
<gc-op id="53" type="scan" timems="3.582" contextid="49" timestamp="2015-12-17T02:16:21.477" /> | |
<gc-op id="54" type="finalize" timems="0.702" contextid="49" timestamp="2015-12-17T02:16:21.477" /> | |
<finalizer-duration-micros none="0" object="0" class="17" module="1" float="0" string="0" regexp="1" array="0" hash="0" struct="2" bignum="0" file="4" data="12" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="0" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=56 /> | |
<free-frozen-strings-micros=407 /> | |
<cpu-millis scan=3.559 finalize=0.612 /> | |
<cpu-parallelism scan=0.9935 finalize=0.8721 /> | |
<gc-op id="55" type="sweep" timems="0.255" contextid="49" timestamp="2015-12-17T02:16:21.478" /> | |
<heap-resize id="56" type="expand" space="tenure" amount="1048576" count="1" timems="0.039" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.478" /> | |
<gc-end id="57" type="global" contextid="49" durationms="5.329" usertimems="4.619" systemtimems="0.217" timestamp="2015-12-17T02:16:21.478" activeThreads="1"> | |
<mem-info id="58" free="2315528" total="6291456" percent="36"> | |
<mem type="tenure" free="2315528" total="6291456" percent="36" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="59" type="global" contextid="49" timestamp="2015-12-17T02:16:21.478" /> | |
<allocation-satisfied id="60" threadId="00007F2F313786E0" bytesRequested="88" /> | |
<af-end id="61" timestamp="2015-12-17T02:16:21.478" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="62" timestamp="2015-12-17T02:16:21.478" durationms="6.098" /> | |
<exclusive-start id="63" timestamp="2015-12-17T02:16:21.531" intervalms="59.141"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="64" threadId="00007F2F3137CFD0" totalBytesRequested="160" timestamp="2015-12-17T02:16:21.532" intervalms="59.204" /> | |
<cycle-start id="65" type="global" contextid="0" timestamp="2015-12-17T02:16:21.532" intervalms="59.216" /> | |
<gc-start id="66" type="global" contextid="65" timestamp="2015-12-17T02:16:21.532"> | |
<mem-info id="67" free="0" total="6291456" percent="0"> | |
<mem type="tenure" free="0" total="6291456" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="2299296" > | |
<allocated-bytes non-tlh="53496" tlh="2245800" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="2299296" /> | |
</allocation-stats> | |
<gc-op id="68" type="mark" timems="6.645" contextid="65" timestamp="2015-12-17T02:16:21.539"> | |
<trace-info objectcount="40098" scancount="33875" scanbytes="1357048" /> | |
</gc-op> | |
<gc-op id="69" type="scan" timems="5.853" contextid="65" timestamp="2015-12-17T02:16:21.539" /> | |
<gc-op id="70" type="finalize" timems="1.013" contextid="65" timestamp="2015-12-17T02:16:21.539" /> | |
<finalizer-duration-micros none="0" object="1" class="25" module="1" float="0" string="0" regexp="1" array="0" hash="0" struct="0" bignum="0" file="6" data="17" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="0" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=1 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=106 /> | |
<free-frozen-strings-micros=614 /> | |
<cpu-millis scan=5.812 finalize=0.902 /> | |
<cpu-parallelism scan=0.9930 finalize=0.8901 /> | |
<gc-op id="71" type="sweep" timems="0.348" contextid="65" timestamp="2015-12-17T02:16:21.539" /> | |
<heap-resize id="72" type="expand" space="tenure" amount="1048576" count="1" timems="0.070" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.540" /> | |
<gc-end id="73" type="global" contextid="65" durationms="7.958" usertimems="7.894" systemtimems="0.000" timestamp="2015-12-17T02:16:21.540" activeThreads="1"> | |
<mem-info id="74" free="2252112" total="7340032" percent="30"> | |
<mem type="tenure" free="2252112" total="7340032" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="75" type="global" contextid="65" timestamp="2015-12-17T02:16:21.540" /> | |
<allocation-satisfied id="76" threadId="00007F2F313786E0" bytesRequested="160" /> | |
<af-end id="77" timestamp="2015-12-17T02:16:21.540" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="78" timestamp="2015-12-17T02:16:21.540" durationms="8.849" /> | |
<exclusive-start id="79" timestamp="2015-12-17T02:16:21.580" intervalms="48.477"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="80" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:21.580" intervalms="48.498" /> | |
<cycle-start id="81" type="global" contextid="0" timestamp="2015-12-17T02:16:21.580" intervalms="48.500" /> | |
<gc-start id="82" type="global" contextid="81" timestamp="2015-12-17T02:16:21.580"> | |
<mem-info id="83" free="0" total="7340032" percent="0"> | |
<mem type="tenure" free="0" total="7340032" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="2233184" > | |
<allocated-bytes non-tlh="42416" tlh="2190768" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="2233184" /> | |
</allocation-stats> | |
<gc-op id="84" type="mark" timems="5.211" contextid="81" timestamp="2015-12-17T02:16:21.586"> | |
<trace-info objectcount="37449" scancount="30819" scanbytes="1234168" /> | |
</gc-op> | |
<gc-op id="85" type="scan" timems="4.419" contextid="81" timestamp="2015-12-17T02:16:21.586" /> | |
<gc-op id="86" type="finalize" timems="0.964" contextid="81" timestamp="2015-12-17T02:16:21.586" /> | |
<finalizer-duration-micros none="0" object="1" class="31" module="2" float="0" string="0" regexp="1" array="0" hash="1" struct="1" bignum="0" file="3" data="11" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="1" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=0 table-2=0 table-3=0 table-4=0 table-5=1 table-6=1 table-7=1 table-8=1 table-9=1 table-10=1 table-11=1 table-12=1 table-13=1 table-14=1 table-15=1 table-16=1 /> | |
<parallel-free-rdata-duration-micros slave-0=122 /> | |
<free-frozen-strings-micros=601 /> | |
<cpu-millis scan=4.406 finalize=0.913 /> | |
<cpu-parallelism scan=0.9970 finalize=0.9474 /> | |
<gc-op id="87" type="sweep" timems="0.388" contextid="81" timestamp="2015-12-17T02:16:21.586" /> | |
<heap-resize id="88" type="expand" space="tenure" amount="1048576" count="1" timems="0.048" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.587" /> | |
<gc-end id="89" type="global" contextid="81" durationms="6.437" usertimems="6.833" systemtimems="0.000" timestamp="2015-12-17T02:16:21.587" activeThreads="1"> | |
<mem-info id="90" free="2968048" total="8388608" percent="35"> | |
<mem type="tenure" free="2968048" total="8388608" percent="35" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="91" type="global" contextid="81" timestamp="2015-12-17T02:16:21.587" /> | |
<allocation-satisfied id="92" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="93" timestamp="2015-12-17T02:16:21.587" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="94" timestamp="2015-12-17T02:16:21.587" durationms="7.169" /> | |
<exclusive-start id="95" timestamp="2015-12-17T02:16:21.613" intervalms="33.409"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="96" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:21.613" intervalms="33.343" /> | |
<cycle-start id="97" type="global" contextid="0" timestamp="2015-12-17T02:16:21.613" intervalms="33.361" /> | |
<gc-start id="98" type="global" contextid="97" timestamp="2015-12-17T02:16:21.614"> | |
<mem-info id="99" free="0" total="8388608" percent="0"> | |
<mem type="tenure" free="0" total="8388608" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="2927080" > | |
<allocated-bytes non-tlh="104760" tlh="2822320" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="2927080" /> | |
</allocation-stats> | |
<gc-op id="100" type="mark" timems="6.187" contextid="97" timestamp="2015-12-17T02:16:21.620"> | |
<trace-info objectcount="39553" scancount="32090" scanbytes="1284760" /> | |
</gc-op> | |
<gc-op id="101" type="scan" timems="5.352" contextid="97" timestamp="2015-12-17T02:16:21.620" /> | |
<gc-op id="102" type="finalize" timems="1.033" contextid="97" timestamp="2015-12-17T02:16:21.620" /> | |
<finalizer-duration-micros none="0" object="1" class="32" module="2" float="0" string="1" regexp="0" array="0" hash="1" struct="14" bignum="0" file="3" data="42" match="0" complex="1" rational="0" nil="0" true="0" false="0" symbol="0" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=1 table-8=1 table-9=1 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=55 /> | |
<free-frozen-strings-micros=668 /> | |
<cpu-millis scan=5.286 finalize=0.943 /> | |
<cpu-parallelism scan=0.9877 finalize=0.9126 /> | |
<gc-op id="103" type="sweep" timems="0.519" contextid="97" timestamp="2015-12-17T02:16:21.621" /> | |
<heap-resize id="104" type="expand" space="tenure" amount="1048576" count="1" timems="0.057" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.621" /> | |
<gc-end id="105" type="global" contextid="97" durationms="7.701" usertimems="7.527" systemtimems="0.000" timestamp="2015-12-17T02:16:21.621" activeThreads="1"> | |
<mem-info id="106" free="3269536" total="9437184" percent="34"> | |
<mem type="tenure" free="3269536" total="9437184" percent="34" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="107" type="global" contextid="97" timestamp="2015-12-17T02:16:21.621" /> | |
<allocation-satisfied id="108" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="109" timestamp="2015-12-17T02:16:21.622" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="110" timestamp="2015-12-17T02:16:21.622" durationms="8.531" /> | |
<exclusive-start id="111" timestamp="2015-12-17T02:16:21.670" intervalms="56.697"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="112" threadId="00007F2F3137CFD0" totalBytesRequested="4120" timestamp="2015-12-17T02:16:21.670" intervalms="56.693" /> | |
<cycle-start id="113" type="global" contextid="0" timestamp="2015-12-17T02:16:21.670" intervalms="56.688" /> | |
<gc-start id="114" type="global" contextid="113" timestamp="2015-12-17T02:16:21.670"> | |
<mem-info id="115" free="0" total="9437184" percent="0"> | |
<mem type="tenure" free="0" total="9437184" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="3210648" > | |
<allocated-bytes non-tlh="86168" tlh="3124480" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="3210648" /> | |
</allocation-stats> | |
<gc-op id="116" type="mark" timems="6.244" contextid="113" timestamp="2015-12-17T02:16:21.677"> | |
<trace-info objectcount="46634" scancount="36938" scanbytes="1477584" /> | |
</gc-op> | |
<gc-op id="117" type="scan" timems="5.401" contextid="113" timestamp="2015-12-17T02:16:21.677" /> | |
<gc-op id="118" type="finalize" timems="1.055" contextid="113" timestamp="2015-12-17T02:16:21.677" /> | |
<finalizer-duration-micros none="0" object="0" class="31" module="2" float="0" string="0" regexp="1" array="0" hash="1" struct="44" bignum="1" file="20" data="21" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="0" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=1 table-8=1 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=59 /> | |
<free-frozen-strings-micros=645 /> | |
<cpu-millis scan=5.392 finalize=0.963 /> | |
<cpu-parallelism scan=0.9983 finalize=0.9127 /> | |
<gc-op id="119" type="sweep" timems="0.434" contextid="113" timestamp="2015-12-17T02:16:21.678" /> | |
<heap-resize id="120" type="expand" space="tenure" amount="1048576" count="1" timems="0.053" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.678" /> | |
<gc-end id="121" type="global" contextid="113" durationms="7.687" usertimems="6.369" systemtimems="0.870" timestamp="2015-12-17T02:16:21.678" activeThreads="1"> | |
<mem-info id="122" free="3426200" total="10485760" percent="32"> | |
<mem type="tenure" free="3426200" total="10485760" percent="32" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="123" type="global" contextid="113" timestamp="2015-12-17T02:16:21.678" /> | |
<allocation-satisfied id="124" threadId="00007F2F313786E0" bytesRequested="4120" /> | |
<af-end id="125" timestamp="2015-12-17T02:16:21.678" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="126" timestamp="2015-12-17T02:16:21.678" durationms="8.490" /> | |
<exclusive-start id="127" timestamp="2015-12-17T02:16:21.699" intervalms="28.896"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="128" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:21.699" intervalms="28.912" /> | |
<cycle-start id="129" type="global" contextid="0" timestamp="2015-12-17T02:16:21.699" intervalms="28.910" /> | |
<gc-start id="130" type="global" contextid="129" timestamp="2015-12-17T02:16:21.699"> | |
<mem-info id="131" free="0" total="10485760" percent="0"> | |
<mem type="tenure" free="0" total="10485760" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="3353696" > | |
<allocated-bytes non-tlh="545512" tlh="2808184" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="3353696" /> | |
</allocation-stats> | |
<gc-op id="132" type="mark" timems="6.947" contextid="129" timestamp="2015-12-17T02:16:21.706"> | |
<trace-info objectcount="51428" scancount="41098" scanbytes="1645280" /> | |
</gc-op> | |
<gc-op id="133" type="scan" timems="5.992" contextid="129" timestamp="2015-12-17T02:16:21.707" /> | |
<gc-op id="134" type="finalize" timems="1.157" contextid="129" timestamp="2015-12-17T02:16:21.707" /> | |
<finalizer-duration-micros none="0" object="1" class="33" module="2" float="0" string="0" regexp="0" array="1" hash="0" struct="1" bignum="0" file="9" data="42" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="1" fixnum="0" omrbuf="0" undef="0" node="1" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=1 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=99 /> | |
<free-frozen-strings-micros=745 /> | |
<cpu-millis scan=5.980 finalize=1.056 /> | |
<cpu-parallelism scan=0.9980 finalize=0.9127 /> | |
<gc-op id="135" type="sweep" timems="0.504" contextid="129" timestamp="2015-12-17T02:16:21.707" /> | |
<heap-resize id="136" type="expand" space="tenure" amount="1048576" count="1" timems="0.245" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.708" /> | |
<gc-end id="137" type="global" contextid="129" durationms="8.712" usertimems="8.250" systemtimems="0.000" timestamp="2015-12-17T02:16:21.708" activeThreads="1"> | |
<mem-info id="138" free="3496608" total="11534336" percent="30"> | |
<mem type="tenure" free="3496608" total="11534336" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="139" type="global" contextid="129" timestamp="2015-12-17T02:16:21.708" /> | |
<allocation-satisfied id="140" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="141" timestamp="2015-12-17T02:16:21.708" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="142" timestamp="2015-12-17T02:16:21.708" durationms="9.552" /> | |
<exclusive-start id="143" timestamp="2015-12-17T02:16:21.739" intervalms="40.065"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="144" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:21.739" intervalms="40.065" /> | |
<cycle-start id="145" type="global" contextid="0" timestamp="2015-12-17T02:16:21.739" intervalms="40.065" /> | |
<gc-start id="146" type="global" contextid="145" timestamp="2015-12-17T02:16:21.739"> | |
<mem-info id="147" free="0" total="11534336" percent="0"> | |
<mem type="tenure" free="0" total="11534336" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="3437384" > | |
<allocated-bytes non-tlh="448080" tlh="2989304" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="3437384" /> | |
</allocation-stats> | |
<gc-op id="148" type="mark" timems="7.640" contextid="145" timestamp="2015-12-17T02:16:21.747"> | |
<trace-info objectcount="55036" scancount="44108" scanbytes="1765520" /> | |
</gc-op> | |
<gc-op id="149" type="scan" timems="6.655" contextid="145" timestamp="2015-12-17T02:16:21.747" /> | |
<gc-op id="150" type="finalize" timems="1.196" contextid="145" timestamp="2015-12-17T02:16:21.747" /> | |
<finalizer-duration-micros none="0" object="1" class="32" module="3" float="0" string="0" regexp="1" array="0" hash="0" struct="0" bignum="0" file="13" data="87" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="0" fixnum="0" omrbuf="0" undef="0" node="0" iclass="3" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=0 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=1 table-8=1 table-9=1 table-10=1 table-11=1 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=101 /> | |
<free-frozen-strings-micros=725 /> | |
<cpu-millis scan=6.501 finalize=1.079 /> | |
<cpu-parallelism scan=0.9768 finalize=0.9023 /> | |
<gc-op id="151" type="sweep" timems="0.498" contextid="145" timestamp="2015-12-17T02:16:21.748" /> | |
<heap-resize id="152" type="expand" space="tenure" amount="1179648" count="1" timems="0.104" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.748" /> | |
<gc-end id="153" type="global" contextid="145" durationms="9.197" usertimems="7.448" systemtimems="0.784" timestamp="2015-12-17T02:16:21.748" activeThreads="1"> | |
<mem-info id="154" free="3855152" total="12713984" percent="30"> | |
<mem type="tenure" free="3855152" total="12713984" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="155" type="global" contextid="145" timestamp="2015-12-17T02:16:21.749" /> | |
<allocation-satisfied id="156" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="157" timestamp="2015-12-17T02:16:21.749" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="158" timestamp="2015-12-17T02:16:21.749" durationms="10.012" /> | |
<exclusive-start id="159" timestamp="2015-12-17T02:16:21.824" intervalms="85.308"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="160" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:21.825" intervalms="85.925" /> | |
<cycle-start id="161" type="global" contextid="0" timestamp="2015-12-17T02:16:21.829" intervalms="89.300" /> | |
<gc-start id="162" type="global" contextid="161" timestamp="2015-12-17T02:16:21.830"> | |
<mem-info id="163" free="0" total="12713984" percent="0"> | |
<mem type="tenure" free="0" total="12713984" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="3795480" > | |
<allocated-bytes non-tlh="130440" tlh="3665040" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="3795480" /> | |
</allocation-stats> | |
<gc-op id="164" type="mark" timems="7.056" contextid="161" timestamp="2015-12-17T02:16:21.838"> | |
<trace-info objectcount="56669" scancount="45174" scanbytes="1807232" /> | |
</gc-op> | |
<gc-op id="165" type="scan" timems="6.281" contextid="161" timestamp="2015-12-17T02:16:21.839" /> | |
<gc-op id="166" type="finalize" timems="0.981" contextid="161" timestamp="2015-12-17T02:16:21.839" /> | |
<finalizer-duration-micros none="0" object="0" class="31" module="3" float="0" string="0" regexp="1" array="0" hash="1" struct="18" bignum="0" file="0" data="3" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="1" fixnum="0" omrbuf="0" undef="0" node="0" iclass="2" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=26 /> | |
<free-frozen-strings-micros=672 /> | |
<cpu-millis scan=6.261 finalize=0.887 /> | |
<cpu-parallelism scan=0.9969 finalize=0.9042 /> | |
<gc-op id="167" type="sweep" timems="0.523" contextid="161" timestamp="2015-12-17T02:16:21.839" /> | |
<heap-resize id="168" type="expand" space="tenure" amount="1048576" count="1" timems="0.059" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.840" /> | |
<gc-end id="169" type="global" contextid="161" durationms="9.779" usertimems="10.182" systemtimems="0.000" timestamp="2015-12-17T02:16:21.840" activeThreads="1"> | |
<mem-info id="170" free="4627696" total="13762560" percent="33"> | |
<mem type="tenure" free="4627696" total="13762560" percent="33" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="171" type="global" contextid="161" timestamp="2015-12-17T02:16:21.840" /> | |
<allocation-satisfied id="172" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="173" timestamp="2015-12-17T02:16:21.840" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="174" timestamp="2015-12-17T02:16:21.840" durationms="15.960" /> | |
<exclusive-start id="175" timestamp="2015-12-17T02:16:21.966" intervalms="141.737"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="176" threadId="00007F2F3137CFD0" totalBytesRequested="184" timestamp="2015-12-17T02:16:21.966" intervalms="141.113" /> | |
<cycle-start id="177" type="global" contextid="0" timestamp="2015-12-17T02:16:21.966" intervalms="137.724" /> | |
<gc-start id="178" type="global" contextid="177" timestamp="2015-12-17T02:16:21.966"> | |
<mem-info id="179" free="0" total="13762560" percent="0"> | |
<mem type="tenure" free="0" total="13762560" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="4574816" > | |
<allocated-bytes non-tlh="656432" tlh="3918384" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="4574816" /> | |
</allocation-stats> | |
<gc-op id="180" type="mark" timems="9.393" contextid="177" timestamp="2015-12-17T02:16:21.976"> | |
<trace-info objectcount="64250" scancount="51279" scanbytes="2053440" /> | |
</gc-op> | |
<gc-op id="181" type="scan" timems="8.180" contextid="177" timestamp="2015-12-17T02:16:21.976" /> | |
<gc-op id="182" type="finalize" timems="1.526" contextid="177" timestamp="2015-12-17T02:16:21.976" /> | |
<finalizer-duration-micros none="0" object="1" class="38" module="5" float="0" string="0" regexp="0" array="1" hash="0" struct="2" bignum="1" file="9" data="53" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="2" fixnum="0" omrbuf="0" undef="0" node="0" iclass="4" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=2 /> | |
<parallel-free-rdata-duration-micros slave-0=220 /> | |
<free-frozen-strings-micros=862 /> | |
<cpu-millis scan=8.128 finalize=1.418 /> | |
<cpu-parallelism scan=0.9936 finalize=0.9294 /> | |
<gc-op id="183" type="sweep" timems="0.610" contextid="177" timestamp="2015-12-17T02:16:21.977" /> | |
<heap-resize id="184" type="expand" space="tenure" amount="1245184" count="1" timems="0.075" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:21.977" /> | |
<gc-end id="185" type="global" contextid="177" durationms="11.133" usertimems="9.959" systemtimems="0.679" timestamp="2015-12-17T02:16:21.977" activeThreads="1"> | |
<mem-info id="186" free="4525592" total="15007744" percent="30"> | |
<mem type="tenure" free="4525592" total="15007744" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="187" type="global" contextid="177" timestamp="2015-12-17T02:16:21.978" /> | |
<allocation-satisfied id="188" threadId="00007F2F313786E0" bytesRequested="184" /> | |
<af-end id="189" timestamp="2015-12-17T02:16:21.978" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="190" timestamp="2015-12-17T02:16:21.978" durationms="11.929" /> | |
<exclusive-start id="191" timestamp="2015-12-17T02:16:22.152" intervalms="186.222"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="192" threadId="00007F2F3137CFD0" totalBytesRequested="3800" timestamp="2015-12-17T02:16:22.152" intervalms="186.225" /> | |
<cycle-start id="193" type="global" contextid="0" timestamp="2015-12-17T02:16:22.152" intervalms="186.212" /> | |
<gc-start id="194" type="global" contextid="193" timestamp="2015-12-17T02:16:22.152"> | |
<mem-info id="195" free="0" total="15007744" percent="0"> | |
<mem type="tenure" free="0" total="15007744" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="4416408" > | |
<allocated-bytes non-tlh="572248" tlh="3844160" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="4416408" /> | |
</allocation-stats> | |
<gc-op id="196" type="mark" timems="10.656" contextid="193" timestamp="2015-12-17T02:16:22.163"> | |
<trace-info objectcount="67726" scancount="54150" scanbytes="2169912" /> | |
</gc-op> | |
<gc-op id="197" type="scan" timems="8.936" contextid="193" timestamp="2015-12-17T02:16:22.163" /> | |
<gc-op id="198" type="finalize" timems="1.904" contextid="193" timestamp="2015-12-17T02:16:22.164" /> | |
<finalizer-duration-micros none="0" object="0" class="130" module="4" float="0" string="1" regexp="0" array="0" hash="0" struct="1" bignum="1" file="26" data="65" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="1" fixnum="0" omrbuf="0" undef="0" node="1" iclass="6" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=19 table-1=20 table-2=20 table-3=20 table-4=20 table-5=20 table-6=20 table-7=20 table-8=20 table-9=21 table-10=21 table-11=21 table-12=21 table-13=21 table-14=21 table-15=21 table-16=22 /> | |
<parallel-free-rdata-duration-micros slave-0=422 /> | |
<free-frozen-strings-micros=1023 /> | |
<cpu-millis scan=8.841 finalize=1.746 /> | |
<cpu-parallelism scan=0.9894 finalize=0.9171 /> | |
<gc-op id="199" type="sweep" timems="0.619" contextid="193" timestamp="2015-12-17T02:16:22.164" /> | |
<heap-resize id="200" type="expand" space="tenure" amount="1310720" count="1" timems="0.066" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:22.165" /> | |
<gc-end id="201" type="global" contextid="193" durationms="12.188" usertimems="11.890" systemtimems="0.000" timestamp="2015-12-17T02:16:22.165" activeThreads="1"> | |
<mem-info id="202" free="4935688" total="16318464" percent="30"> | |
<mem type="tenure" free="4935688" total="16318464" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="203" type="global" contextid="193" timestamp="2015-12-17T02:16:22.165" /> | |
<allocation-satisfied id="204" threadId="00007F2F313786E0" bytesRequested="3800" /> | |
<af-end id="205" timestamp="2015-12-17T02:16:22.165" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="206" timestamp="2015-12-17T02:16:22.165" durationms="12.968" /> | |
<exclusive-start id="207" timestamp="2015-12-17T02:16:22.285" intervalms="133.171"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="208" threadId="00007F2F3137CFD0" totalBytesRequested="160" timestamp="2015-12-17T02:16:22.285" intervalms="133.152" /> | |
<cycle-start id="209" type="global" contextid="0" timestamp="2015-12-17T02:16:22.286" intervalms="133.189" /> | |
<gc-start id="210" type="global" contextid="209" timestamp="2015-12-17T02:16:22.286"> | |
<mem-info id="211" free="0" total="16318464" percent="0"> | |
<mem type="tenure" free="0" total="16318464" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="4833344" > | |
<allocated-bytes non-tlh="867640" tlh="3965704" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="4833344" /> | |
</allocation-stats> | |
<gc-op id="212" type="mark" timems="11.755" contextid="209" timestamp="2015-12-17T02:16:22.298"> | |
<trace-info objectcount="72392" scancount="58356" scanbytes="2335600" /> | |
</gc-op> | |
<gc-op id="213" type="scan" timems="10.093" contextid="209" timestamp="2015-12-17T02:16:22.298" /> | |
<gc-op id="214" type="finalize" timems="1.889" contextid="209" timestamp="2015-12-17T02:16:22.298" /> | |
<finalizer-duration-micros none="0" object="0" class="44" module="5" float="0" string="1" regexp="0" array="0" hash="1" struct="1" bignum="0" file="22" data="89" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="2" fixnum="0" omrbuf="0" undef="0" node="1" iclass="7" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=3 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=400 /> | |
<free-frozen-strings-micros=1071 /> | |
<cpu-millis scan=10.000 finalize=1.788 /> | |
<cpu-parallelism scan=0.9908 finalize=0.9466 /> | |
<gc-op id="215" type="sweep" timems="0.638" contextid="209" timestamp="2015-12-17T02:16:22.299" /> | |
<heap-resize id="216" type="expand" space="tenure" amount="1310720" count="1" timems="0.105" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:22.299" /> | |
<gc-end id="217" type="global" contextid="209" durationms="13.493" usertimems="12.724" systemtimems="0.186" timestamp="2015-12-17T02:16:22.299" activeThreads="1"> | |
<mem-info id="218" free="5300072" total="17629184" percent="30"> | |
<mem type="tenure" free="5300072" total="17629184" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="219" type="global" contextid="209" timestamp="2015-12-17T02:16:22.299" /> | |
<allocation-satisfied id="220" threadId="00007F2F313786E0" bytesRequested="160" /> | |
<af-end id="221" timestamp="2015-12-17T02:16:22.299" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="222" timestamp="2015-12-17T02:16:22.300" durationms="14.303" /> | |
<exclusive-start id="223" timestamp="2015-12-17T02:16:22.468" intervalms="182.472"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="224" threadId="00007F2F3137CFD0" totalBytesRequested="6456" timestamp="2015-12-17T02:16:22.468" intervalms="182.466" /> | |
<cycle-start id="225" type="global" contextid="0" timestamp="2015-12-17T02:16:22.468" intervalms="182.541" /> | |
<gc-start id="226" type="global" contextid="225" timestamp="2015-12-17T02:16:22.468"> | |
<mem-info id="227" free="0" total="17629184" percent="0"> | |
<mem type="tenure" free="0" total="17629184" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="5194648" > | |
<allocated-bytes non-tlh="969896" tlh="4224752" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="5194648" /> | |
</allocation-stats> | |
<gc-op id="228" type="mark" timems="13.154" contextid="225" timestamp="2015-12-17T02:16:22.482"> | |
<trace-info objectcount="79586" scancount="64617" scanbytes="2590704" /> | |
</gc-op> | |
<gc-op id="229" type="scan" timems="11.323" contextid="225" timestamp="2015-12-17T02:16:22.482" /> | |
<gc-op id="230" type="finalize" timems="2.067" contextid="225" timestamp="2015-12-17T02:16:22.482" /> | |
<finalizer-duration-micros none="0" object="1" class="54" module="6" float="0" string="0" regexp="0" array="0" hash="0" struct="1" bignum="0" file="105" data="136" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="2" fixnum="0" omrbuf="0" undef="0" node="0" iclass="8" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=1 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=362 /> | |
<free-frozen-strings-micros=1136 /> | |
<cpu-millis scan=11.193 finalize=2.002 /> | |
<cpu-parallelism scan=0.9885 finalize=0.9684 /> | |
<gc-op id="231" type="sweep" timems="0.741" contextid="225" timestamp="2015-12-17T02:16:22.483" /> | |
<heap-resize id="232" type="expand" space="tenure" amount="1769472" count="1" timems="0.083" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:22.483" /> | |
<gc-end id="233" type="global" contextid="225" durationms="14.841" usertimems="15.179" systemtimems="0.000" timestamp="2015-12-17T02:16:22.483" activeThreads="1"> | |
<mem-info id="234" free="5845952" total="19398656" percent="30"> | |
<mem type="tenure" free="5845952" total="19398656" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="235" type="global" contextid="225" timestamp="2015-12-17T02:16:22.483" /> | |
<allocation-satisfied id="236" threadId="00007F2F313786E0" bytesRequested="6456" /> | |
<af-end id="237" timestamp="2015-12-17T02:16:22.483" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="238" timestamp="2015-12-17T02:16:22.483" durationms="15.717" /> | |
<exclusive-start id="239" timestamp="2015-12-17T02:16:22.687" intervalms="219.617"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="240" threadId="00007F2F3137CFD0" totalBytesRequested="64" timestamp="2015-12-17T02:16:22.688" intervalms="219.643" /> | |
<cycle-start id="241" type="global" contextid="0" timestamp="2015-12-17T02:16:22.688" intervalms="219.573" /> | |
<gc-start id="242" type="global" contextid="241" timestamp="2015-12-17T02:16:22.688"> | |
<mem-info id="243" free="0" total="19398656" percent="0"> | |
<mem type="tenure" free="0" total="19398656" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="5753976" > | |
<allocated-bytes non-tlh="931520" tlh="4822456" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="5753976" /> | |
</allocation-stats> | |
<gc-op id="244" type="mark" timems="14.494" contextid="241" timestamp="2015-12-17T02:16:22.703"> | |
<trace-info objectcount="86677" scancount="69109" scanbytes="2766248" /> | |
</gc-op> | |
<gc-op id="245" type="scan" timems="12.668" contextid="241" timestamp="2015-12-17T02:16:22.703" /> | |
<gc-op id="246" type="finalize" timems="2.063" contextid="241" timestamp="2015-12-17T02:16:22.703" /> | |
<finalizer-duration-micros none="0" object="1" class="60" module="7" float="0" string="1" regexp="0" array="0" hash="1" struct="2" bignum="3" file="22" data="78" match="0" complex="1" rational="0" nil="0" true="0" false="0" symbol="2" fixnum="0" omrbuf="0" undef="0" node="0" iclass="10" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=3 table-13=3 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=295 /> | |
<free-frozen-strings-micros=1328 /> | |
<cpu-millis scan=12.578 finalize=1.951 /> | |
<cpu-parallelism scan=0.9929 finalize=0.9455 /> | |
<gc-op id="247" type="sweep" timems="0.730" contextid="241" timestamp="2015-12-17T02:16:22.704" /> | |
<heap-resize id="248" type="expand" space="tenure" amount="2228224" count="1" timems="0.074" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:22.704" /> | |
<gc-end id="249" type="global" contextid="241" durationms="16.278" usertimems="15.960" systemtimems="0.000" timestamp="2015-12-17T02:16:22.704" activeThreads="1"> | |
<mem-info id="250" free="6532368" total="21626880" percent="30"> | |
<mem type="tenure" free="6532368" total="21626880" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="251" type="global" contextid="241" timestamp="2015-12-17T02:16:22.704" /> | |
<allocation-satisfied id="252" threadId="00007F2F313786E0" bytesRequested="64" /> | |
<af-end id="253" timestamp="2015-12-17T02:16:22.704" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="254" timestamp="2015-12-17T02:16:22.705" durationms="17.122" /> | |
<exclusive-start id="255" timestamp="2015-12-17T02:16:22.873" intervalms="185.669"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="256" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:22.873" intervalms="185.655" /> | |
<cycle-start id="257" type="global" contextid="0" timestamp="2015-12-17T02:16:22.873" intervalms="185.652" /> | |
<gc-start id="258" type="global" contextid="257" timestamp="2015-12-17T02:16:22.873"> | |
<mem-info id="259" free="0" total="21626880" percent="0"> | |
<mem type="tenure" free="0" total="21626880" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="6453464" > | |
<allocated-bytes non-tlh="775288" tlh="5678176" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="6453464" /> | |
</allocation-stats> | |
<gc-op id="260" type="mark" timems="17.800" contextid="257" timestamp="2015-12-17T02:16:22.891"> | |
<trace-info objectcount="103570" scancount="84321" scanbytes="3376368" /> | |
</gc-op> | |
<gc-op id="261" type="scan" timems="15.810" contextid="257" timestamp="2015-12-17T02:16:22.892" /> | |
<gc-op id="262" type="finalize" timems="2.199" contextid="257" timestamp="2015-12-17T02:16:22.892" /> | |
<finalizer-duration-micros none="0" object="1" class="61" module="8" float="0" string="0" regexp="0" array="1" hash="0" struct="4" bignum="3" file="16" data="78" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="3" fixnum="0" omrbuf="0" undef="0" node="0" iclass="10" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=3 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=354 /> | |
<free-frozen-strings-micros=1437 /> | |
<cpu-millis scan=15.640 finalize=2.124 /> | |
<cpu-parallelism scan=0.9892 finalize=0.9657 /> | |
<gc-op id="263" type="sweep" timems="0.871" contextid="257" timestamp="2015-12-17T02:16:22.893" /> | |
<heap-resize id="264" type="expand" space="tenure" amount="2686976" count="1" timems="0.079" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:22.893" /> | |
<gc-end id="265" type="global" contextid="257" durationms="19.748" usertimems="19.614" systemtimems="0.000" timestamp="2015-12-17T02:16:22.893" activeThreads="1"> | |
<mem-info id="266" free="7334840" total="24313856" percent="30"> | |
<mem type="tenure" free="7334840" total="24313856" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="267" type="global" contextid="257" timestamp="2015-12-17T02:16:22.893" /> | |
<allocation-satisfied id="268" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="269" timestamp="2015-12-17T02:16:22.894" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="270" timestamp="2015-12-17T02:16:22.894" durationms="20.573" /> | |
<exclusive-start id="271" timestamp="2015-12-17T02:16:23.016" intervalms="142.794"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="272" threadId="00007F2F3137CFD0" totalBytesRequested="152" timestamp="2015-12-17T02:16:23.016" intervalms="142.810" /> | |
<cycle-start id="273" type="global" contextid="0" timestamp="2015-12-17T02:16:23.016" intervalms="142.798" /> | |
<gc-start id="274" type="global" contextid="273" timestamp="2015-12-17T02:16:23.016"> | |
<mem-info id="275" free="0" total="24313856" percent="0"> | |
<mem type="tenure" free="0" total="24313856" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="7242440" > | |
<allocated-bytes non-tlh="2347304" tlh="4895136" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="7242440" /> | |
</allocation-stats> | |
<gc-op id="276" type="mark" timems="23.197" contextid="273" timestamp="2015-12-17T02:16:23.040"> | |
<trace-info objectcount="115817" scancount="87407" scanbytes="4065664" /> | |
</gc-op> | |
<gc-op id="277" type="scan" timems="21.152" contextid="273" timestamp="2015-12-17T02:16:23.040" /> | |
<gc-op id="278" type="finalize" timems="2.269" contextid="273" timestamp="2015-12-17T02:16:23.040" /> | |
<finalizer-duration-micros none="0" object="1" class="67" module="9" float="0" string="1" regexp="0" array="0" hash="0" struct="4" bignum="1" file="17" data="49" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="3" fixnum="0" omrbuf="0" undef="0" node="0" iclass="12" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=1 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=2 table-11=2 table-12=3 table-13=3 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=288 /> | |
<free-frozen-strings-micros=1575 /> | |
<cpu-millis scan=20.855 finalize=2.149 /> | |
<cpu-parallelism scan=0.9860 finalize=0.9470 /> | |
<gc-op id="279" type="sweep" timems="0.995" contextid="273" timestamp="2015-12-17T02:16:23.041" /> | |
<heap-resize id="280" type="expand" space="tenure" amount="3670016" count="1" timems="0.094" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:23.041" /> | |
<gc-end id="281" type="global" contextid="273" durationms="25.203" usertimems="24.452" systemtimems="0.018" timestamp="2015-12-17T02:16:23.041" activeThreads="1"> | |
<mem-info id="282" free="8437000" total="27983872" percent="30"> | |
<mem type="tenure" free="8437000" total="27983872" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="283" type="global" contextid="273" timestamp="2015-12-17T02:16:23.042" /> | |
<allocation-satisfied id="284" threadId="00007F2F313786E0" bytesRequested="152" /> | |
<af-end id="285" timestamp="2015-12-17T02:16:23.042" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="286" timestamp="2015-12-17T02:16:23.042" durationms="26.024" /> | |
<exclusive-start id="287" timestamp="2015-12-17T02:16:23.054" intervalms="38.216"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="288" threadId="00007F2F3137CFD0" totalBytesRequested="160" timestamp="2015-12-17T02:16:23.054" intervalms="38.222" /> | |
<cycle-start id="289" type="global" contextid="0" timestamp="2015-12-17T02:16:23.054" intervalms="38.183" /> | |
<gc-start id="290" type="global" contextid="289" timestamp="2015-12-17T02:16:23.054"> | |
<mem-info id="291" free="0" total="27983872" percent="0"> | |
<mem type="tenure" free="0" total="27983872" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="8345200" > | |
<allocated-bytes non-tlh="24952" tlh="8320248" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="8345200" /> | |
</allocation-stats> | |
<gc-op id="292" type="mark" timems="23.953" contextid="289" timestamp="2015-12-17T02:16:23.079"> | |
<trace-info objectcount="187894" scancount="123750" scanbytes="4968104" /> | |
</gc-op> | |
<gc-op id="293" type="scan" timems="22.020" contextid="289" timestamp="2015-12-17T02:16:23.079" /> | |
<gc-op id="294" type="finalize" timems="2.127" contextid="289" timestamp="2015-12-17T02:16:23.079" /> | |
<finalizer-duration-micros none="0" object="1" class="68" module="9" float="0" string="1" regexp="0" array="0" hash="1" struct="5" bignum="1" file="0" data="15" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="4" fixnum="0" omrbuf="0" undef="0" node="0" iclass="11" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=2 table-1=2 table-2=2 table-3=2 table-4=3 table-5=3 table-6=3 table-7=3 table-8=3 table-9=3 table-10=3 table-11=3 table-12=3 table-13=4 table-14=4 table-15=4 table-16=4 /> | |
<parallel-free-rdata-duration-micros slave-0=84 /> | |
<free-frozen-strings-micros=1714 /> | |
<cpu-millis scan=21.923 finalize=2.038 /> | |
<cpu-parallelism scan=0.9956 finalize=0.9583 /> | |
<gc-op id="295" type="sweep" timems="0.991" contextid="289" timestamp="2015-12-17T02:16:23.080" /> | |
<heap-resize id="296" type="expand" space="tenure" amount="9961472" count="1" timems="0.043" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:23.080" /> | |
<gc-end id="297" type="global" contextid="289" durationms="26.065" usertimems="25.102" systemtimems="0.422" timestamp="2015-12-17T02:16:23.080" activeThreads="1"> | |
<mem-info id="298" free="11410008" total="37945344" percent="30"> | |
<mem type="tenure" free="11410008" total="37945344" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="299" type="global" contextid="289" timestamp="2015-12-17T02:16:23.081" /> | |
<allocation-satisfied id="300" threadId="00007F2F313786E0" bytesRequested="160" /> | |
<af-end id="301" timestamp="2015-12-17T02:16:23.081" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="302" timestamp="2015-12-17T02:16:23.081" durationms="26.784" /> | |
<exclusive-start id="303" timestamp="2015-12-17T02:16:23.145" intervalms="90.464"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="304" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:23.145" intervalms="90.483" /> | |
<cycle-start id="305" type="global" contextid="0" timestamp="2015-12-17T02:16:23.145" intervalms="90.520" /> | |
<gc-start id="306" type="global" contextid="305" timestamp="2015-12-17T02:16:23.145"> | |
<mem-info id="307" free="0" total="37945344" percent="0"> | |
<mem type="tenure" free="0" total="37945344" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="11384912" > | |
<allocated-bytes non-tlh="0" tlh="11384912" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="11384912" /> | |
</allocation-stats> | |
<gc-op id="308" type="mark" timems="26.642" contextid="305" timestamp="2015-12-17T02:16:23.172"> | |
<trace-info objectcount="235548" scancount="154337" scanbytes="6191560" /> | |
</gc-op> | |
<gc-op id="309" type="scan" timems="24.939" contextid="305" timestamp="2015-12-17T02:16:23.172" /> | |
<gc-op id="310" type="finalize" timems="1.914" contextid="305" timestamp="2015-12-17T02:16:23.172" /> | |
<finalizer-duration-micros none="0" object="0" class="70" module="8" float="0" string="1" regexp="0" array="0" hash="0" struct="4" bignum="1" file="0" data="6" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="2" fixnum="0" omrbuf="0" undef="0" node="0" iclass="20" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=2 table-2=2 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=3 table-9=3 table-10=3 table-11=3 table-12=3 table-13=3 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=73 /> | |
<free-frozen-strings-micros=1500 /> | |
<cpu-millis scan=24.715 finalize=1.822 /> | |
<cpu-parallelism scan=0.9910 finalize=0.9521 /> | |
<gc-op id="311" type="sweep" timems="1.469" contextid="305" timestamp="2015-12-17T02:16:23.174" /> | |
<heap-resize id="312" type="expand" space="tenure" amount="9371648" count="1" timems="0.133" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:23.174" /> | |
<gc-end id="313" type="global" contextid="305" durationms="29.117" usertimems="28.461" systemtimems="0.043" timestamp="2015-12-17T02:16:23.174" activeThreads="1"> | |
<mem-info id="314" free="14237704" total="47316992" percent="30"> | |
<mem type="tenure" free="14237704" total="47316992" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="315" type="global" contextid="305" timestamp="2015-12-17T02:16:23.174" /> | |
<allocation-satisfied id="316" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="317" timestamp="2015-12-17T02:16:23.174" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="318" timestamp="2015-12-17T02:16:23.174" durationms="29.935" /> | |
<exclusive-start id="319" timestamp="2015-12-17T02:16:23.451" intervalms="306.581"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="320" threadId="00007F2F3137CFD0" totalBytesRequested="48" timestamp="2015-12-17T02:16:23.451" intervalms="306.563" /> | |
<cycle-start id="321" type="global" contextid="0" timestamp="2015-12-17T02:16:23.451" intervalms="306.568" /> | |
<gc-start id="322" type="global" contextid="321" timestamp="2015-12-17T02:16:23.452"> | |
<mem-info id="323" free="0" total="47316992" percent="0"> | |
<mem type="tenure" free="0" total="47316992" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="14133792" > | |
<allocated-bytes non-tlh="701936" tlh="13431856" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="14133792" /> | |
</allocation-stats> | |
<gc-op id="324" type="mark" timems="29.133" contextid="321" timestamp="2015-12-17T02:16:23.481"> | |
<trace-info objectcount="241748" scancount="166481" scanbytes="6662536" /> | |
</gc-op> | |
<gc-op id="325" type="scan" timems="26.292" contextid="321" timestamp="2015-12-17T02:16:23.481" /> | |
<gc-op id="326" type="finalize" timems="3.035" contextid="321" timestamp="2015-12-17T02:16:23.481" /> | |
<finalizer-duration-micros none="0" object="1" class="89" module="15" float="0" string="0" regexp="0" array="0" hash="0" struct="5" bignum="1" file="38" data="279" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="3" fixnum="0" omrbuf="0" undef="0" node="1" iclass="14" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=0 table-1=1 table-2=1 table-3=1 table-4=1 table-5=1 table-6=1 table-7=1 table-8=2 table-9=2 table-10=2 table-11=2 table-12=2 table-13=2 table-14=2 table-15=2 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=599 /> | |
<free-frozen-strings-micros=1775 /> | |
<cpu-millis scan=26.205 finalize=2.979 /> | |
<cpu-parallelism scan=0.9967 finalize=0.9816 /> | |
<gc-op id="327" type="sweep" timems="1.944" contextid="321" timestamp="2015-12-17T02:16:23.483" /> | |
<heap-resize id="328" type="expand" space="tenure" amount="6160384" count="1" timems="0.180" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:23.484" /> | |
<gc-end id="329" type="global" contextid="321" durationms="32.071" usertimems="31.849" systemtimems="0.000" timestamp="2015-12-17T02:16:23.484" activeThreads="1"> | |
<mem-info id="330" free="16068240" total="53477376" percent="30"> | |
<mem type="tenure" free="16068240" total="53477376" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="331" type="global" contextid="321" timestamp="2015-12-17T02:16:23.484" /> | |
<allocation-satisfied id="332" threadId="00007F2F313786E0" bytesRequested="48" /> | |
<af-end id="333" timestamp="2015-12-17T02:16:23.484" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="334" timestamp="2015-12-17T02:16:23.484" durationms="32.965" /> | |
<exclusive-start id="335" timestamp="2015-12-17T02:16:23.790" intervalms="338.507"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="336" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:23.790" intervalms="338.494" /> | |
<cycle-start id="337" type="global" contextid="0" timestamp="2015-12-17T02:16:23.790" intervalms="338.527" /> | |
<gc-start id="338" type="global" contextid="337" timestamp="2015-12-17T02:16:23.790"> | |
<mem-info id="339" free="0" total="53477376" percent="0"> | |
<mem type="tenure" free="0" total="53477376" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="15905584" > | |
<allocated-bytes non-tlh="2791040" tlh="13114544" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="15905584" /> | |
</allocation-stats> | |
<gc-op id="340" type="mark" timems="33.615" contextid="337" timestamp="2015-12-17T02:16:23.824"> | |
<trace-info objectcount="264377" scancount="184795" scanbytes="7393128" /> | |
</gc-op> | |
<gc-op id="341" type="scan" timems="30.201" contextid="337" timestamp="2015-12-17T02:16:23.824" /> | |
<gc-op id="342" type="finalize" timems="3.641" contextid="337" timestamp="2015-12-17T02:16:23.824" /> | |
<finalizer-duration-micros none="0" object="1" class="106" module="20" float="0" string="0" regexp="0" array="0" hash="0" struct="11" bignum="1" file="40" data="115" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="5" fixnum="0" omrbuf="0" undef="0" node="0" iclass="17" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=2 table-1=2 table-2=2 table-3=2 table-4=3 table-5=3 table-6=3 table-7=3 table-8=3 table-9=3 table-10=3 table-11=3 table-12=3 table-13=4 table-14=4 table-15=4 table-16=4 /> | |
<parallel-free-rdata-duration-micros slave-0=907 /> | |
<free-frozen-strings-micros=2169 /> | |
<cpu-millis scan=30.022 finalize=3.491 /> | |
<cpu-parallelism scan=0.9941 finalize=0.9587 /> | |
<gc-op id="343" type="sweep" timems="2.186" contextid="337" timestamp="2015-12-17T02:16:23.827" /> | |
<heap-resize id="344" type="expand" space="tenure" amount="5373952" count="1" timems="0.336" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:23.827" /> | |
<gc-end id="345" type="global" contextid="337" durationms="37.143" usertimems="36.459" systemtimems="0.000" timestamp="2015-12-17T02:16:23.827" activeThreads="1"> | |
<mem-info id="346" free="17680392" total="58851328" percent="30"> | |
<mem type="tenure" free="17680392" total="58851328" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="347" type="global" contextid="337" timestamp="2015-12-17T02:16:23.827" /> | |
<allocation-satisfied id="348" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="349" timestamp="2015-12-17T02:16:23.828" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="350" timestamp="2015-12-17T02:16:23.828" durationms="38.000" /> | |
<exclusive-start id="351" timestamp="2015-12-17T02:16:24.207" intervalms="417.264"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="352" threadId="00007F2F3137CFD0" totalBytesRequested="4120" timestamp="2015-12-17T02:16:24.207" intervalms="417.230" /> | |
<cycle-start id="353" type="global" contextid="0" timestamp="2015-12-17T02:16:24.207" intervalms="417.201" /> | |
<gc-start id="354" type="global" contextid="353" timestamp="2015-12-17T02:16:24.207"> | |
<mem-info id="355" free="0" total="58851328" percent="0"> | |
<mem type="tenure" free="0" total="58851328" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="17440896" > | |
<allocated-bytes non-tlh="6781760" tlh="10659136" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="17440896" /> | |
</allocation-stats> | |
<gc-op id="356" type="mark" timems="47.242" contextid="353" timestamp="2015-12-17T02:16:24.255"> | |
<trace-info objectcount="272760" scancount="190645" scanbytes="7628648" /> | |
</gc-op> | |
<gc-op id="357" type="scan" timems="41.587" contextid="353" timestamp="2015-12-17T02:16:24.255" /> | |
<gc-op id="358" type="finalize" timems="5.963" contextid="353" timestamp="2015-12-17T02:16:24.255" /> | |
<finalizer-duration-micros none="0" object="1" class="209" module="41" float="0" string="0" regexp="0" array="0" hash="0" struct="18" bignum="1" file="139" data="365" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="5" fixnum="0" omrbuf="0" undef="0" node="0" iclass="35" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=1 table-2=2 table-3=2 table-4=2 table-5=2 table-6=2 table-7=2 table-8=2 table-9=2 table-10=3 table-11=3 table-12=3 table-13=3 table-14=3 table-15=3 table-16=3 /> | |
<parallel-free-rdata-duration-micros slave-0=1854 /> | |
<free-frozen-strings-micros=2966 /> | |
<cpu-millis scan=40.152 finalize=5.846 /> | |
<cpu-parallelism scan=0.9655 finalize=0.9803 /> | |
<gc-op id="359" type="sweep" timems="3.006" contextid="353" timestamp="2015-12-17T02:16:24.258" /> | |
<heap-resize id="360" type="expand" space="tenure" amount="3997696" count="1" timems="0.312" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:24.259" /> | |
<gc-end id="361" type="global" contextid="353" durationms="51.779" usertimems="50.853" systemtimems="0.000" timestamp="2015-12-17T02:16:24.259" activeThreads="1"> | |
<mem-info id="362" free="18862744" total="62849024" percent="30"> | |
<mem type="tenure" free="18862744" total="62849024" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="363" type="global" contextid="353" timestamp="2015-12-17T02:16:24.259" /> | |
<allocation-satisfied id="364" threadId="00007F2F313786E0" bytesRequested="4120" /> | |
<af-end id="365" timestamp="2015-12-17T02:16:24.259" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="366" timestamp="2015-12-17T02:16:24.260" durationms="52.655" /> | |
<exclusive-start id="367" timestamp="2015-12-17T02:16:24.635" intervalms="428.178"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="368" threadId="00007F2F3137CFD0" totalBytesRequested="19728" timestamp="2015-12-17T02:16:24.635" intervalms="428.239" /> | |
<cycle-start id="369" type="global" contextid="0" timestamp="2015-12-17T02:16:24.635" intervalms="428.255" /> | |
<gc-start id="370" type="global" contextid="369" timestamp="2015-12-17T02:16:24.636"> | |
<mem-info id="371" free="0" total="62849024" percent="0"> | |
<mem type="tenure" free="0" total="62849024" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="18552432" > | |
<allocated-bytes non-tlh="10297568" tlh="8254864" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="18552432" /> | |
</allocation-stats> | |
<gc-op id="372" type="mark" timems="46.065" contextid="369" timestamp="2015-12-17T02:16:24.682"> | |
<trace-info objectcount="287945" scancount="203172" scanbytes="8141584" /> | |
</gc-op> | |
<gc-op id="373" type="scan" timems="41.861" contextid="369" timestamp="2015-12-17T02:16:24.683" /> | |
<gc-op id="374" type="finalize" timems="4.436" contextid="369" timestamp="2015-12-17T02:16:24.683" /> | |
<finalizer-duration-micros none="0" object="1" class="133" module="20" float="0" string="1" regexp="0" array="0" hash="0" struct="14" bignum="1" file="71" data="174" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="6" fixnum="0" omrbuf="0" undef="0" node="1" iclass="36" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=2 table-2=2 table-3=2 table-4=2 table-5=3 table-6=3 table-7=3 table-8=3 table-9=3 table-10=3 table-11=4 table-12=4 table-13=4 table-14=4 table-15=5 table-16=5 /> | |
<parallel-free-rdata-duration-micros slave-0=827 /> | |
<free-frozen-strings-micros=2879 /> | |
<cpu-millis scan=41.237 finalize=4.304 /> | |
<cpu-parallelism scan=0.9851 finalize=0.9701 /> | |
<gc-op id="375" type="sweep" timems="2.334" contextid="369" timestamp="2015-12-17T02:16:24.685" /> | |
<heap-resize id="376" type="expand" space="tenure" amount="4128768" count="1" timems="0.331" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:24.686" /> | |
<gc-end id="377" type="global" contextid="369" durationms="50.058" usertimems="49.060" systemtimems="0.000" timestamp="2015-12-17T02:16:24.686" activeThreads="1"> | |
<mem-info id="378" free="20131736" total="66977792" percent="30"> | |
<mem type="tenure" free="20131736" total="66977792" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="379" type="global" contextid="369" timestamp="2015-12-17T02:16:24.686" /> | |
<allocation-satisfied id="380" threadId="00007F2F313786E0" bytesRequested="19728" /> | |
<af-end id="381" timestamp="2015-12-17T02:16:24.686" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="382" timestamp="2015-12-17T02:16:24.686" durationms="51.267" /> | |
<exclusive-start id="383" timestamp="2015-12-17T02:16:24.948" intervalms="312.745"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="384" threadId="00007F2F3137CFD0" totalBytesRequested="984" timestamp="2015-12-17T02:16:24.948" intervalms="312.743" /> | |
<cycle-start id="385" type="global" contextid="0" timestamp="2015-12-17T02:16:24.948" intervalms="312.766" /> | |
<gc-start id="386" type="global" contextid="385" timestamp="2015-12-17T02:16:24.948"> | |
<mem-info id="387" free="0" total="66977792" percent="0"> | |
<mem type="tenure" free="0" total="66977792" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="19971224" > | |
<allocated-bytes non-tlh="4541848" tlh="15429376" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="19961424" /> | |
</allocation-stats> | |
<gc-op id="388" type="mark" timems="43.265" contextid="385" timestamp="2015-12-17T02:16:24.992"> | |
<trace-info objectcount="320209" scancount="220801" scanbytes="8833032" /> | |
</gc-op> | |
<gc-op id="389" type="scan" timems="39.226" contextid="385" timestamp="2015-12-17T02:16:24.992" /> | |
<gc-op id="390" type="finalize" timems="4.328" contextid="385" timestamp="2015-12-17T02:16:24.992" /> | |
<finalizer-duration-micros none="0" object="1" class="149" module="22" float="0" string="0" regexp="0" array="0" hash="0" struct="19" bignum="0" file="30" data="171" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="10" fixnum="0" omrbuf="0" undef="0" node="0" iclass="38" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=1 table-1=2 table-2=2 table-3=2 table-4=2 table-5=2 table-6=3 table-7=3 table-8=3 table-9=3 table-10=3 table-11=3 table-12=4 table-13=4 table-14=5 table-15=5 table-16=6 /> | |
<parallel-free-rdata-duration-micros slave-0=589 /> | |
<free-frozen-strings-micros=2986 /> | |
<cpu-millis scan=39.032 finalize=4.198 /> | |
<cpu-parallelism scan=0.9951 finalize=0.9699 /> | |
<gc-op id="391" type="sweep" timems="2.831" contextid="385" timestamp="2015-12-17T02:16:24.995" /> | |
<heap-resize id="392" type="expand" space="tenure" amount="7405568" count="1" timems="0.552" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:24.996" /> | |
<gc-end id="393" type="global" contextid="385" durationms="47.647" usertimems="47.850" systemtimems="0.000" timestamp="2015-12-17T02:16:24.996" activeThreads="1"> | |
<mem-info id="394" free="22347000" total="74383360" percent="30"> | |
<mem type="tenure" free="22347000" total="74383360" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="395" type="global" contextid="385" timestamp="2015-12-17T02:16:24.996" /> | |
<allocation-satisfied id="396" threadId="00007F2F313786E0" bytesRequested="984" /> | |
<af-end id="397" timestamp="2015-12-17T02:16:24.996" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="398" timestamp="2015-12-17T02:16:24.996" durationms="48.558" /> | |
<exclusive-start id="399" timestamp="2015-12-17T02:16:25.226" intervalms="278.573"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="400" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:25.227" intervalms="278.572" /> | |
<cycle-start id="401" type="global" contextid="0" timestamp="2015-12-17T02:16:25.227" intervalms="278.551" /> | |
<gc-start id="402" type="global" contextid="401" timestamp="2015-12-17T02:16:25.227"> | |
<mem-info id="403" free="0" total="74383360" percent="0"> | |
<mem type="tenure" free="0" total="74383360" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="22100296" > | |
<allocated-bytes non-tlh="891688" tlh="21208608" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="22100296" /> | |
</allocation-stats> | |
<gc-op id="404" type="mark" timems="53.029" contextid="401" timestamp="2015-12-17T02:16:25.280"> | |
<trace-info objectcount="338614" scancount="229881" scanbytes="9216256" /> | |
</gc-op> | |
<gc-op id="405" type="scan" timems="48.668" contextid="401" timestamp="2015-12-17T02:16:25.280" /> | |
<gc-op id="406" type="finalize" timems="4.620" contextid="401" timestamp="2015-12-17T02:16:25.280" /> | |
<finalizer-duration-micros none="0" object="1" class="147" module="19" float="0" string="1" regexp="0" array="0" hash="0" struct="29" bignum="0" file="3" data="505" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="0" iclass="37" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=6 table-1=11 table-2=16 table-3=20 table-4=21 table-5=23 table-6=26 table-7=29 table-8=32 table-9=35 table-10=37 table-11=39 table-12=43 table-13=45 table-14=46 table-15=49 table-16=51 /> | |
<parallel-free-rdata-duration-micros slave-0=392 /> | |
<free-frozen-strings-micros=3151 /> | |
<cpu-millis scan=45.244 finalize=4.432 /> | |
<cpu-parallelism scan=0.9296 finalize=0.9592 /> | |
<gc-op id="407" type="sweep" timems="3.074" contextid="401" timestamp="2015-12-17T02:16:25.284" /> | |
<heap-resize id="408" type="expand" space="tenure" amount="4849664" count="1" timems="0.581" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:25.285" /> | |
<gc-end id="409" type="global" contextid="401" durationms="57.755" usertimems="49.379" systemtimems="4.351" timestamp="2015-12-17T02:16:25.285" activeThreads="1"> | |
<mem-info id="410" free="23793160" total="79233024" percent="30"> | |
<mem type="tenure" free="23793160" total="79233024" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="411" type="global" contextid="401" timestamp="2015-12-17T02:16:25.285" /> | |
<allocation-satisfied id="412" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="413" timestamp="2015-12-17T02:16:25.285" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="414" timestamp="2015-12-17T02:16:25.285" durationms="58.640" /> | |
<exclusive-start id="415" timestamp="2015-12-17T02:16:25.494" intervalms="267.601"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="416" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:25.494" intervalms="267.619" /> | |
<cycle-start id="417" type="global" contextid="0" timestamp="2015-12-17T02:16:25.494" intervalms="267.623" /> | |
<gc-start id="418" type="global" contextid="417" timestamp="2015-12-17T02:16:25.494"> | |
<mem-info id="419" free="0" total="79233024" percent="0"> | |
<mem type="tenure" free="0" total="79233024" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="23458632" > | |
<allocated-bytes non-tlh="526680" tlh="22931952" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="23458632" /> | |
</allocation-stats> | |
<gc-op id="420" type="mark" timems="45.014" contextid="417" timestamp="2015-12-17T02:16:25.540"> | |
<trace-info objectcount="340444" scancount="231225" scanbytes="9268192" /> | |
</gc-op> | |
<gc-op id="421" type="scan" timems="41.139" contextid="417" timestamp="2015-12-17T02:16:25.540" /> | |
<gc-op id="422" type="finalize" timems="4.157" contextid="417" timestamp="2015-12-17T02:16:25.540" /> | |
<finalizer-duration-micros none="0" object="1" class="130" module="14" float="0" string="0" regexp="0" array="1" hash="0" struct="39" bignum="1" file="1" data="65" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="10" fixnum="0" omrbuf="0" undef="0" node="0" iclass="35" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=12 table-1=28 table-2=39 table-3=52 table-4=62 table-5=71 table-6=82 table-7=93 table-8=103 table-9=112 table-10=121 table-11=127 table-12=133 table-13=139 table-14=147 table-15=155 table-16=162 /> | |
<parallel-free-rdata-duration-micros slave-0=258 /> | |
<free-frozen-strings-micros=3142 /> | |
<cpu-millis scan=40.996 finalize=4.018 /> | |
<cpu-parallelism scan=0.9965 finalize=0.9665 /> | |
<gc-op id="423" type="sweep" timems="6.440" contextid="417" timestamp="2015-12-17T02:16:25.547" /> | |
<heap-resize id="424" type="expand" space="tenure" amount="1048576" count="1" timems="0.651" reason="insufficient free space following gc" timestamp="2015-12-17T02:16:25.548" /> | |
<gc-end id="425" type="global" contextid="417" durationms="53.659" usertimems="52.761" systemtimems="0.007" timestamp="2015-12-17T02:16:25.548" activeThreads="1"> | |
<mem-info id="426" free="24562096" total="80281600" percent="30"> | |
<mem type="tenure" free="24562096" total="80281600" percent="30" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="427" type="global" contextid="417" timestamp="2015-12-17T02:16:25.548" /> | |
<allocation-satisfied id="428" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="429" timestamp="2015-12-17T02:16:25.548" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="430" timestamp="2015-12-17T02:16:25.549" durationms="54.600" /> | |
<exclusive-start id="431" timestamp="2015-12-17T02:16:25.736" intervalms="242.287"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="432" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:25.737" intervalms="242.426" /> | |
<cycle-start id="433" type="global" contextid="0" timestamp="2015-12-17T02:16:25.737" intervalms="242.419" /> | |
<gc-start id="434" type="global" contextid="433" timestamp="2015-12-17T02:16:25.737"> | |
<mem-info id="435" free="0" total="80281600" percent="0"> | |
<mem type="tenure" free="0" total="80281600" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="24253680" > | |
<allocated-bytes non-tlh="542928" tlh="23710752" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="24253680" /> | |
</allocation-stats> | |
<gc-op id="436" type="mark" timems="55.242" contextid="433" timestamp="2015-12-17T02:16:25.792"> | |
<trace-info objectcount="339951" scancount="230984" scanbytes="9259128" /> | |
</gc-op> | |
<gc-op id="437" type="scan" timems="50.637" contextid="433" timestamp="2015-12-17T02:16:25.793" /> | |
<gc-op id="438" type="finalize" timems="4.956" contextid="433" timestamp="2015-12-17T02:16:25.793" /> | |
<finalizer-duration-micros none="0" object="1" class="177" module="19" float="0" string="1" regexp="0" array="0" hash="1" struct="44" bignum="1" file="1" data="63" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="0" iclass="42" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=15 table-1=29 table-2=43 table-3=57 table-4=66 table-5=75 table-6=93 table-7=100 table-8=111 table-9=119 table-10=128 table-11=136 table-12=143 table-13=150 table-14=156 table-15=163 table-16=170 /> | |
<parallel-free-rdata-duration-micros slave-0=285 /> | |
<free-frozen-strings-micros=3772 /> | |
<cpu-millis scan=47.151 finalize=4.802 /> | |
<cpu-parallelism scan=0.9311 finalize=0.9689 /> | |
<gc-op id="439" type="sweep" timems="5.998" contextid="433" timestamp="2015-12-17T02:16:25.799" /> | |
<heap-resize id="440" type="expand" space="tenure" amount="13697024" count="1" timems="0.512" reason="excessive time being spent in gc" timestamp="2015-12-17T02:16:25.800" /> | |
<gc-end id="441" type="global" contextid="433" durationms="63.003" usertimems="59.580" systemtimems="0.000" timestamp="2015-12-17T02:16:25.800" activeThreads="1"> | |
<mem-info id="442" free="38350880" total="93978624" percent="40"> | |
<mem type="tenure" free="38350880" total="93978624" percent="40" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="443" type="global" contextid="433" timestamp="2015-12-17T02:16:25.800" /> | |
<allocation-satisfied id="444" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="445" timestamp="2015-12-17T02:16:25.800" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="446" timestamp="2015-12-17T02:16:25.800" durationms="64.062" /> | |
<exclusive-start id="447" timestamp="2015-12-17T02:16:26.144" intervalms="408.213"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="448" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:26.145" intervalms="408.541" /> | |
<cycle-start id="449" type="global" contextid="0" timestamp="2015-12-17T02:16:26.146" intervalms="408.943" /> | |
<gc-start id="450" type="global" contextid="449" timestamp="2015-12-17T02:16:26.147"> | |
<mem-info id="451" free="0" total="93978624" percent="0"> | |
<mem type="tenure" free="0" total="93978624" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="38047488" > | |
<allocated-bytes non-tlh="439304" tlh="37608184" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="38047488" /> | |
</allocation-stats> | |
<gc-op id="452" type="mark" timems="53.496" contextid="449" timestamp="2015-12-17T02:16:26.202"> | |
<trace-info objectcount="342411" scancount="231906" scanbytes="9296840" /> | |
</gc-op> | |
<gc-op id="453" type="scan" timems="48.772" contextid="449" timestamp="2015-12-17T02:16:26.202" /> | |
<gc-op id="454" type="finalize" timems="4.956" contextid="449" timestamp="2015-12-17T02:16:26.202" /> | |
<finalizer-duration-micros none="0" object="1" class="187" module="23" float="0" string="0" regexp="0" array="1" hash="0" struct="89" bignum="1" file="1" data="126" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="14" fixnum="0" omrbuf="0" undef="0" node="1" iclass="53" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=30 table-1=55 table-2=73 table-3=92 table-4=109 table-5=126 table-6=146 table-7=160 table-8=177 table-9=190 table-10=202 table-11=215 table-12=227 table-13=238 table-14=252 table-15=262 table-16=273 /> | |
<parallel-free-rdata-duration-micros slave-0=377 /> | |
<free-frozen-strings-micros=3564 /> | |
<cpu-millis scan=47.735 finalize=4.860 /> | |
<cpu-parallelism scan=0.9787 finalize=0.9806 /> | |
<gc-op id="455" type="sweep" timems="3.064" contextid="449" timestamp="2015-12-17T02:16:26.206" /> | |
<heap-resize id="456" type="expand" space="tenure" amount="15990784" count="1" timems="0.614" reason="excessive time being spent in gc" timestamp="2015-12-17T02:16:26.206" /> | |
<gc-end id="457" type="global" contextid="449" durationms="59.713" usertimems="55.048" systemtimems="3.391" timestamp="2015-12-17T02:16:26.206" activeThreads="1"> | |
<mem-info id="458" free="54053744" total="109969408" percent="49"> | |
<mem type="tenure" free="54053744" total="109969408" percent="49" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="459" type="global" contextid="449" timestamp="2015-12-17T02:16:26.207" /> | |
<allocation-satisfied id="460" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="461" timestamp="2015-12-17T02:16:26.207" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="462" timestamp="2015-12-17T02:16:26.207" durationms="62.393" /> | |
<exclusive-start id="463" timestamp="2015-12-17T02:16:26.761" intervalms="616.079"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="464" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:26.761" intervalms="615.572" /> | |
<cycle-start id="465" type="global" contextid="0" timestamp="2015-12-17T02:16:26.761" intervalms="615.122" /> | |
<gc-start id="466" type="global" contextid="465" timestamp="2015-12-17T02:16:26.761"> | |
<mem-info id="467" free="0" total="109969408" percent="0"> | |
<mem type="tenure" free="0" total="109969408" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="53647896" > | |
<allocated-bytes non-tlh="898944" tlh="52748952" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="53647896" /> | |
</allocation-stats> | |
<gc-op id="468" type="mark" timems="46.300" contextid="465" timestamp="2015-12-17T02:16:26.807"> | |
<trace-info objectcount="341093" scancount="230650" scanbytes="9244536" /> | |
</gc-op> | |
<gc-op id="469" type="scan" timems="42.149" contextid="465" timestamp="2015-12-17T02:16:26.808" /> | |
<gc-op id="470" type="finalize" timems="4.413" contextid="465" timestamp="2015-12-17T02:16:26.808" /> | |
<finalizer-duration-micros none="0" object="1" class="147" module="17" float="0" string="0" regexp="1" array="0" hash="0" struct="77" bignum="1" file="1" data="128" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="0" iclass="36" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=31 table-1=55 table-2=77 table-3=100 table-4=120 table-5=138 table-6=157 table-7=176 table-8=196 table-9=212 table-10=228 table-11=244 table-12=257 table-13=272 table-14=286 table-15=299 table-16=312 /> | |
<parallel-free-rdata-duration-micros slave-0=368 /> | |
<free-frozen-strings-micros=3036 /> | |
<cpu-millis scan=41.845 finalize=4.303 /> | |
<cpu-parallelism scan=0.9928 finalize=0.9751 /> | |
<gc-op id="471" type="sweep" timems="3.386" contextid="465" timestamp="2015-12-17T02:16:26.811" /> | |
<heap-resize id="472" type="expand" space="tenure" amount="18743296" count="1" timems="0.733" reason="excessive time being spent in gc" timestamp="2015-12-17T02:16:26.812" /> | |
<gc-end id="473" type="global" contextid="465" durationms="51.440" usertimems="51.133" systemtimems="0.000" timestamp="2015-12-17T02:16:26.812" activeThreads="1"> | |
<mem-info id="474" free="72850696" total="128712704" percent="56"> | |
<mem type="tenure" free="72850696" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="475" type="global" contextid="465" timestamp="2015-12-17T02:16:26.812" /> | |
<allocation-satisfied id="476" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="477" timestamp="2015-12-17T02:16:26.813" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="478" timestamp="2015-12-17T02:16:26.813" durationms="52.205" /> | |
<exclusive-start id="479" timestamp="2015-12-17T02:16:27.400" intervalms="639.857"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="480" threadId="00007F2F3137CFD0" totalBytesRequested="248" timestamp="2015-12-17T02:16:27.401" intervalms="639.872" /> | |
<cycle-start id="481" type="global" contextid="0" timestamp="2015-12-17T02:16:27.401" intervalms="639.898" /> | |
<gc-start id="482" type="global" contextid="481" timestamp="2015-12-17T02:16:27.401"> | |
<mem-info id="483" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72478064" > | |
<allocated-bytes non-tlh="373256" tlh="72104808" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72478064" /> | |
</allocation-stats> | |
<gc-op id="484" type="mark" timems="45.322" contextid="481" timestamp="2015-12-17T02:16:27.446"> | |
<trace-info objectcount="338869" scancount="228036" scanbytes="9132832" /> | |
</gc-op> | |
<gc-op id="485" type="scan" timems="40.741" contextid="481" timestamp="2015-12-17T02:16:27.447" /> | |
<gc-op id="486" type="finalize" timems="4.820" contextid="481" timestamp="2015-12-17T02:16:27.447" /> | |
<finalizer-duration-micros none="0" object="1" class="180" module="23" float="0" string="0" regexp="0" array="0" hash="0" struct="87" bignum="1" file="1" data="193" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="0" iclass="47" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=49 table-1=81 table-2=115 table-3=147 table-4=178 table-5=208 table-6=234 table-7=258 table-8=280 table-9=325 table-10=361 table-11=387 table-12=408 table-13=427 table-14=450 table-15=468 table-16=489 /> | |
<parallel-free-rdata-duration-micros slave-0=449 /> | |
<free-frozen-strings-micros=3082 /> | |
<cpu-millis scan=40.593 finalize=4.679 /> | |
<cpu-parallelism scan=0.9964 finalize=0.9707 /> | |
<gc-op id="487" type="sweep" timems="3.393" contextid="481" timestamp="2015-12-17T02:16:27.450" /> | |
<gc-end id="488" type="global" contextid="481" durationms="49.792" usertimems="49.873" systemtimems="0.000" timestamp="2015-12-17T02:16:27.451" activeThreads="1"> | |
<mem-info id="489" free="73047872" total="128712704" percent="56"> | |
<mem type="tenure" free="73047872" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="490" type="global" contextid="481" timestamp="2015-12-17T02:16:27.451" /> | |
<allocation-satisfied id="491" threadId="00007F2F313786E0" bytesRequested="248" /> | |
<af-end id="492" timestamp="2015-12-17T02:16:27.451" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="493" timestamp="2015-12-17T02:16:27.451" durationms="51.044" /> | |
<exclusive-start id="494" timestamp="2015-12-17T02:16:28.008" intervalms="607.824"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="495" threadId="00007F2F3137CFD0" totalBytesRequested="152" timestamp="2015-12-17T02:16:28.008" intervalms="607.845" /> | |
<cycle-start id="496" type="global" contextid="0" timestamp="2015-12-17T02:16:28.009" intervalms="607.821" /> | |
<gc-start id="497" type="global" contextid="496" timestamp="2015-12-17T02:16:28.009"> | |
<mem-info id="498" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72702048" > | |
<allocated-bytes non-tlh="298032" tlh="72404016" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72702048" /> | |
</allocation-stats> | |
<gc-op id="499" type="mark" timems="53.773" contextid="496" timestamp="2015-12-17T02:16:28.063"> | |
<trace-info objectcount="339089" scancount="228301" scanbytes="9133696" /> | |
</gc-op> | |
<gc-op id="500" type="scan" timems="45.796" contextid="496" timestamp="2015-12-17T02:16:28.063" /> | |
<gc-op id="501" type="finalize" timems="8.214" contextid="496" timestamp="2015-12-17T02:16:28.063" /> | |
<finalizer-duration-micros none="0" object="1" class="149" module="18" float="0" string="0" regexp="1" array="0" hash="0" struct="81" bignum="1" file="0" data="141" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="1" iclass="38" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=64 table-1=99 table-2=129 table-3=180 table-4=206 table-5=229 table-6=250 table-7=275 table-8=300 table-9=319 table-10=342 table-11=364 table-12=386 table-13=406 table-14=426 table-15=444 table-16=466 /> | |
<parallel-free-rdata-duration-micros slave-0=367 /> | |
<free-frozen-strings-micros=6690 /> | |
<cpu-millis scan=43.875 finalize=8.130 /> | |
<cpu-parallelism scan=0.9581 finalize=0.9898 /> | |
<gc-op id="502" type="sweep" timems="3.336" contextid="496" timestamp="2015-12-17T02:16:28.066" /> | |
<gc-end id="503" type="global" contextid="496" durationms="58.066" usertimems="55.794" systemtimems="0.000" timestamp="2015-12-17T02:16:28.067" activeThreads="1"> | |
<mem-info id="504" free="73028600" total="128712704" percent="56"> | |
<mem type="tenure" free="73028600" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="505" type="global" contextid="496" timestamp="2015-12-17T02:16:28.067" /> | |
<allocation-satisfied id="506" threadId="00007F2F313786E0" bytesRequested="152" /> | |
<af-end id="507" timestamp="2015-12-17T02:16:28.067" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="508" timestamp="2015-12-17T02:16:28.067" durationms="58.861" /> | |
<exclusive-start id="509" timestamp="2015-12-17T02:16:28.605" intervalms="596.426"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="510" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:28.605" intervalms="596.420" /> | |
<cycle-start id="511" type="global" contextid="0" timestamp="2015-12-17T02:16:28.605" intervalms="596.472" /> | |
<gc-start id="512" type="global" contextid="511" timestamp="2015-12-17T02:16:28.605"> | |
<mem-info id="513" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72646808" > | |
<allocated-bytes non-tlh="409888" tlh="72236920" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72646808" /> | |
</allocation-stats> | |
<gc-op id="514" type="mark" timems="49.473" contextid="511" timestamp="2015-12-17T02:16:28.655"> | |
<trace-info objectcount="343251" scancount="231003" scanbytes="9259872" /> | |
</gc-op> | |
<gc-op id="515" type="scan" timems="44.648" contextid="511" timestamp="2015-12-17T02:16:28.655" /> | |
<gc-op id="516" type="finalize" timems="5.079" contextid="511" timestamp="2015-12-17T02:16:28.655" /> | |
<finalizer-duration-micros none="0" object="1" class="187" module="25" float="0" string="0" regexp="1" array="0" hash="0" struct="101" bignum="0" file="1" data="180" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="1" iclass="53" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=52 table-1=95 table-2=135 table-3=169 table-4=197 table-5=221 table-6=246 table-7=274 table-8=302 table-9=328 table-10=353 table-11=373 table-12=392 table-13=412 table-14=431 table-15=450 table-16=472 /> | |
<parallel-free-rdata-duration-micros slave-0=579 /> | |
<free-frozen-strings-micros=3196 /> | |
<cpu-millis scan=44.372 finalize=4.912 /> | |
<cpu-parallelism scan=0.9938 finalize=0.9671 /> | |
<gc-op id="517" type="sweep" timems="4.180" contextid="511" timestamp="2015-12-17T02:16:28.660" /> | |
<gc-end id="518" type="global" contextid="511" durationms="54.808" usertimems="54.347" systemtimems="0.000" timestamp="2015-12-17T02:16:28.660" activeThreads="1"> | |
<mem-info id="519" free="72572880" total="128712704" percent="56"> | |
<mem type="tenure" free="72572880" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="520" type="global" contextid="511" timestamp="2015-12-17T02:16:28.660" /> | |
<allocation-satisfied id="521" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="522" timestamp="2015-12-17T02:16:28.660" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="523" timestamp="2015-12-17T02:16:28.660" durationms="55.687" /> | |
<exclusive-start id="524" timestamp="2015-12-17T02:16:29.224" intervalms="619.108"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="525" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:29.224" intervalms="619.115" /> | |
<cycle-start id="526" type="global" contextid="0" timestamp="2015-12-17T02:16:29.224" intervalms="619.177" /> | |
<gc-start id="527" type="global" contextid="526" timestamp="2015-12-17T02:16:29.224"> | |
<mem-info id="528" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72129632" > | |
<allocated-bytes non-tlh="796384" tlh="71333248" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72129632" /> | |
</allocation-stats> | |
<gc-op id="529" type="mark" timems="52.065" contextid="526" timestamp="2015-12-17T02:16:29.277"> | |
<trace-info objectcount="344392" scancount="232030" scanbytes="9308552" /> | |
</gc-op> | |
<gc-op id="530" type="scan" timems="44.737" contextid="526" timestamp="2015-12-17T02:16:29.277" /> | |
<gc-op id="531" type="finalize" timems="7.548" contextid="526" timestamp="2015-12-17T02:16:29.277" /> | |
<finalizer-duration-micros none="0" object="1" class="188" module="30" float="0" string="1" regexp="0" array="1" hash="1" struct="149" bignum="1" file="1" data="2238" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="17" fixnum="0" omrbuf="0" undef="0" node="0" iclass="53" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=41 table-1=78 table-2=100 table-3=123 table-4=149 table-5=175 table-6=199 table-7=222 table-8=243 table-9=267 table-10=290 table-11=317 table-12=340 table-13=361 table-14=382 table-15=402 table-16=418 /> | |
<parallel-free-rdata-duration-micros slave-0=1195 /> | |
<free-frozen-strings-micros=3015 /> | |
<cpu-millis scan=42.870 finalize=7.434 /> | |
<cpu-parallelism scan=0.9583 finalize=0.9849 /> | |
<gc-op id="532" type="sweep" timems="3.459" contextid="526" timestamp="2015-12-17T02:16:29.281" /> | |
<gc-end id="533" type="global" contextid="526" durationms="56.584" usertimems="53.737" systemtimems="0.454" timestamp="2015-12-17T02:16:29.281" activeThreads="1"> | |
<mem-info id="534" free="72485992" total="128712704" percent="56"> | |
<mem type="tenure" free="72485992" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="535" type="global" contextid="526" timestamp="2015-12-17T02:16:29.281" /> | |
<allocation-satisfied id="536" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="537" timestamp="2015-12-17T02:16:29.281" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="538" timestamp="2015-12-17T02:16:29.281" durationms="57.566" /> | |
<exclusive-start id="539" timestamp="2015-12-17T02:16:29.838" intervalms="614.681"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="540" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:29.839" intervalms="614.685" /> | |
<cycle-start id="541" type="global" contextid="0" timestamp="2015-12-17T02:16:29.839" intervalms="614.624" /> | |
<gc-start id="542" type="global" contextid="541" timestamp="2015-12-17T02:16:29.839"> | |
<mem-info id="543" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72028736" > | |
<allocated-bytes non-tlh="603024" tlh="71425712" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72028736" /> | |
</allocation-stats> | |
<gc-op id="544" type="mark" timems="51.493" contextid="541" timestamp="2015-12-17T02:16:29.891"> | |
<trace-info objectcount="341735" scancount="230434" scanbytes="9240224" /> | |
</gc-op> | |
<gc-op id="545" type="scan" timems="46.695" contextid="541" timestamp="2015-12-17T02:16:29.891" /> | |
<gc-op id="546" type="finalize" timems="5.016" contextid="541" timestamp="2015-12-17T02:16:29.891" /> | |
<finalizer-duration-micros none="0" object="1" class="179" module="24" float="0" string="1" regexp="0" array="0" hash="0" struct="88" bignum="1" file="1" data="166" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="0" iclass="127" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=70 table-1=103 table-2=135 table-3=163 table-4=197 table-5=233 table-6=262 table-7=285 table-8=311 table-9=343 table-10=368 table-11=398 table-12=423 table-13=448 table-14=471 table-15=494 table-16=523 /> | |
<parallel-free-rdata-duration-micros slave-0=552 /> | |
<free-frozen-strings-micros=3109 /> | |
<cpu-millis scan=45.421 finalize=4.873 /> | |
<cpu-parallelism scan=0.9727 finalize=0.9716 /> | |
<gc-op id="547" type="sweep" timems="3.216" contextid="541" timestamp="2015-12-17T02:16:29.894" /> | |
<gc-end id="548" type="global" contextid="541" durationms="55.808" usertimems="54.303" systemtimems="0.000" timestamp="2015-12-17T02:16:29.895" activeThreads="1"> | |
<mem-info id="549" free="72692744" total="128712704" percent="56"> | |
<mem type="tenure" free="72692744" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="550" type="global" contextid="541" timestamp="2015-12-17T02:16:29.895" /> | |
<allocation-satisfied id="551" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="552" timestamp="2015-12-17T02:16:29.895" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="553" timestamp="2015-12-17T02:16:29.895" durationms="56.754" /> | |
<exclusive-start id="554" timestamp="2015-12-17T02:16:30.469" intervalms="630.170"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="555" threadId="00007F2F3137CFD0" totalBytesRequested="11800" timestamp="2015-12-17T02:16:30.469" intervalms="630.163" /> | |
<cycle-start id="556" type="global" contextid="0" timestamp="2015-12-17T02:16:30.469" intervalms="630.164" /> | |
<gc-start id="557" type="global" contextid="556" timestamp="2015-12-17T02:16:30.469"> | |
<mem-info id="558" free="27064" total="128712704" percent="0"> | |
<mem type="tenure" free="27064" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72288744" > | |
<allocated-bytes non-tlh="298736" tlh="71990008" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72288744" /> | |
</allocation-stats> | |
<gc-op id="559" type="mark" timems="54.233" contextid="556" timestamp="2015-12-17T02:16:30.524"> | |
<trace-info objectcount="338632" scancount="227912" scanbytes="9129256" /> | |
</gc-op> | |
<gc-op id="560" type="scan" timems="49.471" contextid="556" timestamp="2015-12-17T02:16:30.524" /> | |
<gc-op id="561" type="finalize" timems="5.020" contextid="556" timestamp="2015-12-17T02:16:30.524" /> | |
<finalizer-duration-micros none="0" object="1" class="163" module="19" float="0" string="0" regexp="0" array="0" hash="0" struct="90" bignum="0" file="1" data="150" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="1" iclass="156" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=48 table-1=80 table-2=112 table-3=143 table-4=171 table-5=200 table-6=225 table-7=249 table-8=274 table-9=301 table-10=328 table-11=352 table-12=376 table-13=398 table-14=418 table-15=440 table-16=459 /> | |
<parallel-free-rdata-duration-micros slave-0=390 /> | |
<free-frozen-strings-micros=3302 /> | |
<cpu-millis scan=47.256 finalize=4.929 /> | |
<cpu-parallelism scan=0.9552 finalize=0.9819 /> | |
<gc-op id="562" type="sweep" timems="3.386" contextid="556" timestamp="2015-12-17T02:16:30.528" /> | |
<gc-end id="563" type="global" contextid="556" durationms="58.783" usertimems="56.664" systemtimems="0.000" timestamp="2015-12-17T02:16:30.528" activeThreads="1"> | |
<mem-info id="564" free="73092944" total="128712704" percent="56"> | |
<mem type="tenure" free="73092944" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="565" type="global" contextid="556" timestamp="2015-12-17T02:16:30.528" /> | |
<allocation-satisfied id="566" threadId="00007F2F313786E0" bytesRequested="11800" /> | |
<af-end id="567" timestamp="2015-12-17T02:16:30.528" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="568" timestamp="2015-12-17T02:16:30.528" durationms="59.689" /> | |
<exclusive-start id="569" timestamp="2015-12-17T02:16:31.114" intervalms="645.087"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="570" threadId="00007F2F3137CFD0" totalBytesRequested="152" timestamp="2015-12-17T02:16:31.114" intervalms="645.088" /> | |
<cycle-start id="571" type="global" contextid="0" timestamp="2015-12-17T02:16:31.114" intervalms="645.089" /> | |
<gc-start id="572" type="global" contextid="571" timestamp="2015-12-17T02:16:31.114"> | |
<mem-info id="573" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72744576" > | |
<allocated-bytes non-tlh="340352" tlh="72404224" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72744576" /> | |
</allocation-stats> | |
<gc-op id="574" type="mark" timems="52.059" contextid="571" timestamp="2015-12-17T02:16:31.166"> | |
<trace-info objectcount="343840" scancount="231557" scanbytes="9285224" /> | |
</gc-op> | |
<gc-op id="575" type="scan" timems="47.378" contextid="571" timestamp="2015-12-17T02:16:31.167" /> | |
<gc-op id="576" type="finalize" timems="4.926" contextid="571" timestamp="2015-12-17T02:16:31.167" /> | |
<finalizer-duration-micros none="0" object="1" class="172" module="22" float="0" string="1" regexp="0" array="0" hash="0" struct="77" bignum="1" file="0" data="159" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="10" fixnum="0" omrbuf="0" undef="0" node="0" iclass="49" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=41 table-1=72 table-2=100 table-3=126 table-4=165 table-5=207 table-6=242 table-7=263 table-8=284 table-9=308 table-10=333 table-11=355 table-12=382 table-13=400 table-14=420 table-15=438 table-16=455 /> | |
<parallel-free-rdata-duration-micros slave-0=427 /> | |
<free-frozen-strings-micros=3292 /> | |
<cpu-millis scan=46.874 finalize=4.769 /> | |
<cpu-parallelism scan=0.9894 finalize=0.9681 /> | |
<gc-op id="577" type="sweep" timems="3.494" contextid="571" timestamp="2015-12-17T02:16:31.171" /> | |
<gc-end id="578" type="global" contextid="571" durationms="56.676" usertimems="55.603" systemtimems="0.000" timestamp="2015-12-17T02:16:31.171" activeThreads="1"> | |
<mem-info id="579" free="72449840" total="128712704" percent="56"> | |
<mem type="tenure" free="72449840" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="580" type="global" contextid="571" timestamp="2015-12-17T02:16:31.171" /> | |
<allocation-satisfied id="581" threadId="00007F2F313786E0" bytesRequested="152" /> | |
<af-end id="582" timestamp="2015-12-17T02:16:31.171" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="583" timestamp="2015-12-17T02:16:31.171" durationms="57.619" /> | |
<exclusive-start id="584" timestamp="2015-12-17T02:16:31.751" intervalms="636.976"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="585" threadId="00007F2F3137CFD0" totalBytesRequested="64" timestamp="2015-12-17T02:16:31.751" intervalms="636.968" /> | |
<cycle-start id="586" type="global" contextid="0" timestamp="2015-12-17T02:16:31.751" intervalms="636.963" /> | |
<gc-start id="587" type="global" contextid="586" timestamp="2015-12-17T02:16:31.751"> | |
<mem-info id="588" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72032088" > | |
<allocated-bytes non-tlh="743248" tlh="71288840" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72032088" /> | |
</allocation-stats> | |
<gc-op id="589" type="mark" timems="53.016" contextid="586" timestamp="2015-12-17T02:16:31.804"> | |
<trace-info objectcount="344669" scancount="232002" scanbytes="9301032" /> | |
</gc-op> | |
<gc-op id="590" type="scan" timems="48.652" contextid="586" timestamp="2015-12-17T02:16:31.805" /> | |
<gc-op id="591" type="finalize" timems="4.595" contextid="586" timestamp="2015-12-17T02:16:31.805" /> | |
<finalizer-duration-micros none="0" object="1" class="152" module="18" float="0" string="0" regexp="0" array="0" hash="1" struct="81" bignum="1" file="1" data="143" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="10" fixnum="0" omrbuf="0" undef="0" node="0" iclass="39" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=52 table-1=81 table-2=110 table-3=133 table-4=161 table-5=239 table-6=272 table-7=302 table-8=330 table-9=354 table-10=377 table-11=402 table-12=422 table-13=444 table-14=464 table-15=486 table-16=508 /> | |
<parallel-free-rdata-duration-micros slave-0=369 /> | |
<free-frozen-strings-micros=3024 /> | |
<cpu-millis scan=47.973 finalize=4.552 /> | |
<cpu-parallelism scan=0.9860 finalize=0.9906 /> | |
<gc-op id="592" type="sweep" timems="3.401" contextid="586" timestamp="2015-12-17T02:16:31.808" /> | |
<gc-end id="593" type="global" contextid="586" durationms="57.371" usertimems="56.236" systemtimems="0.090" timestamp="2015-12-17T02:16:31.809" activeThreads="1"> | |
<mem-info id="594" free="72380280" total="128712704" percent="56"> | |
<mem type="tenure" free="72380280" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="595" type="global" contextid="586" timestamp="2015-12-17T02:16:31.809" /> | |
<allocation-satisfied id="596" threadId="00007F2F313786E0" bytesRequested="64" /> | |
<af-end id="597" timestamp="2015-12-17T02:16:31.809" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="598" timestamp="2015-12-17T02:16:31.809" durationms="58.273" /> | |
<exclusive-start id="599" timestamp="2015-12-17T02:16:32.397" intervalms="646.483"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="600" threadId="00007F2F3137CFD0" totalBytesRequested="64" timestamp="2015-12-17T02:16:32.397" intervalms="646.497" /> | |
<cycle-start id="601" type="global" contextid="0" timestamp="2015-12-17T02:16:32.398" intervalms="646.497" /> | |
<gc-start id="602" type="global" contextid="601" timestamp="2015-12-17T02:16:32.398"> | |
<mem-info id="603" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="71937080" > | |
<allocated-bytes non-tlh="641792" tlh="71295288" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="71937080" /> | |
</allocation-stats> | |
<gc-op id="604" type="mark" timems="50.779" contextid="601" timestamp="2015-12-17T02:16:32.449"> | |
<trace-info objectcount="342327" scancount="230758" scanbytes="9251968" /> | |
</gc-op> | |
<gc-op id="605" type="scan" timems="45.980" contextid="601" timestamp="2015-12-17T02:16:32.449" /> | |
<gc-op id="606" type="finalize" timems="5.062" contextid="601" timestamp="2015-12-17T02:16:32.449" /> | |
<finalizer-duration-micros none="0" object="1" class="177" module="60" float="0" string="1" regexp="0" array="0" hash="0" struct="88" bignum="0" file="1" data="156" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="0" iclass="49" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=47 table-1=80 table-2=111 table-3=146 table-4=187 table-5=217 table-6=247 table-7=275 table-8=309 table-9=334 table-10=359 table-11=385 table-12=414 table-13=444 table-14=468 table-15=497 table-16=525 /> | |
<parallel-free-rdata-duration-micros slave-0=396 /> | |
<free-frozen-strings-micros=3288 /> | |
<cpu-millis scan=45.728 finalize=4.930 /> | |
<cpu-parallelism scan=0.9945 finalize=0.9738 /> | |
<gc-op id="607" type="sweep" timems="3.695" contextid="601" timestamp="2015-12-17T02:16:32.453" /> | |
<gc-end id="608" type="global" contextid="601" durationms="55.565" usertimems="54.962" systemtimems="0.000" timestamp="2015-12-17T02:16:32.453" activeThreads="1"> | |
<mem-info id="609" free="72645808" total="128712704" percent="56"> | |
<mem type="tenure" free="72645808" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="610" type="global" contextid="601" timestamp="2015-12-17T02:16:32.453" /> | |
<allocation-satisfied id="611" threadId="00007F2F313786E0" bytesRequested="64" /> | |
<af-end id="612" timestamp="2015-12-17T02:16:32.454" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="613" timestamp="2015-12-17T02:16:32.454" durationms="56.544" /> | |
<exclusive-start id="614" timestamp="2015-12-17T02:16:33.091" intervalms="693.588"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="615" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:33.091" intervalms="693.593" /> | |
<cycle-start id="616" type="global" contextid="0" timestamp="2015-12-17T02:16:33.091" intervalms="693.597" /> | |
<gc-start id="617" type="global" contextid="616" timestamp="2015-12-17T02:16:33.091"> | |
<mem-info id="618" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72261144" > | |
<allocated-bytes non-tlh="343800" tlh="71917344" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72261144" /> | |
</allocation-stats> | |
<gc-op id="619" type="mark" timems="73.705" contextid="616" timestamp="2015-12-17T02:16:33.165"> | |
<trace-info objectcount="339074" scancount="228167" scanbytes="9129056" /> | |
</gc-op> | |
<gc-op id="620" type="scan" timems="68.795" contextid="616" timestamp="2015-12-17T02:16:33.165" /> | |
<gc-op id="621" type="finalize" timems="5.175" contextid="616" timestamp="2015-12-17T02:16:33.166" /> | |
<finalizer-duration-micros none="0" object="0" class="206" module="30" float="0" string="1" regexp="0" array="0" hash="1" struct="96" bignum="0" file="0" data="157" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="0" iclass="44" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=48 table-1=84 table-2=120 table-3=146 table-4=174 table-5=202 table-6=232 table-7=259 table-8=286 table-9=313 table-10=338 table-11=361 table-12=386 table-13=411 table-14=428 table-15=450 table-16=469 /> | |
<parallel-free-rdata-duration-micros slave-0=529 /> | |
<free-frozen-strings-micros=3349 /> | |
<cpu-millis scan=51.222 finalize=5.015 /> | |
<cpu-parallelism scan=0.7446 finalize=0.9691 /> | |
<gc-op id="622" type="sweep" timems="3.368" contextid="616" timestamp="2015-12-17T02:16:33.169" /> | |
<gc-end id="623" type="global" contextid="616" durationms="78.217" usertimems="60.008" systemtimems="0.042" timestamp="2015-12-17T02:16:33.169" activeThreads="1"> | |
<mem-info id="624" free="73055136" total="128712704" percent="56"> | |
<mem type="tenure" free="73055136" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="625" type="global" contextid="616" timestamp="2015-12-17T02:16:33.170" /> | |
<allocation-satisfied id="626" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="627" timestamp="2015-12-17T02:16:33.170" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="628" timestamp="2015-12-17T02:16:33.170" durationms="79.093" /> | |
<exclusive-start id="629" timestamp="2015-12-17T02:16:33.882" intervalms="791.262"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="630" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:33.882" intervalms="791.307" /> | |
<cycle-start id="631" type="global" contextid="0" timestamp="2015-12-17T02:16:33.882" intervalms="791.271" /> | |
<gc-start id="632" type="global" contextid="631" timestamp="2015-12-17T02:16:33.882"> | |
<mem-info id="633" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72691160" > | |
<allocated-bytes non-tlh="329760" tlh="72361400" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72691160" /> | |
</allocation-stats> | |
<gc-op id="634" type="mark" timems="66.294" contextid="631" timestamp="2015-12-17T02:16:33.949"> | |
<trace-info objectcount="340499" scancount="229708" scanbytes="9190800" /> | |
</gc-op> | |
<gc-op id="635" type="scan" timems="61.145" contextid="631" timestamp="2015-12-17T02:16:33.949" /> | |
<gc-op id="636" type="finalize" timems="5.411" contextid="631" timestamp="2015-12-17T02:16:33.949" /> | |
<finalizer-duration-micros none="0" object="1" class="322" module="68" float="0" string="0" regexp="0" array="1" hash="0" struct="87" bignum="1" file="0" data="154" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="1" iclass="53" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=45 table-1=87 table-2=122 table-3=155 table-4=184 table-5=211 table-6=241 table-7=274 table-8=302 table-9=326 table-10=353 table-11=380 table-12=420 table-13=451 table-14=476 table-15=504 table-16=535 /> | |
<parallel-free-rdata-duration-micros slave-0=405 /> | |
<free-frozen-strings-micros=3495 /> | |
<cpu-millis scan=60.739 finalize=5.307 /> | |
<cpu-parallelism scan=0.9934 finalize=0.9807 /> | |
<gc-op id="637" type="sweep" timems="3.476" contextid="631" timestamp="2015-12-17T02:16:33.953" /> | |
<gc-end id="638" type="global" contextid="631" durationms="70.923" usertimems="69.632" systemtimems="0.288" timestamp="2015-12-17T02:16:33.953" activeThreads="1"> | |
<mem-info id="639" free="72868400" total="128712704" percent="56"> | |
<mem type="tenure" free="72868400" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="640" type="global" contextid="631" timestamp="2015-12-17T02:16:33.954" /> | |
<allocation-satisfied id="641" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="642" timestamp="2015-12-17T02:16:33.954" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="643" timestamp="2015-12-17T02:16:33.954" durationms="71.864" /> | |
<exclusive-start id="644" timestamp="2015-12-17T02:16:34.799" intervalms="917.023"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="645" threadId="00007F2F3137CFD0" totalBytesRequested="64" timestamp="2015-12-17T02:16:34.799" intervalms="917.035" /> | |
<cycle-start id="646" type="global" contextid="0" timestamp="2015-12-17T02:16:34.799" intervalms="917.034" /> | |
<gc-start id="647" type="global" contextid="646" timestamp="2015-12-17T02:16:34.800"> | |
<mem-info id="648" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72492640" > | |
<allocated-bytes non-tlh="404568" tlh="72088072" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72492640" /> | |
</allocation-stats> | |
<gc-op id="649" type="mark" timems="74.222" contextid="646" timestamp="2015-12-17T02:16:34.874"> | |
<trace-info objectcount="343329" scancount="230925" scanbytes="9260592" /> | |
</gc-op> | |
<gc-op id="650" type="scan" timems="69.316" contextid="646" timestamp="2015-12-17T02:16:34.874" /> | |
<gc-op id="651" type="finalize" timems="5.127" contextid="646" timestamp="2015-12-17T02:16:34.874" /> | |
<finalizer-duration-micros none="0" object="1" class="224" module="23" float="0" string="0" regexp="50" array="1" hash="0" struct="135" bignum="1" file="1" data="222" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="14" fixnum="0" omrbuf="0" undef="0" node="0" iclass="45" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=99 table-1=135 table-2=168 table-3=194 table-4=219 table-5=246 table-6=271 table-7=296 table-8=320 table-9=341 table-10=365 table-11=390 table-12=417 table-13=438 table-14=458 table-15=476 table-16=497 /> | |
<parallel-free-rdata-duration-micros slave-0=625 /> | |
<free-frozen-strings-micros=3048 /> | |
<cpu-millis scan=68.176 finalize=5.020 /> | |
<cpu-parallelism scan=0.9836 finalize=0.9790 /> | |
<gc-op id="652" type="sweep" timems="3.442" contextid="646" timestamp="2015-12-17T02:16:34.878" /> | |
<gc-end id="653" type="global" contextid="646" durationms="78.620" usertimems="76.457" systemtimems="0.650" timestamp="2015-12-17T02:16:34.878" activeThreads="1"> | |
<mem-info id="654" free="72438808" total="128712704" percent="56"> | |
<mem type="tenure" free="72438808" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="655" type="global" contextid="646" timestamp="2015-12-17T02:16:34.878" /> | |
<allocation-satisfied id="656" threadId="00007F2F313786E0" bytesRequested="64" /> | |
<af-end id="657" timestamp="2015-12-17T02:16:34.878" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="658" timestamp="2015-12-17T02:16:34.878" durationms="79.335" /> | |
<exclusive-start id="659" timestamp="2015-12-17T02:16:35.765" intervalms="965.555"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="660" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:35.765" intervalms="965.466" /> | |
<cycle-start id="661" type="global" contextid="0" timestamp="2015-12-17T02:16:35.765" intervalms="965.473" /> | |
<gc-start id="662" type="global" contextid="661" timestamp="2015-12-17T02:16:35.765"> | |
<mem-info id="663" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="71952424" > | |
<allocated-bytes non-tlh="780728" tlh="71171696" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="71931568" /> | |
</allocation-stats> | |
<gc-op id="664" type="mark" timems="75.910" contextid="661" timestamp="2015-12-17T02:16:35.841"> | |
<trace-info objectcount="343055" scancount="231140" scanbytes="9268200" /> | |
</gc-op> | |
<gc-op id="665" type="scan" timems="70.698" contextid="661" timestamp="2015-12-17T02:16:35.841" /> | |
<gc-op id="666" type="finalize" timems="5.502" contextid="661" timestamp="2015-12-17T02:16:35.842" /> | |
<finalizer-duration-micros none="0" object="1" class="194" module="25" float="0" string="0" regexp="0" array="0" hash="1" struct="94" bignum="0" file="1" data="176" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="15" fixnum="0" omrbuf="0" undef="0" node="1" iclass="54" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=56 table-1=95 table-2=126 table-3=154 table-4=184 table-5=213 table-6=239 table-7=269 table-8=300 table-9=325 table-10=349 table-11=374 table-12=398 table-13=443 table-14=475 table-15=506 table-16=541 /> | |
<parallel-free-rdata-duration-micros slave-0=455 /> | |
<free-frozen-strings-micros=3644 /> | |
<cpu-millis scan=68.129 finalize=5.477 /> | |
<cpu-parallelism scan=0.9637 finalize=0.9955 /> | |
<gc-op id="667" type="sweep" timems="3.597" contextid="661" timestamp="2015-12-17T02:16:35.847" /> | |
<gc-end id="668" type="global" contextid="661" durationms="81.908" usertimems="78.071" systemtimems="0.000" timestamp="2015-12-17T02:16:35.847" activeThreads="1"> | |
<mem-info id="669" free="72580280" total="128712704" percent="56"> | |
<mem type="tenure" free="72580280" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="670" type="global" contextid="661" timestamp="2015-12-17T02:16:35.847" /> | |
<allocation-satisfied id="671" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="672" timestamp="2015-12-17T02:16:35.847" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="673" timestamp="2015-12-17T02:16:35.847" durationms="82.823" /> | |
<exclusive-start id="674" timestamp="2015-12-17T02:16:36.517" intervalms="751.940"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="675" threadId="00007F2F3137CFD0" totalBytesRequested="7880" timestamp="2015-12-17T02:16:36.517" intervalms="752.472" /> | |
<cycle-start id="676" type="global" contextid="0" timestamp="2015-12-17T02:16:36.518" intervalms="752.655" /> | |
<gc-start id="677" type="global" contextid="676" timestamp="2015-12-17T02:16:36.518"> | |
<mem-info id="678" free="39144" total="128712704" percent="0"> | |
<mem type="tenure" free="39144" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72129856" > | |
<allocated-bytes non-tlh="537816" tlh="71592040" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72129856" /> | |
</allocation-stats> | |
<gc-op id="679" type="mark" timems="48.634" contextid="676" timestamp="2015-12-17T02:16:36.567"> | |
<trace-info objectcount="340402" scancount="229469" scanbytes="9204248" /> | |
</gc-op> | |
<gc-op id="680" type="scan" timems="43.849" contextid="676" timestamp="2015-12-17T02:16:36.567" /> | |
<gc-op id="681" type="finalize" timems="5.018" contextid="676" timestamp="2015-12-17T02:16:36.567" /> | |
<finalizer-duration-micros none="0" object="1" class="260" module="33" float="0" string="0" regexp="0" array="0" hash="0" struct="97" bignum="1" file="1" data="171" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="1" iclass="44" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=129 table-1=162 table-2=190 table-3=219 table-4=249 table-5=275 table-6=304 table-7=328 table-8=355 table-9=378 table-10=400 table-11=420 table-12=440 table-13=461 table-14=485 table-15=504 table-16=528 /> | |
<parallel-free-rdata-duration-micros slave-0=449 /> | |
<free-frozen-strings-micros=3170 /> | |
<cpu-millis scan=43.639 finalize=4.926 /> | |
<cpu-parallelism scan=0.9952 finalize=0.9817 /> | |
<gc-op id="682" type="sweep" timems="3.478" contextid="676" timestamp="2015-12-17T02:16:36.571" /> | |
<gc-end id="683" type="global" contextid="676" durationms="53.438" usertimems="53.060" systemtimems="0.000" timestamp="2015-12-17T02:16:36.571" activeThreads="1"> | |
<mem-info id="684" free="72889320" total="128712704" percent="56"> | |
<mem type="tenure" free="72889320" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="685" type="global" contextid="676" timestamp="2015-12-17T02:16:36.571" /> | |
<allocation-satisfied id="686" threadId="00007F2F313786E0" bytesRequested="7880" /> | |
<af-end id="687" timestamp="2015-12-17T02:16:36.571" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="688" timestamp="2015-12-17T02:16:36.572" durationms="54.982" /> | |
<exclusive-start id="689" timestamp="2015-12-17T02:16:37.149" intervalms="632.851"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="690" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:37.150" intervalms="632.336" /> | |
<cycle-start id="691" type="global" contextid="0" timestamp="2015-12-17T02:16:37.150" intervalms="632.183" /> | |
<gc-start id="692" type="global" contextid="691" timestamp="2015-12-17T02:16:37.150"> | |
<mem-info id="693" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72550632" > | |
<allocated-bytes non-tlh="145872" tlh="72404760" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72550632" /> | |
</allocation-stats> | |
<gc-op id="694" type="mark" timems="50.278" contextid="691" timestamp="2015-12-17T02:16:37.201"> | |
<trace-info objectcount="338549" scancount="227842" scanbytes="9114808" /> | |
</gc-op> | |
<gc-op id="695" type="scan" timems="45.377" contextid="691" timestamp="2015-12-17T02:16:37.201" /> | |
<gc-op id="696" type="finalize" timems="5.153" contextid="691" timestamp="2015-12-17T02:16:37.201" /> | |
<finalizer-duration-micros none="0" object="1" class="188" module="24" float="0" string="1" regexp="0" array="0" hash="0" struct="87" bignum="0" file="1" data="155" match="0" complex="0" rational="0" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="0" iclass="51" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=54 table-1=101 table-2=134 table-3=166 table-4=195 table-5=226 table-6=251 table-7=277 table-8=305 table-9=329 table-10=356 table-11=380 table-12=407 table-13=430 table-14=455 table-15=480 table-16=503 /> | |
<parallel-free-rdata-duration-micros slave-0=435 /> | |
<free-frozen-strings-micros=3428 /> | |
<cpu-millis scan=45.055 finalize=5.028 /> | |
<cpu-parallelism scan=0.9929 finalize=0.9757 /> | |
<gc-op id="697" type="sweep" timems="3.194" contextid="691" timestamp="2015-12-17T02:16:37.205" /> | |
<gc-end id="698" type="global" contextid="691" durationms="54.680" usertimems="50.042" systemtimems="3.763" timestamp="2015-12-17T02:16:37.205" activeThreads="1"> | |
<mem-info id="699" free="73120264" total="128712704" percent="56"> | |
<mem type="tenure" free="73120264" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="700" type="global" contextid="691" timestamp="2015-12-17T02:16:37.205" /> | |
<allocation-satisfied id="701" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="702" timestamp="2015-12-17T02:16:37.205" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="703" timestamp="2015-12-17T02:16:37.205" durationms="55.859" /> | |
<exclusive-start id="704" timestamp="2015-12-17T02:16:37.821" intervalms="671.561"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="705" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:37.821" intervalms="671.568" /> | |
<cycle-start id="706" type="global" contextid="0" timestamp="2015-12-17T02:16:37.821" intervalms="671.564" /> | |
<gc-start id="707" type="global" contextid="706" timestamp="2015-12-17T02:16:37.821"> | |
<mem-info id="708" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72735896" > | |
<allocated-bytes non-tlh="456952" tlh="72278944" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72735896" /> | |
</allocation-stats> | |
<gc-op id="709" type="mark" timems="53.998" contextid="706" timestamp="2015-12-17T02:16:37.876"> | |
<trace-info objectcount="346016" scancount="233536" scanbytes="9368304" /> | |
</gc-op> | |
<gc-op id="710" type="scan" timems="49.400" contextid="706" timestamp="2015-12-17T02:16:37.876" /> | |
<gc-op id="711" type="finalize" timems="4.843" contextid="706" timestamp="2015-12-17T02:16:37.876" /> | |
<finalizer-duration-micros none="0" object="1" class="169" module="21" float="0" string="0" regexp="0" array="0" hash="0" struct="107" bignum="1" file="1" data="217" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="0" iclass="38" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=40 table-1=64 table-2=89 table-3=110 table-4=134 table-5=154 table-6=172 table-7=194 table-8=215 table-9=240 table-10=261 table-11=282 table-12=304 table-13=324 table-14=342 table-15=365 table-16=382 /> | |
<parallel-free-rdata-duration-micros slave-0=490 /> | |
<free-frozen-strings-micros=3140 /> | |
<cpu-millis scan=48.807 finalize=4.714 /> | |
<cpu-parallelism scan=0.9880 finalize=0.9735 /> | |
<gc-op id="712" type="sweep" timems="3.463" contextid="706" timestamp="2015-12-17T02:16:37.880" /> | |
<gc-end id="713" type="global" contextid="706" durationms="58.527" usertimems="57.659" systemtimems="0.000" timestamp="2015-12-17T02:16:37.880" activeThreads="1"> | |
<mem-info id="714" free="72399560" total="128712704" percent="56"> | |
<mem type="tenure" free="72399560" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="715" type="global" contextid="706" timestamp="2015-12-17T02:16:37.880" /> | |
<allocation-satisfied id="716" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="717" timestamp="2015-12-17T02:16:37.880" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="718" timestamp="2015-12-17T02:16:37.880" durationms="59.502" /> | |
<exclusive-start id="719" timestamp="2015-12-17T02:16:38.485" intervalms="664.130"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="720" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:38.485" intervalms="664.148" /> | |
<cycle-start id="721" type="global" contextid="0" timestamp="2015-12-17T02:16:38.485" intervalms="664.096" /> | |
<gc-start id="722" type="global" contextid="721" timestamp="2015-12-17T02:16:38.485"> | |
<mem-info id="723" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="71952024" > | |
<allocated-bytes non-tlh="745400" tlh="71206624" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="71952024" /> | |
</allocation-stats> | |
<gc-op id="724" type="mark" timems="53.070" contextid="721" timestamp="2015-12-17T02:16:38.539"> | |
<trace-info objectcount="344082" scancount="231695" scanbytes="9291560" /> | |
</gc-op> | |
<gc-op id="725" type="scan" timems="48.373" contextid="721" timestamp="2015-12-17T02:16:38.539" /> | |
<gc-op id="726" type="finalize" timems="4.943" contextid="721" timestamp="2015-12-17T02:16:38.539" /> | |
<finalizer-duration-micros none="0" object="1" class="205" module="26" float="0" string="0" regexp="0" array="1" hash="0" struct="113" bignum="1" file="0" data="185" match="1" complex="1" rational="0" nil="0" true="0" false="0" symbol="13" fixnum="0" omrbuf="0" undef="0" node="0" iclass="53" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=38 table-1=71 table-2=100 table-3=129 table-4=156 table-5=176 table-6=201 table-7=224 table-8=246 table-9=270 table-10=291 table-11=313 table-12=341 table-13=364 table-14=385 table-15=407 table-16=430 /> | |
<parallel-free-rdata-duration-micros slave-0=430 /> | |
<free-frozen-strings-micros=3219 /> | |
<cpu-millis scan=47.437 finalize=4.847 /> | |
<cpu-parallelism scan=0.9806 finalize=0.9807 /> | |
<gc-op id="727" type="sweep" timems="3.482" contextid="721" timestamp="2015-12-17T02:16:38.543" /> | |
<gc-end id="728" type="global" contextid="721" durationms="57.590" usertimems="56.568" systemtimems="0.000" timestamp="2015-12-17T02:16:38.543" activeThreads="1"> | |
<mem-info id="729" free="72504504" total="128712704" percent="56"> | |
<mem type="tenure" free="72504504" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="730" type="global" contextid="721" timestamp="2015-12-17T02:16:38.543" /> | |
<allocation-satisfied id="731" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="732" timestamp="2015-12-17T02:16:38.543" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="733" timestamp="2015-12-17T02:16:38.543" durationms="58.412" /> | |
<exclusive-start id="734" timestamp="2015-12-17T02:16:39.201" intervalms="715.758"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="735" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:39.201" intervalms="715.747" /> | |
<cycle-start id="736" type="global" contextid="0" timestamp="2015-12-17T02:16:39.201" intervalms="715.768" /> | |
<gc-start id="737" type="global" contextid="736" timestamp="2015-12-17T02:16:39.201"> | |
<mem-info id="738" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72079016" > | |
<allocated-bytes non-tlh="584312" tlh="71494704" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72079016" /> | |
</allocation-stats> | |
<gc-op id="739" type="mark" timems="66.132" contextid="736" timestamp="2015-12-17T02:16:39.268"> | |
<trace-info objectcount="341808" scancount="230471" scanbytes="9240168" /> | |
</gc-op> | |
<gc-op id="740" type="scan" timems="60.604" contextid="736" timestamp="2015-12-17T02:16:39.268" /> | |
<gc-op id="741" type="finalize" timems="5.818" contextid="736" timestamp="2015-12-17T02:16:39.268" /> | |
<finalizer-duration-micros none="0" object="1" class="193" module="23" float="0" string="0" regexp="0" array="0" hash="0" struct="107" bignum="0" file="1" data="164" match="0" complex="1" rational="0" nil="0" true="0" false="0" symbol="14" fixnum="0" omrbuf="0" undef="0" node="0" iclass="50" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=34 table-1=67 table-2=105 table-3=131 table-4=159 table-5=187 table-6=209 table-7=229 table-8=256 table-9=282 table-10=307 table-11=330 table-12=353 table-13=376 table-14=397 table-15=415 table-16=436 /> | |
<parallel-free-rdata-duration-micros slave-0=677 /> | |
<free-frozen-strings-micros=3827 /> | |
<cpu-millis scan=53.627 finalize=4.930 /> | |
<cpu-parallelism scan=0.8849 finalize=0.8474 /> | |
<gc-op id="742" type="sweep" timems="3.285" contextid="736" timestamp="2015-12-17T02:16:39.271" /> | |
<gc-end id="743" type="global" contextid="736" durationms="70.431" usertimems="62.868" systemtimems="0.000" timestamp="2015-12-17T02:16:39.272" activeThreads="1"> | |
<mem-info id="744" free="72660992" total="128712704" percent="56"> | |
<mem type="tenure" free="72660992" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="745" type="global" contextid="736" timestamp="2015-12-17T02:16:39.272" /> | |
<allocation-satisfied id="746" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="747" timestamp="2015-12-17T02:16:39.272" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="748" timestamp="2015-12-17T02:16:39.272" durationms="71.096" /> | |
<exclusive-start id="749" timestamp="2015-12-17T02:16:39.932" intervalms="731.156"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="750" threadId="00007F2F3137CFD0" totalBytesRequested="56" timestamp="2015-12-17T02:16:39.932" intervalms="731.137" /> | |
<cycle-start id="751" type="global" contextid="0" timestamp="2015-12-17T02:16:39.932" intervalms="731.102" /> | |
<gc-start id="752" type="global" contextid="751" timestamp="2015-12-17T02:16:39.932"> | |
<mem-info id="753" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72273416" > | |
<allocated-bytes non-tlh="275128" tlh="71998288" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72273416" /> | |
</allocation-stats> | |
<gc-op id="754" type="mark" timems="58.936" contextid="751" timestamp="2015-12-17T02:16:39.991"> | |
<trace-info objectcount="339875" scancount="229059" scanbytes="9177528" /> | |
</gc-op> | |
<gc-op id="755" type="scan" timems="54.074" contextid="751" timestamp="2015-12-17T02:16:39.992" /> | |
<gc-op id="756" type="finalize" timems="5.107" contextid="751" timestamp="2015-12-17T02:16:39.992" /> | |
<finalizer-duration-micros none="0" object="1" class="188" module="22" float="0" string="0" regexp="0" array="0" hash="0" struct="93" bignum="0" file="1" data="155" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="15" fixnum="0" omrbuf="0" undef="0" node="0" iclass="41" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=43 table-1=79 table-2=110 table-3=138 table-4=166 table-5=192 table-6=262 table-7=291 table-8=314 table-9=345 table-10=366 table-11=391 table-12=414 table-13=442 table-14=464 table-15=483 table-16=504 /> | |
<parallel-free-rdata-duration-micros slave-0=443 /> | |
<free-frozen-strings-micros=3383 /> | |
<cpu-millis scan=53.674 finalize=4.987 /> | |
<cpu-parallelism scan=0.9926 finalize=0.9765 /> | |
<gc-op id="757" type="sweep" timems="4.478" contextid="751" timestamp="2015-12-17T02:16:39.997" /> | |
<gc-end id="758" type="global" contextid="751" durationms="64.860" usertimems="64.076" systemtimems="0.041" timestamp="2015-12-17T02:16:39.997" activeThreads="1"> | |
<mem-info id="759" free="73010176" total="128712704" percent="56"> | |
<mem type="tenure" free="73010176" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="760" type="global" contextid="751" timestamp="2015-12-17T02:16:39.997" /> | |
<allocation-satisfied id="761" threadId="00007F2F313786E0" bytesRequested="56" /> | |
<af-end id="762" timestamp="2015-12-17T02:16:39.998" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="763" timestamp="2015-12-17T02:16:39.998" durationms="65.655" /> | |
<exclusive-start id="764" timestamp="2015-12-17T02:16:40.639" intervalms="706.728"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="765" threadId="00007F2F3137CFD0" totalBytesRequested="152" timestamp="2015-12-17T02:16:40.639" intervalms="706.724" /> | |
<cycle-start id="766" type="global" contextid="0" timestamp="2015-12-17T02:16:40.639" intervalms="706.787" /> | |
<gc-start id="767" type="global" contextid="766" timestamp="2015-12-17T02:16:40.639"> | |
<mem-info id="768" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72641912" > | |
<allocated-bytes non-tlh="261120" tlh="72380792" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72641912" /> | |
</allocation-stats> | |
<gc-op id="769" type="mark" timems="69.120" contextid="766" timestamp="2015-12-17T02:16:40.709"> | |
<trace-info objectcount="343808" scancount="231522" scanbytes="9280152" /> | |
</gc-op> | |
<gc-op id="770" type="scan" timems="63.281" contextid="766" timestamp="2015-12-17T02:16:40.709" /> | |
<gc-op id="771" type="finalize" timems="6.143" contextid="766" timestamp="2015-12-17T02:16:40.709" /> | |
<finalizer-duration-micros none="0" object="1" class="177" module="25" float="0" string="0" regexp="1" array="0" hash="0" struct="82" bignum="0" file="0" data="149" match="0" complex="1" rational="0" nil="0" true="0" false="0" symbol="15" fixnum="0" omrbuf="0" undef="0" node="0" iclass="41" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=42 table-1=76 table-2=104 table-3=135 table-4=166 table-5=187 table-6=209 table-7=229 table-8=251 table-9=274 table-10=290 table-11=309 table-12=334 table-13=352 table-14=370 table-15=388 table-16=410 /> | |
<parallel-free-rdata-duration-micros slave-0=425 /> | |
<free-frozen-strings-micros=4496 /> | |
<cpu-millis scan=62.449 finalize=6.080 /> | |
<cpu-parallelism scan=0.9868 finalize=0.9897 /> | |
<gc-op id="772" type="sweep" timems="3.506" contextid="766" timestamp="2015-12-17T02:16:40.713" /> | |
<gc-end id="773" type="global" contextid="766" durationms="73.743" usertimems="72.437" systemtimems="0.080" timestamp="2015-12-17T02:16:40.713" activeThreads="1"> | |
<mem-info id="774" free="72495552" total="128712704" percent="56"> | |
<mem type="tenure" free="72495552" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="775" type="global" contextid="766" timestamp="2015-12-17T02:16:40.713" /> | |
<allocation-satisfied id="776" threadId="00007F2F313786E0" bytesRequested="152" /> | |
<af-end id="777" timestamp="2015-12-17T02:16:40.713" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="778" timestamp="2015-12-17T02:16:40.713" durationms="74.615" /> | |
<exclusive-start id="779" timestamp="2015-12-17T02:16:41.284" intervalms="645.526"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="780" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:41.284" intervalms="645.522" /> | |
<cycle-start id="781" type="global" contextid="0" timestamp="2015-12-17T02:16:41.285" intervalms="645.521" /> | |
<gc-start id="782" type="global" contextid="781" timestamp="2015-12-17T02:16:41.285"> | |
<mem-info id="783" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72069888" > | |
<allocated-bytes non-tlh="749712" tlh="71320176" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72069888" /> | |
</allocation-stats> | |
<gc-op id="784" type="mark" timems="48.584" contextid="781" timestamp="2015-12-17T02:16:41.333"> | |
<trace-info objectcount="344666" scancount="231978" scanbytes="9300392" /> | |
</gc-op> | |
<gc-op id="785" type="scan" timems="43.288" contextid="781" timestamp="2015-12-17T02:16:41.334" /> | |
<gc-op id="786" type="finalize" timems="5.541" contextid="781" timestamp="2015-12-17T02:16:41.334" /> | |
<finalizer-duration-micros none="0" object="1" class="242" module="29" float="0" string="0" regexp="0" array="0" hash="0" struct="85" bignum="0" file="1" data="151" match="1" complex="1" rational="0" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="0" iclass="61" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=47 table-1=78 table-2=104 table-3=132 table-4=158 table-5=183 table-6=210 table-7=233 table-8=255 table-9=273 table-10=300 table-11=322 table-12=346 table-13=366 table-14=388 table-15=409 table-16=431 /> | |
<parallel-free-rdata-duration-micros slave-0=658 /> | |
<free-frozen-strings-micros=3609 /> | |
<cpu-millis scan=43.079 finalize=5.411 /> | |
<cpu-parallelism scan=0.9952 finalize=0.9766 /> | |
<gc-op id="787" type="sweep" timems="3.622" contextid="781" timestamp="2015-12-17T02:16:41.338" /> | |
<gc-end id="788" type="global" contextid="781" durationms="53.361" usertimems="52.506" systemtimems="0.086" timestamp="2015-12-17T02:16:41.338" activeThreads="1"> | |
<mem-info id="789" free="72372328" total="128712704" percent="56"> | |
<mem type="tenure" free="72372328" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="790" type="global" contextid="781" timestamp="2015-12-17T02:16:41.338" /> | |
<allocation-satisfied id="791" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="792" timestamp="2015-12-17T02:16:41.338" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="793" timestamp="2015-12-17T02:16:41.338" durationms="54.246" /> | |
<exclusive-start id="794" timestamp="2015-12-17T02:16:41.924" intervalms="639.666"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="795" threadId="00007F2F3137CFD0" totalBytesRequested="64" timestamp="2015-12-17T02:16:41.924" intervalms="639.654" /> | |
<cycle-start id="796" type="global" contextid="0" timestamp="2015-12-17T02:16:41.924" intervalms="639.631" /> | |
<gc-start id="797" type="global" contextid="796" timestamp="2015-12-17T02:16:41.924"> | |
<mem-info id="798" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="71912488" > | |
<allocated-bytes non-tlh="623352" tlh="71289136" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="71912488" /> | |
</allocation-stats> | |
<gc-op id="799" type="mark" timems="54.401" contextid="796" timestamp="2015-12-17T02:16:41.979"> | |
<trace-info objectcount="342076" scancount="230623" scanbytes="9244400" /> | |
</gc-op> | |
<gc-op id="800" type="scan" timems="50.096" contextid="796" timestamp="2015-12-17T02:16:41.979" /> | |
<gc-op id="801" type="finalize" timems="4.502" contextid="796" timestamp="2015-12-17T02:16:41.979" /> | |
<finalizer-duration-micros none="0" object="1" class="156" module="18" float="0" string="0" regexp="0" array="0" hash="0" struct="89" bignum="0" file="1" data="148" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="1" iclass="39" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=46 table-1=76 table-2=108 table-3=135 table-4=162 table-5=188 table-6=212 table-7=238 table-8=262 table-9=282 table-10=347 table-11=393 table-12=423 table-13=447 table-14=471 table-15=496 table-16=519 /> | |
<parallel-free-rdata-duration-micros slave-0=376 /> | |
<free-frozen-strings-micros=2894 /> | |
<cpu-millis scan=49.169 finalize=4.415 /> | |
<cpu-parallelism scan=0.9815 finalize=0.9806 /> | |
<gc-op id="802" type="sweep" timems="3.254" contextid="796" timestamp="2015-12-17T02:16:41.983" /> | |
<gc-end id="803" type="global" contextid="796" durationms="58.693" usertimems="57.476" systemtimems="0.000" timestamp="2015-12-17T02:16:41.983" activeThreads="1"> | |
<mem-info id="804" free="72685088" total="128712704" percent="56"> | |
<mem type="tenure" free="72685088" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="805" type="global" contextid="796" timestamp="2015-12-17T02:16:41.983" /> | |
<allocation-satisfied id="806" threadId="00007F2F313786E0" bytesRequested="64" /> | |
<af-end id="807" timestamp="2015-12-17T02:16:41.983" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="808" timestamp="2015-12-17T02:16:41.983" durationms="59.512" /> | |
<exclusive-start id="809" timestamp="2015-12-17T02:16:42.580" intervalms="656.167"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="810" threadId="00007F2F3137CFD0" totalBytesRequested="464" timestamp="2015-12-17T02:16:42.580" intervalms="656.195" /> | |
<cycle-start id="811" type="global" contextid="0" timestamp="2015-12-17T02:16:42.580" intervalms="656.208" /> | |
<gc-start id="812" type="global" contextid="811" timestamp="2015-12-17T02:16:42.581"> | |
<mem-info id="813" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72295512" > | |
<allocated-bytes non-tlh="367216" tlh="71928296" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72295512" /> | |
</allocation-stats> | |
<gc-op id="814" type="mark" timems="49.369" contextid="811" timestamp="2015-12-17T02:16:42.630"> | |
<trace-info objectcount="338683" scancount="227922" scanbytes="9118168" /> | |
</gc-op> | |
<gc-op id="815" type="scan" timems="44.729" contextid="811" timestamp="2015-12-17T02:16:42.630" /> | |
<gc-op id="816" type="finalize" timems="4.920" contextid="811" timestamp="2015-12-17T02:16:42.631" /> | |
<finalizer-duration-micros none="0" object="1" class="180" module="16" float="0" string="0" regexp="0" array="0" hash="1" struct="80" bignum="1" file="1" data="142" match="0" complex="1" rational="0" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="0" iclass="41" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=46 table-1=82 table-2=117 table-3=151 table-4=181 table-5=210 table-6=237 table-7=263 table-8=286 table-9=310 table-10=340 table-11=363 table-12=393 table-13=422 table-14=448 table-15=471 table-16=491 /> | |
<parallel-free-rdata-duration-micros slave-0=370 /> | |
<free-frozen-strings-micros=3288 /> | |
<cpu-millis scan=44.583 finalize=4.829 /> | |
<cpu-parallelism scan=0.9967 finalize=0.9814 /> | |
<gc-op id="817" type="sweep" timems="4.661" contextid="811" timestamp="2015-12-17T02:16:42.635" /> | |
<gc-end id="818" type="global" contextid="811" durationms="55.508" usertimems="50.794" systemtimems="3.888" timestamp="2015-12-17T02:16:42.636" activeThreads="1"> | |
<mem-info id="819" free="73071584" total="128712704" percent="56"> | |
<mem type="tenure" free="73071584" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="820" type="global" contextid="811" timestamp="2015-12-17T02:16:42.636" /> | |
<allocation-satisfied id="821" threadId="00007F2F313786E0" bytesRequested="464" /> | |
<af-end id="822" timestamp="2015-12-17T02:16:42.637" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="823" timestamp="2015-12-17T02:16:42.637" durationms="57.052" /> | |
<exclusive-start id="824" timestamp="2015-12-17T02:16:43.201" intervalms="620.566"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="825" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:43.201" intervalms="620.528" /> | |
<cycle-start id="826" type="global" contextid="0" timestamp="2015-12-17T02:16:43.201" intervalms="620.531" /> | |
<gc-start id="827" type="global" contextid="826" timestamp="2015-12-17T02:16:43.201"> | |
<mem-info id="828" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72696752" > | |
<allocated-bytes non-tlh="314680" tlh="72382072" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72696752" /> | |
</allocation-stats> | |
<gc-op id="829" type="mark" timems="49.284" contextid="826" timestamp="2015-12-17T02:16:43.251"> | |
<trace-info objectcount="340544" scancount="229740" scanbytes="9205720" /> | |
</gc-op> | |
<gc-op id="830" type="scan" timems="44.317" contextid="826" timestamp="2015-12-17T02:16:43.251" /> | |
<gc-op id="831" type="finalize" timems="5.177" contextid="826" timestamp="2015-12-17T02:16:43.251" /> | |
<finalizer-duration-micros none="0" object="1" class="148" module="18" float="0" string="0" regexp="0" array="0" hash="0" struct="82" bignum="0" file="0" data="141" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="0" iclass="36" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=58 table-1=131 table-2=158 table-3=185 table-4=209 table-5=234 table-6=261 table-7=282 table-8=305 table-9=325 table-10=341 table-11=365 table-12=383 table-13=405 table-14=426 table-15=444 table-16=463 /> | |
<parallel-free-rdata-duration-micros slave-0=437 /> | |
<free-frozen-strings-micros=3608 /> | |
<cpu-millis scan=44.091 finalize=4.508 /> | |
<cpu-parallelism scan=0.9949 finalize=0.8707 /> | |
<gc-op id="832" type="sweep" timems="3.369" contextid="826" timestamp="2015-12-17T02:16:43.254" /> | |
<gc-end id="833" type="global" contextid="826" durationms="53.636" usertimems="52.621" systemtimems="0.000" timestamp="2015-12-17T02:16:43.255" activeThreads="1"> | |
<mem-info id="834" free="72848944" total="128712704" percent="56"> | |
<mem type="tenure" free="72848944" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="835" type="global" contextid="826" timestamp="2015-12-17T02:16:43.255" /> | |
<allocation-satisfied id="836" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="837" timestamp="2015-12-17T02:16:43.255" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="838" timestamp="2015-12-17T02:16:43.255" durationms="54.467" /> | |
<exclusive-start id="839" timestamp="2015-12-17T02:16:43.808" intervalms="607.574"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="840" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:43.808" intervalms="607.562" /> | |
<cycle-start id="841" type="global" contextid="0" timestamp="2015-12-17T02:16:43.808" intervalms="607.536" /> | |
<gc-start id="842" type="global" contextid="841" timestamp="2015-12-17T02:16:43.809"> | |
<mem-info id="843" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72470776" > | |
<allocated-bytes non-tlh="432432" tlh="72038344" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72470776" /> | |
</allocation-stats> | |
<gc-op id="844" type="mark" timems="47.251" contextid="841" timestamp="2015-12-17T02:16:43.856"> | |
<trace-info objectcount="342846" scancount="230504" scanbytes="9255304" /> | |
</gc-op> | |
<gc-op id="845" type="scan" timems="42.484" contextid="841" timestamp="2015-12-17T02:16:43.856" /> | |
<gc-op id="846" type="finalize" timems="5.036" contextid="841" timestamp="2015-12-17T02:16:43.856" /> | |
<finalizer-duration-micros none="0" object="1" class="248" module="26" float="0" string="0" regexp="0" array="0" hash="0" struct="112" bignum="0" file="1" data="159" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="14" fixnum="0" omrbuf="0" undef="0" node="0" iclass="53" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=45 table-1=82 table-2=110 table-3=137 table-4=161 table-5=191 table-6=219 table-7=244 table-8=268 table-9=295 table-10=318 table-11=345 table-12=369 table-13=416 table-14=440 table-15=466 table-16=490 /> | |
<parallel-free-rdata-duration-micros slave-0=432 /> | |
<free-frozen-strings-micros=3216 /> | |
<cpu-millis scan=42.322 finalize=4.952 /> | |
<cpu-parallelism scan=0.9962 finalize=0.9833 /> | |
<gc-op id="847" type="sweep" timems="3.533" contextid="841" timestamp="2015-12-17T02:16:43.860" /> | |
<gc-end id="848" type="global" contextid="841" durationms="51.931" usertimems="51.254" systemtimems="0.000" timestamp="2015-12-17T02:16:43.861" activeThreads="1"> | |
<mem-info id="849" free="72541176" total="128712704" percent="56"> | |
<mem type="tenure" free="72541176" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="850" type="global" contextid="841" timestamp="2015-12-17T02:16:43.861" /> | |
<allocation-satisfied id="851" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="852" timestamp="2015-12-17T02:16:43.861" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="853" timestamp="2015-12-17T02:16:43.861" durationms="52.765" /> | |
<exclusive-start id="854" timestamp="2015-12-17T02:16:44.440" intervalms="631.320"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="855" threadId="00007F2F3137CFD0" totalBytesRequested="96" timestamp="2015-12-17T02:16:44.440" intervalms="631.391" /> | |
<cycle-start id="856" type="global" contextid="0" timestamp="2015-12-17T02:16:44.440" intervalms="631.416" /> | |
<gc-start id="857" type="global" contextid="856" timestamp="2015-12-17T02:16:44.440"> | |
<mem-info id="858" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72073040" > | |
<allocated-bytes non-tlh="853696" tlh="71219344" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72073040" /> | |
</allocation-stats> | |
<gc-op id="859" type="mark" timems="55.559" contextid="856" timestamp="2015-12-17T02:16:44.496"> | |
<trace-info objectcount="343301" scancount="231264" scanbytes="9273104" /> | |
</gc-op> | |
<gc-op id="860" type="scan" timems="51.087" contextid="856" timestamp="2015-12-17T02:16:44.496" /> | |
<gc-op id="861" type="finalize" timems="4.714" contextid="856" timestamp="2015-12-17T02:16:44.496" /> | |
<finalizer-duration-micros none="0" object="1" class="153" module="17" float="0" string="0" regexp="1" array="0" hash="0" struct="84" bignum="0" file="1" data="154" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="11" fixnum="0" omrbuf="0" undef="0" node="1" iclass="40" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=44 table-1=75 table-2=102 table-3=129 table-4=157 table-5=182 table-6=206 table-7=231 table-8=250 table-9=273 table-10=298 table-11=320 table-12=345 table-13=365 table-14=388 table-15=409 table-16=427 /> | |
<parallel-free-rdata-duration-micros slave-0=390 /> | |
<free-frozen-strings-micros=3175 /> | |
<cpu-millis scan=50.493 finalize=4.620 /> | |
<cpu-parallelism scan=0.9884 finalize=0.9801 /> | |
<gc-op id="862" type="sweep" timems="3.494" contextid="856" timestamp="2015-12-17T02:16:44.500" /> | |
<gc-end id="863" type="global" contextid="856" durationms="60.141" usertimems="59.106" systemtimems="0.000" timestamp="2015-12-17T02:16:44.500" activeThreads="1"> | |
<mem-info id="864" free="72544784" total="128712704" percent="56"> | |
<mem type="tenure" free="72544784" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="865" type="global" contextid="856" timestamp="2015-12-17T02:16:44.500" /> | |
<allocation-satisfied id="866" threadId="00007F2F313786E0" bytesRequested="96" /> | |
<af-end id="867" timestamp="2015-12-17T02:16:44.500" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="868" timestamp="2015-12-17T02:16:44.501" durationms="61.066" /> | |
<exclusive-start id="869" timestamp="2015-12-17T02:16:45.231" intervalms="791.671"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="870" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:45.231" intervalms="791.637" /> | |
<cycle-start id="871" type="global" contextid="0" timestamp="2015-12-17T02:16:45.232" intervalms="791.646" /> | |
<gc-start id="872" type="global" contextid="871" timestamp="2015-12-17T02:16:45.232"> | |
<mem-info id="873" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72113632" > | |
<allocated-bytes non-tlh="497656" tlh="71615976" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72085168" /> | |
</allocation-stats> | |
<gc-op id="874" type="mark" timems="59.048" contextid="871" timestamp="2015-12-17T02:16:45.291"> | |
<trace-info objectcount="341155" scancount="230114" scanbytes="9225232" /> | |
</gc-op> | |
<gc-op id="875" type="scan" timems="53.555" contextid="871" timestamp="2015-12-17T02:16:45.291" /> | |
<gc-op id="876" type="finalize" timems="5.734" contextid="871" timestamp="2015-12-17T02:16:45.291" /> | |
<finalizer-duration-micros none="0" object="1" class="201" module="28" float="0" string="0" regexp="0" array="1" hash="0" struct="108" bignum="1" file="1" data="184" match="0" complex="0" rational="1" nil="0" true="0" false="0" symbol="16" fixnum="0" omrbuf="0" undef="0" node="0" iclass="55" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=59 table-1=97 table-2=130 table-3=166 table-4=192 table-5=226 table-6=253 table-7=285 table-8=314 table-9=342 table-10=375 table-11=407 table-12=434 table-13=461 table-14=488 table-15=515 table-16=541 /> | |
<parallel-free-rdata-duration-micros slave-0=535 /> | |
<free-frozen-strings-micros=3804 /> | |
<cpu-millis scan=51.363 finalize=5.652 /> | |
<cpu-parallelism scan=0.9591 finalize=0.9857 /> | |
<gc-op id="877" type="sweep" timems="3.590" contextid="871" timestamp="2015-12-17T02:16:45.295" /> | |
<gc-end id="878" type="global" contextid="871" durationms="63.725" usertimems="61.322" systemtimems="0.000" timestamp="2015-12-17T02:16:45.295" activeThreads="1"> | |
<mem-info id="879" free="72851472" total="128712704" percent="56"> | |
<mem type="tenure" free="72851472" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="880" type="global" contextid="871" timestamp="2015-12-17T02:16:45.295" /> | |
<allocation-satisfied id="881" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="882" timestamp="2015-12-17T02:16:45.296" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="883" timestamp="2015-12-17T02:16:45.296" durationms="64.466" /> | |
<exclusive-start id="884" timestamp="2015-12-17T02:16:45.998" intervalms="766.637"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="885" threadId="00007F2F3137CFD0" totalBytesRequested="472" timestamp="2015-12-17T02:16:45.998" intervalms="766.713" /> | |
<cycle-start id="886" type="global" contextid="0" timestamp="2015-12-17T02:16:45.998" intervalms="766.698" /> | |
<gc-start id="887" type="global" contextid="886" timestamp="2015-12-17T02:16:45.998"> | |
<mem-info id="888" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72481208" > | |
<allocated-bytes non-tlh="143792" tlh="72337416" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72481208" /> | |
</allocation-stats> | |
<gc-op id="889" type="mark" timems="55.920" contextid="886" timestamp="2015-12-17T02:16:46.054"> | |
<trace-info objectcount="338568" scancount="227860" scanbytes="9115800" /> | |
</gc-op> | |
<gc-op id="890" type="scan" timems="50.467" contextid="886" timestamp="2015-12-17T02:16:46.055" /> | |
<gc-op id="891" type="finalize" timems="5.691" contextid="886" timestamp="2015-12-17T02:16:46.055" /> | |
<finalizer-duration-micros none="0" object="1" class="183" module="23" float="0" string="0" regexp="0" array="0" hash="0" struct="85" bignum="0" file="1" data="147" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="12" fixnum="0" omrbuf="0" undef="0" node="0" iclass="43" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=167 table-1=205 table-2=232 table-3=264 table-4=293 table-5=316 table-6=341 table-7=364 table-8=386 table-9=447 table-10=482 table-11=502 table-12=524 table-13=543 table-14=564 table-15=585 table-16=608 /> | |
<parallel-free-rdata-duration-micros slave-0=409 /> | |
<free-frozen-strings-micros=3923 /> | |
<cpu-millis scan=49.435 finalize=5.595 /> | |
<cpu-parallelism scan=0.9796 finalize=0.9831 /> | |
<gc-op id="892" type="sweep" timems="6.818" contextid="886" timestamp="2015-12-17T02:16:46.062" /> | |
<gc-end id="893" type="global" contextid="886" durationms="63.607" usertimems="58.937" systemtimems="0.303" timestamp="2015-12-17T02:16:46.062" activeThreads="1"> | |
<mem-info id="894" free="73120672" total="128712704" percent="56"> | |
<mem type="tenure" free="73120672" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="895" type="global" contextid="886" timestamp="2015-12-17T02:16:46.062" /> | |
<allocation-satisfied id="896" threadId="00007F2F313786E0" bytesRequested="472" /> | |
<af-end id="897" timestamp="2015-12-17T02:16:46.062" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="898" timestamp="2015-12-17T02:16:46.062" durationms="64.433" /> | |
<exclusive-start id="899" timestamp="2015-12-17T02:16:46.701" intervalms="703.569"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<af-start id="900" threadId="00007F2F3137CFD0" totalBytesRequested="40" timestamp="2015-12-17T02:16:46.702" intervalms="703.564" /> | |
<cycle-start id="901" type="global" contextid="0" timestamp="2015-12-17T02:16:46.702" intervalms="703.569" /> | |
<gc-start id="902" type="global" contextid="901" timestamp="2015-12-17T02:16:46.702"> | |
<mem-info id="903" free="0" total="128712704" percent="0"> | |
<mem type="tenure" free="0" total="128712704" percent="0" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="72734056" > | |
<allocated-bytes non-tlh="437616" tlh="72296440" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="72734056" /> | |
</allocation-stats> | |
<gc-op id="904" type="mark" timems="120.739" contextid="901" timestamp="2015-12-17T02:16:46.823"> | |
<trace-info objectcount="343634" scancount="231364" scanbytes="9273152" /> | |
</gc-op> | |
<gc-op id="905" type="scan" timems="101.760" contextid="901" timestamp="2015-12-17T02:16:46.839" /> | |
<gc-op id="906" type="finalize" timems="34.198" contextid="901" timestamp="2015-12-17T02:16:46.839" /> | |
<finalizer-duration-micros none="0" object="2" class="3552" module="27" float="0" string="0" regexp="0" array="1" hash="0" struct="2009" bignum="1" file="1" data="1396" match="2" complex="0" rational="0" nil="0" true="0" false="0" symbol="17" fixnum="0" omrbuf="0" undef="0" node="1" iclass="60" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=43 table-1=76 table-2=107 table-3=146 table-4=170 table-5=194 table-6=215 table-7=243 table-8=263 table-9=285 table-10=308 table-11=327 table-12=344 table-13=364 table-14=384 table-15=401 table-16=420 /> | |
<parallel-free-rdata-duration-micros slave-0=4732 /> | |
<free-frozen-strings-micros=3688 /> | |
<cpu-millis scan=58.669 finalize=8.627 /> | |
<cpu-parallelism scan=0.5765 finalize=0.2523 /> | |
<gc-op id="907" type="sweep" timems="15.319" contextid="901" timestamp="2015-12-17T02:16:46.856" /> | |
<gc-end id="908" type="global" contextid="901" durationms="155.465" usertimems="61.741" systemtimems="11.400" timestamp="2015-12-17T02:16:46.857" activeThreads="1"> | |
<mem-info id="909" free="72517656" total="128712704" percent="56"> | |
<mem type="tenure" free="72517656" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="910" type="global" contextid="901" timestamp="2015-12-17T02:16:46.858" /> | |
<allocation-satisfied id="911" threadId="00007F2F313786E0" bytesRequested="40" /> | |
<af-end id="912" timestamp="2015-12-17T02:16:46.858" threadId="00007F2F3137CFD0" success="true" /> | |
<exclusive-end id="913" timestamp="2015-12-17T02:16:46.858" durationms="156.478" /> | |
<exclusive-start id="914" timestamp="2015-12-17T02:17:14.938" intervalms="28230.354"> | |
<response-info timems="0.000" idlems="0.000" threads="0" lastid="0000000000000000" lastname="OMR_VMThread [" /> | |
</exclusive-start> | |
<sys-start reason="explicit" id="915" timestamp="2015-12-17T02:17:14.973" intervalms="53626.596" /> | |
<cycle-start id="916" type="global" contextid="0" timestamp="2015-12-17T02:17:14.975" intervalms="28272.720" /> | |
<gc-start id="917" type="global" contextid="916" timestamp="2015-12-17T02:17:14.976"> | |
<mem-info id="918" free="2973472" total="128712704" percent="2"> | |
<mem type="tenure" free="2973472" total="128712704" percent="2" /> | |
</mem-info> | |
</gc-start> | |
<allocation-stats totalBytes="69154368" > | |
<allocated-bytes non-tlh="572768" tlh="68581600" /> | |
<largest-consumer threadName="OMR_VMThread [" threadId="00007F2F313786E0" bytes="69102952" /> | |
</allocation-stats> | |
<gc-op id="919" type="mark" timems="745.990" contextid="916" timestamp="2015-12-17T02:17:15.755"> | |
<trace-info objectcount="335889" scancount="226273" scanbytes="9051000" /> | |
</gc-op> | |
<gc-op id="920" type="scan" timems="685.678" contextid="916" timestamp="2015-12-17T02:17:15.827" /> | |
<gc-op id="921" type="finalize" timems="130.266" contextid="916" timestamp="2015-12-17T02:17:15.901" /> | |
<finalizer-duration-micros none="0" object="1340" class="22708" module="29" float="0" string="1" regexp="0" array="0" hash="0" struct="3822" bignum="1" file="4" data="3635" match="1" complex="0" rational="0" nil="0" true="0" false="0" symbol="17" fixnum="0" omrbuf="0" undef="0" node="0" iclass="278" zombie="0" /> | |
<free-ivar-tables-duration-micros table-0=44 table-1=81 table-2=113 table-3=143 table-4=175 table-5=206 table-6=228 table-7=255 table-8=282 table-9=310 table-10=341 table-11=363 table-12=391 table-13=416 table-14=439 table-15=468 table-16=493 /> | |
<parallel-free-rdata-duration-micros slave-0=3844 /> | |
<free-frozen-strings-micros=5821 /> | |
<cpu-millis scan=123.280 finalize=18.177 /> | |
<cpu-parallelism scan=0.1798 finalize=0.1395 /> | |
<gc-op id="922" type="sweep" timems="57.216" contextid="916" timestamp="2015-12-17T02:17:15.981" /> | |
<gc-end id="923" type="global" contextid="916" durationms="1038.827" usertimems="103.158" systemtimems="63.508" timestamp="2015-12-17T02:17:16.014" activeThreads="1"> | |
<mem-info id="924" free="73353480" total="128712704" percent="56"> | |
<mem type="tenure" free="73353480" total="128712704" percent="56" /> | |
</mem-info> | |
</gc-end> | |
<cycle-end id="925" type="global" contextid="916" timestamp="2015-12-17T02:17:16.015" /> | |
<sys-end id="926" timestamp="2015-12-17T02:17:16.016" /> | |
<exclusive-end id="927" timestamp="2015-12-17T02:17:16.017" durationms="1084.803" /> | |
</verbosegc> |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment
Refresh