--- Output check failed: 30 -----
[0.008s][info][gc] Using G1
[0.013s][trace][stringtable] Start size: 65536 (16)
[2.230s][info ][gc,start ] GC(0) Pause Young (Normal) (G1 Evacuation Pause)
[2.400s][trace][stringtable] Uncleaned items:300001
[2.400s][debug][stringtable] Concurrent work triggered, live factor: 10.1504 dead factor: 4.57765
[2.402s][info ][gc ] GC(0) Pause Young (Normal) (G1 Evacuation Pause) 48M->27M(258M) 171.804ms
[2.402s][debug][stringtable,perf] Concurrent work, live factor: 10.1504
[2.402s][trace][stringtable ] Started to grow
[2.876s][debug][stringtable,perf] Grow, 0.4743730 secs
[2.876s][debug][stringtable ] Grown to size:131072
[3.132s][info ][gc,start ] GC(1) Pause Young (Normal) (G1 Evacuation Pause)
[3.211s][trace][stringtable ] Uncleaned items:100000
[3.211s][debug][stringtable ] Concurrent work triggered, live factor: 4.44971 dead factor: 0.762939
[3.212s][info ][gc ] GC(1) Pause Young (Normal) (G1 Evacuation Pause) 42M->37M(258M) 79.879ms
[3.212s][debug][stringtable,perf] Concurrent work, live factor: 4.44971
[3.212s][trace][stringtable ] Started to grow
[3.692s][debug][stringtable,perf] Grow, 0.4791735 secs
[3.692s][debug][stringtable ] Grown to size:262144
[4.139s][info ][gc,start ] GC(2) Pause Young (Normal) (G1 Evacuation Pause)
[4.255s][trace][stringtable ] Uncleaned items:249906
[4.255s][debug][stringtable ] Concurrent work triggered, live factor: 3.44822 dead factor: 0.953316
[4.256s][info ][gc ] GC(2) Pause Young (Normal) (G1 Evacuation Pause) 66M->49M(258M) 117.272ms
[4.256s][debug][stringtable,perf] Concurrent work, live factor: 3.44822
[4.257s][trace][stringtable ] Started to grow
[4.946s][debug][stringtable,perf] Grow, 0.6894955 secs
[4.947s][debug][stringtable ] Grown to size:524288
[5.233s][info ][gc,start ] GC(3) Pause Young (Normal) (G1 Evacuation Pause)
[5.396s][trace][stringtable ] Uncleaned items:250000
[5.396s][debug][stringtable ] Concurrent work triggered, live factor: 2.27316 dead factor: 0.476837
[5.397s][info ][gc ] GC(3) Pause Young (Normal) (G1 Evacuation Pause) 86M->71M(258M) 164.037ms
[5.397s][debug][stringtable,perf] Concurrent work, live factor: 2.27316
[5.400s][trace][stringtable ] Started to grow
[6.428s][debug][stringtable,perf] Grow, 1.0282199 secs
[6.429s][debug][stringtable ] Grown to size:1048576
[6.548s][info ][gc,start ] GC(4) Pause Young (Normal) (G1 Evacuation Pause)
[6.725s][trace][stringtable ] Uncleaned items:250000
[6.727s][info ][gc ] GC(4) Pause Young (Normal) (G1 Evacuation Pause) 108M->92M(393M) 179.198ms
[8.064s][info ][gc,start ] GC(5) Pause Young (Normal) (G1 Evacuation Pause)
[8.268s][trace][stringtable ] Uncleaned items:490052
[8.270s][info ][gc ] GC(5) Pause Young (Normal) (G1 Evacuation Pause) 135M->113M(393M) 205.909ms
[9.681s][info ][gc,start ] GC(6) Pause Young (Normal) (G1 Evacuation Pause)
[9.862s][trace][stringtable ] Uncleaned items:542094
[9.862s][debug][stringtable ] Concurrent work triggered, live factor: 2.2895 dead factor: 0.516981
[9.864s][info ][gc ] GC(6) Pause Young (Normal) (G1 Evacuation Pause) 158M->140M(393M) 183.124ms
[9.864s][debug][stringtable,perf] Concurrent work, live factor: 2.2895
[9.867s][trace][stringtable ] Started to grow
[11.194s][info ][gc,start ] GC(7) Pause Young (Normal) (G1 Evacuation Pause)
[11.340s][trace][stringtable ] Uncleaned items:397393
[11.342s][info ][gc ] GC(7) Pause Young (Normal) (G1 Evacuation Pause) 184M->165M(393M) 147.404ms
[11.994s][debug][stringtable,perf] Grow, 2.1270961 secs
[11.994s][debug][stringtable ] Grown to size:2097152
[12.553s][info ][gc,start ] GC(8) Pause Young (Normal) (G1 Evacuation Pause)
[12.690s][trace][stringtable ] Uncleaned items:500861
[12.692s][info ][gc ] GC(8) Pause Young (Normal) (G1 Evacuation Pause) 214M->189M(495M) 138.856ms
[14.025s][info ][gc,start ] GC(9) Pause Young (Normal) (G1 Evacuation Pause)
[14.205s][trace][stringtable ] Uncleaned items:648384
[14.206s][info ][gc ] GC(9) Pause Young (Normal) (G1 Evacuation Pause) 247M->221M(495M) 181.539ms
[15.511s][info ][gc,start ] GC(10) Pause Young (Normal) (G1 Evacuation Pause)
[15.669s][trace][stringtable ] Uncleaned items:782329
[15.671s][info ][gc ] GC(10) Pause Young (Normal) (G1 Evacuation Pause) 273M->248M(495M) 160.165ms
[17.265s][info ][gc,start ] GC(11) Pause Young (Concurrent Start) (G1 Evacuation Pause)
[17.448s][trace][stringtable ] Uncleaned items:893218
[17.448s][debug][stringtable ] Concurrent work triggered, live factor: 2.20815 dead factor: 0.42592
[17.450s][info ][gc ] GC(11) Pause Young (Concurrent Start) (G1 Evacuation Pause) 305M->279M(495M) 184.582ms
[17.450s][info ][gc ] GC(12) Concurrent Mark Cycle
[17.450s][debug][stringtable,perf] Concurrent work, live factor: 2.20815
[17.454s][trace][stringtable ] Started to grow
[18.245s][info ][gc,start ] GC(12) Pause Remark
[18.275s][trace][stringtable ] Uncleaned items:560588
[18.275s][info ][gc ] GC(12) Pause Remark 303M->303M(495M) 30.271ms
[18.663s][info ][gc,start ] GC(12) Pause Cleanup
[18.663s][info ][gc ] GC(12) Pause Cleanup 316M->316M(495M) 0.082ms
[18.664s][info ][gc ] GC(12) Concurrent Mark Cycle 1214.326ms
[19.272s][info ][gc,start ] GC(13) Pause Young (Prepare Mixed) (G1 Evacuation Pause)
[19.469s][trace][stringtable ] Uncleaned items:683455
[19.471s][info ][gc ] GC(13) Pause Young (Prepare Mixed) (G1 Evacuation Pause) 337M->312M(495M) 198.349ms
[21.109s][info ][gc,start ] GC(14) Pause Young (Mixed) (G1 Evacuation Pause)
[21.295s][trace][stringtable ] Uncleaned items:619663
[21.297s][info ][gc ] GC(14) Pause Young (Mixed) (G1 Evacuation Pause) 371M->342M(592M) 188.309ms
[21.645s][debug][stringtable,perf] Grow, 4.1902160 secs
[21.646s][debug][stringtable ] Grown to size:4194304
[22.729s][info ][gc,start ] GC(15) Pause Young (Mixed) (G1 Evacuation Pause)
[22.925s][trace][stringtable ] Uncleaned items:739162
[22.927s][info ][gc ] GC(15) Pause Young (Mixed) (G1 Evacuation Pause) 400M->371M(592M) 198.024ms
[22.961s][info ][gc,start ] GC(16) Pause Full (System.gc())
[22.976s][trace][stringtable ] Uncleaned items:5780861
[22.976s][debug][stringtable ] Concurrent work triggered, live factor: 1.37827 dead factor: 1.37826
[22.996s][info ][gc ] GC(16) Pause Full (System.gc()) 372M->1M(20M) 34.967ms
[22.996s][debug][stringtable,perf] Concurrent work, live factor: 1.37827
[23.095s][info ][gc,start ] GC(17) Pause Young (Normal) (G1 Evacuation Pause)
[23.114s][trace][stringtable ] Uncleaned items:5419677
[23.115s][info ][gc ] GC(17) Pause Young (Normal) (G1 Evacuation Pause) 5M->6M(20M) 20.243ms
[23.159s][info ][gc,start ] GC(18) Pause Young (Concurrent Start) (G1 Evacuation Pause)
[23.174s][trace][stringtable ] Uncleaned items:5261762
[23.175s][info ][gc ] GC(18) Pause Young (Concurrent Start) (G1 Evacuation Pause) 8M->10M(20M) 15.803ms
[23.175s][info ][gc ] GC(19) Concurrent Mark Cycle
[23.195s][info ][gc,start ] GC(19) Pause Remark
[23.199s][trace][stringtable ] Uncleaned items:5190271
[23.200s][info ][gc ] GC(19) Pause Remark 10M->10M(25M) 4.675ms
[23.206s][info ][gc,start ] GC(20) Pause Young (Normal) (G1 Evacuation Pause)
[23.210s][trace][stringtable ] Uncleaned items:5191761
[23.211s][info ][gc ] GC(20) Pause Young (Normal) (G1 Evacuation Pause) 11M->9M(141M) 4.684ms
[23.214s][info ][gc,start ] GC(19) Pause Cleanup
[23.214s][info ][gc ] GC(19) Pause Cleanup 9M->9M(141M) 0.038ms
[23.214s][info ][gc ] GC(19) Concurrent Mark Cycle 39.183ms
[24.054s][info ][gc,start ] GC(21) Pause Young (Prepare Mixed) (G1 Evacuation Pause)
[24.125s][trace][stringtable ] Uncleaned items:2401496
[24.126s][info ][gc ] GC(21) Pause Young (Prepare Mixed) (G1 Evacuation Pause) 49M->31M(141M) 72.274ms
[24.771s][info ][gc,start ] GC(22) Pause Young (Mixed) (G1 Evacuation Pause)
[24.854s][trace][stringtable ] Uncleaned items:835484
[24.855s][info ][gc ] GC(22) Pause Young (Mixed) (G1 Evacuation Pause) 67M->50M(141M) 83.788ms
[25.057s][debug][stringtable,perf] Clean, 2.0605852 secs
[25.057s][debug][stringtable ] Cleaned 7465965 of 7951837
[25.409s][info ][gc,start ] GC(23) Pause Young (Normal) (G1 Evacuation Pause)
[25.479s][trace][stringtable ] Uncleaned items:529490
[25.480s][info ][gc ] GC(23) Pause Young (Normal) (G1 Evacuation Pause) 82M->66M(141M) 70.936ms
[25.909s][info ][gc,start ] GC(24) Pause Young (Normal) (G1 Evacuation Pause)
[25.967s][trace][stringtable ] Uncleaned items:639490
[25.969s][info ][gc ] GC(24) Pause Young (Normal) (G1 Evacuation Pause) 92M->80M(141M) 59.796ms
[26.346s][info ][gc,start ] GC(25) Pause Young (Concurrent Start) (G1 Evacuation Pause)
[26.401s][trace][stringtable ] Uncleaned items:711100
[26.402s][info ][gc ] GC(25) Pause Young (Concurrent Start) (G1 Evacuation Pause) 102M->92M(282M) 56.366ms
[26.402s][info ][gc ] GC(26) Concurrent Mark Cycle
[26.646s][info ][gc,start ] GC(26) Pause Remark
[26.657s][trace][stringtable ] Uncleaned items:674733
[26.657s][info ][gc ] GC(26) Pause Remark 104M->103M(282M) 11.627ms
[26.781s][info ][gc,start ] GC(26) Pause Cleanup
[26.781s][info ][gc ] GC(26) Pause Cleanup 110M->110M(282M) 0.147ms
[26.781s][info ][gc ] GC(26) Concurrent Mark Cycle 379.333ms
[27.583s][info ][gc,start ] GC(27) Pause Young (Prepare Mixed) (G1 Evacuation Pause)
[27.747s][trace][stringtable ] Uncleaned items:951477
[27.748s][info ][gc ] GC(27) Pause Young (Prepare Mixed) (G1 Evacuation Pause) 157M->127M(282M) 165.775ms
[28.509s][info ][gc,start ] GC(28) Pause Young (Mixed) (G1 Evacuation Pause)
[28.629s][trace][stringtable ] Uncleaned items:1049153
[28.630s][info ][gc ] GC(28) Pause Young (Mixed) (G1 Evacuation Pause) 168M->147M(282M) 120.982ms
[29.529s][info ][gc,start ] GC(29) Pause Young (Concurrent Start) (G1 Evacuation Pause)
[29.653s][trace][stringtable ] Uncleaned items:1213733
[29.654s][info ][gc ] GC(29) Pause Young (Concurrent Start) (G1 Evacuation Pause) 196M->169M(282M) 124.608ms
[29.654s][info ][gc ] GC(30) Concurrent Mark Cycle
[30.133s][info ][gc,start ] GC(30) Pause Remark
[30.133s][info ][gc ] GC(30) Pause Remark 193M->193M(282M) 0.016ms
[30.133s][info ][gc ] GC(30) Concurrent Mark Cycle 478.896ms

----------System.err:(15/1047)----------
java.lang.RuntimeException: Missing Callback in [139, 140]
at runtime.stringtable.StringTableCleaningTest.fail(StringTableCleaningTest.java:67)
at runtime.stringtable.StringTableCleaningTest.findCallback(StringTableCleaningTest.java:188)
at runtime.stringtable.StringTableCleaningTest.check(StringTableCleaningTest.java:219)
at runtime.stringtable.StringTableCleaningTest.checkOutput(StringTableCleaningTest.java:231)
at runtime.stringtable.StringTableCleaningTest.main(StringTableCleaningTest.java:63)
at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
at java.base/java.lang.reflect.Method.invoke(Method.java:565)
at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138)
at java.base/java.lang.Thread.run(Thread.java:1447)