Created
June 15, 2011 15:37
-
-
Save pauldix/1027361 to your computer and use it in GitHub Desktop.
brisk log - GC thrashing
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
| INFO [ScheduledTasks:1] 2011-06-15 15:35:40,474 GCInspector.java (line 128) GC for ParNew: 241 ms, 95072 reclaimed leaving 3569921120 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:35:40,971 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:41,475 GCInspector.java (line 128) GC for ParNew: 216 ms, 8995440 reclaimed leaving 5737696856 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:42,584 GCInspector.java (line 128) GC for ParNew: 280 ms, 48616 reclaimed leaving 7905426312 used; max is 10456399872 | |
| INFO [IPC Server handler 1 on 41734] 2011-06-15 15:35:42,778 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:43,585 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 610 ms, 2167917104 reclaimed leaving 2486844944 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:44,713 GCInspector.java (line 128) GC for ParNew: 234 ms, 32672 reclaimed leaving 4654614152 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:45,977 GCInspector.java (line 128) GC for ParNew: 247 ms, 28488 reclaimed leaving 6822346584 used; max is 10456399872 | |
| INFO [IPC Server handler 0 on 41734] 2011-06-15 15:35:46,560 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [IPC Server handler 3 on 41734] 2011-06-15 15:35:47,403 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:47,403 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 631 ms, 6503362848 reclaimed leaving 2486774624 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:48,542 GCInspector.java (line 128) GC for ParNew: 215 ms, -4288 reclaimed leaving 4654567120 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:49,889 GCInspector.java (line 128) GC for ParNew: 251 ms, 33744 reclaimed leaving 6822295144 used; max is 10456399872 | |
| INFO [IPC Server handler 3 on 41734] 2011-06-15 15:35:50,545 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:51,298 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 539 ms, 6503722960 reclaimed leaving 2487177440 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:52,526 GCInspector.java (line 128) GC for ParNew: 302 ms, 7336 reclaimed leaving 4655739088 used; max is 10456399872 | |
| INFO [IPC Server handler 1 on 41734] 2011-06-15 15:35:53,415 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:53,873 GCInspector.java (line 128) GC for ParNew: 248 ms, 12391968 reclaimed leaving 6827750776 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:54,883 GCInspector.java (line 128) GC for ParNew: 223 ms, 43656 reclaimed leaving 4659225872 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:56,204 GCInspector.java (line 128) GC for ParNew: 301 ms, 12712 reclaimed leaving 6827733648 used; max is 10456399872 | |
| INFO [IPC Server handler 3 on 41734] 2011-06-15 15:35:56,955 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:35:57,080 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:57,525 GCInspector.java (line 128) GC for ParNew: 232 ms, 73848 reclaimed leaving 3574950320 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:57,525 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 525 ms, 5421442880 reclaimed leaving 2490653872 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:58,728 GCInspector.java (line 128) GC for ParNew: 214 ms, 64600 reclaimed leaving 5743491504 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:35:59,959 GCInspector.java (line 128) GC for ParNew: 300 ms, 52672 reclaimed leaving 7912399040 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:36:00,293 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:00,961 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 524 ms, 2169057096 reclaimed leaving 2491046264 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:02,083 GCInspector.java (line 128) GC for ParNew: 300 ms, -1688 reclaimed leaving 4660581392 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:03,413 GCInspector.java (line 128) GC for ParNew: 233 ms, 14992 reclaimed leaving 6829890272 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:36:03,414 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:04,806 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 531 ms, 6508021160 reclaimed leaving 2491242120 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:06,032 GCInspector.java (line 128) GC for ParNew: 297 ms, -6920 reclaimed leaving 4660889952 used; max is 10456399872 | |
| INFO [IPC Server handler 3 on 41734] 2011-06-15 15:36:06,938 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [IPC Server handler 1 on 41734] 2011-06-15 15:36:07,383 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:07,383 GCInspector.java (line 128) GC for ParNew: 255 ms, 65144 reclaimed leaving 6830215616 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:08,911 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 537 ms, 6508189032 reclaimed leaving 2491697936 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:09,923 GCInspector.java (line 128) GC for ParNew: 205 ms, -6440 reclaimed leaving 4661435208 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:36:10,610 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:10,924 GCInspector.java (line 128) GC for ParNew: 215 ms, 101376 reclaimed leaving 5746498560 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:11,944 GCInspector.java (line 128) GC for ParNew: 208 ms, 1069896 reclaimed leaving 7916807080 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:12,945 GCInspector.java (line 128) GC for ParNew: 206 ms, 75888 reclaimed leaving 4662451240 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:36:13,609 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:13,946 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 530 ms, 3255278280 reclaimed leaving 2492299208 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:14,992 GCInspector.java (line 128) GC for ParNew: 287 ms, 11952 reclaimed leaving 4662448968 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:15,993 GCInspector.java (line 128) GC for ParNew: 216 ms, 2776456 reclaimed leaving 5747502304 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:17,067 GCInspector.java (line 128) GC for ParNew: 285 ms, 11744 reclaimed leaving 7917978936 used; max is 10456399872 | |
| INFO [IPC Server handler 1 on 41734] 2011-06-15 15:36:17,067 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [IPC Server handler 0 on 41734] 2011-06-15 15:36:17,240 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:18,079 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 552 ms, 6510868928 reclaimed leaving 2492309272 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:19,080 GCInspector.java (line 128) GC for ParNew: 244 ms, 107640 reclaimed leaving 3577385120 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:20,227 GCInspector.java (line 128) GC for ParNew: 290 ms, 54792 reclaimed leaving 5747500304 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:36:20,241 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:21,229 GCInspector.java (line 128) GC for ParNew: 245 ms, 115704 reclaimed leaving 6832947472 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:22,683 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 631 ms, 2187297152 reclaimed leaving 2494630328 used; max is 10456399872 | |
| INFO [IPC Server handler 2 on 41734] 2011-06-15 15:36:23,412 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:23,684 GCInspector.java (line 128) GC for ParNew: 279 ms, 5631408 reclaimed leaving 3581565456 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:25,041 GCInspector.java (line 128) GC for ParNew: 287 ms, 8592 reclaimed leaving 5752466416 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:26,211 GCInspector.java (line 128) GC for ParNew: 212 ms, 37472 reclaimed leaving 7923365544 used; max is 10456399872 | |
| INFO [IPC Server handler 0 on 41734] 2011-06-15 15:36:26,584 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000003_0 0.11111112% reduce > copy (1 of 3 at 0.00 MB/s) > | |
| INFO [IPC Server handler 3 on 41734] 2011-06-15 15:36:26,901 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:27,212 GCInspector.java (line 128) GC for ParNew: 230 ms, 88544 reclaimed leaving 4667045144 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:28,740 GCInspector.java (line 128) GC for ConcurrentMarkSweep: 608 ms, 4341881264 reclaimed leaving 2496100672 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:29,741 GCInspector.java (line 128) GC for ParNew: 244 ms, 1079008 reclaimed leaving 3581749400 used; max is 10456399872 | |
| INFO [IPC Server handler 1 on 41734] 2011-06-15 15:36:30,148 TaskTracker.java (line 2428) attempt_201106151514_0001_r_000000_0 0.11111112% reduce > copy (1 of 3 at 1.03 MB/s) > | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:30,816 GCInspector.java (line 128) GC for ParNew: 215 ms, 82904 reclaimed leaving 5752655688 used; max is 10456399872 | |
| INFO [ScheduledTasks:1] 2011-06-15 15:36:31,957 GCInspector.java (line 128) GC for ParNew: 249 ms, 84720 reclaimed leaving 6838101232 used; max is 10456399872 | |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment