node winston heap usage under load measured with memwatch

heap-usage-with-util-console

{ nodes: 42444,
  time: Mon Jul 06 2015 16:36:48 GMT+0100 (WEST),
  size_bytes: 7244456,
  size: '6.91 mb' }
{ nodes: 45852,
  time: Mon Jul 06 2015 16:36:48 GMT+0100 (WEST),
  size_bytes: 7755120,
  size: '7.4 mb' }
{ num_full_gc: 145,
  num_inc_gc: 105,
  heap_compactions: 145,
  usage_trend: 1.2,
  estimated_base: 6856145,
  current_base: 6922024,
  min: 6175400,
  max: 6947856 }
{ nodes: 42489,
  time: Mon Jul 06 2015 16:36:48 GMT+0100 (WEST),
  size_bytes: 7265608,
  size: '6.93 mb' }
{ nodes: 42356,
  time: Mon Jul 06 2015 16:36:49 GMT+0100 (WEST),
  size_bytes: 7240048,
  size: '6.9 mb' }
{ num_full_gc: 146,
  num_inc_gc: 105,
  heap_compactions: 146,
  usage_trend: 1.2,
  estimated_base: 6858004,
  current_base: 6874736,
  min: 6175400,
  max: 6947856 }
{ nodes: 42240,
  time: Mon Jul 06 2015 16:36:49 GMT+0100 (WEST),
  size_bytes: 7221720,
  size: '6.89 mb' }
{ nodes: 42264,
  time: Mon Jul 06 2015 16:36:49 GMT+0100 (WEST),
  size_bytes: 7225288,
  size: '6.89 mb' }
{ num_full_gc: 147,
  num_inc_gc: 105,
  heap_compactions: 147,
  usage_trend: 1.1,
  estimated_base: 6853246,
  current_base: 6810432,
  min: 6175400,
  max: 6947856 }
{ nodes: 42530,
  time: Mon Jul 06 2015 16:36:49 GMT+0100 (WEST),
  size_bytes: 7258232,
  size: '6.92 mb' }
{ nodes: 42198,
  time: Mon Jul 06 2015 16:36:49 GMT+0100 (WEST),
  size_bytes: 7215032,
  size: '6.88 mb' }
{ num_full_gc: 148,
  num_inc_gc: 105,
  heap_compactions: 148,
  usage_trend: 1.1,
  estimated_base: 6848964,
  current_base: 6810432,
  min: 6175400,
  max: 6947856 }
{ nodes: 42420,
  time: Mon Jul 06 2015 16:36:49 GMT+0100 (WEST),
  size_bytes: 7242480,
  size: '6.91 mb' }
{ nodes: 42424,
  time: Mon Jul 06 2015 16:36:50 GMT+0100 (WEST),
  size_bytes: 7244808,
  size: '6.91 mb' }
{ num_full_gc: 149,
  num_inc_gc: 105,
  heap_compactions: 149,
  usage_trend: 1,
  estimated_base: 6845110,
  current_base: 6810432,
  min: 6175400,
  max: 6947856 }
{ nodes: 42312,
  time: Mon Jul 06 2015 16:36:50 GMT+0100 (WEST),
  size_bytes: 7227392,
  size: '6.89 mb' }
{ nodes: 42313,
  time: Mon Jul 06 2015 16:36:50 GMT+0100 (WEST),
  size_bytes: 7227784,
  size: '6.89 mb' }
{ num_full_gc: 150,
  num_inc_gc: 105,
  heap_compactions: 150,
  usage_trend: 1.1,
  estimated_base: 6848999,
  current_base: 6884008,
  min: 6175400,
  max: 6947856 }
{ nodes: 42579,
  time: Mon Jul 06 2015 16:36:50 GMT+0100 (WEST),
  size_bytes: 7260736,
  size: '6.92 mb' }
{ nodes: 42260,
  time: Mon Jul 06 2015 16:36:50 GMT+0100 (WEST),
  size_bytes: 7219416,
  size: '6.88 mb' }
{ num_full_gc: 151,
  num_inc_gc: 105,
  heap_compactions: 151,
  usage_trend: 1,
  estimated_base: 6844910,
  current_base: 6808112,
  min: 6175400,
  max: 6947856 }
...
{ nodes: 42482,
  time: Mon Jul 06 2015 16:36:50 GMT+0100 (WEST),
  size_bytes: 7246864,
  size: '6.91 mb' }
{ nodes: 45838,
  time: Mon Jul 06 2015 16:36:51 GMT+0100 (WEST),
  size_bytes: 7757160,
  size: '7.4 mb' }
{ num_full_gc: 152,
  num_inc_gc: 110,
  heap_compactions: 152,
  usage_trend: 1.1,
  estimated_base: 6855092,
  current_base: 6946736,
  min: 6175400,
  max: 6947856 }
{ nodes: 42488,
  time: Mon Jul 06 2015 16:36:51 GMT+0100 (WEST),
  size_bytes: 7265984,
  size: '6.93 mb' }
{ nodes: 42358,
  time: Mon Jul 06 2015 16:36:51 GMT+0100 (WEST),
  size_bytes: 7240680,
  size: '6.91 mb' }
{ num_full_gc: 153,
  num_inc_gc: 110,
  heap_compactions: 153,
  usage_trend: 1.2,
  estimated_base: 6858522,
  current_base: 6889400,
  min: 6175400,
  max: 6947856 }
{ nodes: 42247,
  time: Mon Jul 06 2015 16:36:52 GMT+0100 (WEST),
  size_bytes: 7223280,
  size: '6.89 mb' }
{ nodes: 42264,
  time: Mon Jul 06 2015 16:36:52 GMT+0100 (WEST),
  size_bytes: 7225704,
  size: '6.89 mb' }
{ num_full_gc: 154,
  num_inc_gc: 110,
  heap_compactions: 154,
  usage_trend: 1.1,
  estimated_base: 6855428,
  current_base: 6827584,
  min: 6175400,
  max: 6947856 }
{ nodes: 42529,
  time: Mon Jul 06 2015 16:36:52 GMT+0100 (WEST),
  size_bytes: 7258632,
  size: '6.92 mb' }
{ nodes: 42198,
  time: Mon Jul 06 2015 16:36:52 GMT+0100 (WEST),
  size_bytes: 7215448,
  size: '6.88 mb' }
{ num_full_gc: 155,
  num_inc_gc: 110,
  heap_compactions: 155,
  usage_trend: 1.1,
  estimated_base: 6852643,
  current_base: 6827584,
  min: 6175400,
  max: 6947856 }
{ nodes: 42420,
  time: Mon Jul 06 2015 16:36:52 GMT+0100 (WEST),
  size_bytes: 7242896,
  size: '6.91 mb' }
{ nodes: 42424,
  time: Mon Jul 06 2015 16:36:52 GMT+0100 (WEST),
  size_bytes: 7245224,
  size: '6.91 mb' }
{ num_full_gc: 156,
  num_inc_gc: 110,
  heap_compactions: 156,
  usage_trend: 1,
  estimated_base: 6850137,
  current_base: 6827584,
  min: 6175400,
  max: 6947856 }
{ nodes: 42313,
  time: Mon Jul 06 2015 16:36:53 GMT+0100 (WEST),
  size_bytes: 7227824,
  size: '6.89 mb' }
{ nodes: 42313,
  time: Mon Jul 06 2015 16:36:53 GMT+0100 (WEST),
  size_bytes: 7228200,
  size: '6.89 mb' }
{ num_full_gc: 157,
  num_inc_gc: 110,
  heap_compactions: 157,
  usage_trend: 1.1,
  estimated_base: 6854290,
  current_base: 6891672,
  min: 6175400,
  max: 6947856 }
{ nodes: 42578,
  time: Mon Jul 06 2015 16:36:53 GMT+0100 (WEST),
  size_bytes: 7261136,
  size: '6.92 mb' }
{ nodes: 42260,
  time: Mon Jul 06 2015 16:36:53 GMT+0100 (WEST),
  size_bytes: 7219832,
  size: '6.89 mb' }
{ num_full_gc: 158,
  num_inc_gc: 110,
  heap_compactions: 158,
  usage_trend: 1,
  estimated_base: 6850628,
  current_base: 6817672,
  min: 6175400,
  max: 6947856 }
  ...
{ nodes: 42482,
  time: Mon Jul 06 2015 16:36:53 GMT+0100 (WEST),
  size_bytes: 7247280,
  size: '6.91 mb' }
{ nodes: 45800,
  time: Mon Jul 06 2015 16:36:54 GMT+0100 (WEST),
  size_bytes: 7750704,
  size: '7.39 mb' }
{ num_full_gc: 159,
  num_inc_gc: 115,
  heap_compactions: 159,
  usage_trend: 1.1,
  estimated_base: 6858226,
  current_base: 6926616,
  min: 6175400,
  max: 6947856 }
{ nodes: 42454,
  time: Mon Jul 06 2015 16:36:54 GMT+0100 (WEST),
  size_bytes: 7263496,
  size: '6.93 mb' }
{ nodes: 42322,
  time: Mon Jul 06 2015 16:36:54 GMT+0100 (WEST),
  size_bytes: 7237928,
  size: '6.9 mb' }
{ num_full_gc: 160,
  num_inc_gc: 115,
  heap_compactions: 160,
  usage_trend: 1.1,
  estimated_base: 6859729,
  current_base: 6873256,
  min: 6175400,
  max: 6947856 }
{ nodes: 42207,
  time: Mon Jul 06 2015 16:36:54 GMT+0100 (WEST),
  size_bytes: 7219616,
  size: '6.89 mb' }
{ nodes: 42227,
  time: Mon Jul 06 2015 16:36:54 GMT+0100 (WEST),
  size_bytes: 7222936,
  size: '6.89 mb' }
{ num_full_gc: 161,
  num_inc_gc: 115,
  heap_compactions: 161,
  usage_trend: 1,
  estimated_base: 6854900,
  current_base: 6811440,
  min: 6175400,
  max: 6947856 }
{ nodes: 42493,
  time: Mon Jul 06 2015 16:36:55 GMT+0100 (WEST),
  size_bytes: 7255880,
  size: '6.92 mb' }
{ nodes: 42161,
  time: Mon Jul 06 2015 16:36:55 GMT+0100 (WEST),
  size_bytes: 7212680,
  size: '6.88 mb' }
{ num_full_gc: 162,
  num_inc_gc: 115,
  heap_compactions: 162,
  usage_trend: 1,
  estimated_base: 6850554,
  current_base: 6811440,
  min: 6175400,
  max: 6947856 }
{ nodes: 42382,
  time: Mon Jul 06 2015 16:36:55 GMT+0100 (WEST),
  size_bytes: 7240112,
  size: '6.9 mb' }
{ nodes: 42389,
  time: Mon Jul 06 2015 16:36:55 GMT+0100 (WEST),
  size_bytes: 7242488,
  size: '6.91 mb' }
{ num_full_gc: 163,
  num_inc_gc: 115,
  heap_compactions: 163,
  usage_trend: 0.9,
  estimated_base: 6846642,
  current_base: 6811440,
  min: 6175400,
  max: 6947856 }
{ nodes: 42278,
  time: Mon Jul 06 2015 16:36:55 GMT+0100 (WEST),
  size_bytes: 7225080,
  size: '6.89 mb' }
{ nodes: 42276,
  time: Mon Jul 06 2015 16:36:55 GMT+0100 (WEST),
  size_bytes: 7225432,
  size: '6.89 mb' }
{ num_full_gc: 164,
  num_inc_gc: 115,
  heap_compactions: 164,
  usage_trend: 1,
  estimated_base: 6850487,
  current_base: 6885096,
  min: 6175400,
  max: 6947856 }
{ nodes: 42542,
  time: Mon Jul 06 2015 16:36:56 GMT+0100 (WEST),
  size_bytes: 7258384,
  size: '6.92 mb' }
{ nodes: 42225,
  time: Mon Jul 06 2015 16:36:56 GMT+0100 (WEST),
  size_bytes: 7217096,
  size: '6.88 mb' }
{ num_full_gc: 165,
  num_inc_gc: 115,
  heap_compactions: 165,
  usage_trend: 0.9,
  estimated_base: 6846446,
  current_base: 6810080,
  min: 6175400,
  max: 6947856 }
  

heap-usage-with-winston-console-transport

{ nodes: 99976,
  time: Mon Jul 06 2015 16:00:37 GMT+0100 (WEST),
  size_bytes: 11438696,
  size: '10.91 mb' }
{ nodes: 113553,
  time: Mon Jul 06 2015 16:00:38 GMT+0100 (WEST),
  size_bytes: 12726104,
  size: '12.14 mb' }
{ num_full_gc: 48,
  num_inc_gc: 35,
  heap_compactions: 48,
  usage_trend: 13.3,
  estimated_base: 10804475,
  current_base: 12011320,
  min: 6212224,
  max: 12011320 }
{ nodes: 109613,
  time: Mon Jul 06 2015 16:00:39 GMT+0100 (WEST),
  size_bytes: 12150168,
  size: '11.59 mb' }
{ nodes: 109591,
  time: Mon Jul 06 2015 16:00:39 GMT+0100 (WEST),
  size_bytes: 12133840,
  size: '11.57 mb' }
{ num_full_gc: 49,
  num_inc_gc: 35,
  heap_compactions: 49,
  usage_trend: 13.9,
  estimated_base: 10913423,
  current_base: 11893960,
  min: 6212224,
  max: 12011320 }
{ nodes: 109704,
  time: Mon Jul 06 2015 16:00:40 GMT+0100 (WEST),
  size_bytes: 12145312,
  size: '11.58 mb' }
{ nodes: 109415,
  time: Mon Jul 06 2015 16:00:40 GMT+0100 (WEST),
  size_bytes: 12109472,
  size: '11.55 mb' }
{ num_full_gc: 50,
  num_inc_gc: 35,
  heap_compactions: 50,
  usage_trend: 14.3,
  estimated_base: 11010044,
  current_base: 11879640,
  min: 6212224,
  max: 12011320 }
{ nodes: 109772,
  time: Mon Jul 06 2015 16:00:41 GMT+0100 (WEST),
  size_bytes: 12153384,
  size: '11.59 mb' }
{ nodes: 109642,
  time: Mon Jul 06 2015 16:00:41 GMT+0100 (WEST),
  size_bytes: 12135912,
  size: '11.57 mb' }
{ num_full_gc: 51,
  num_inc_gc: 35,
  heap_compactions: 51,
  usage_trend: 14.7,
  estimated_base: 11097003,
  current_base: 11879640,
  min: 6212224,
  max: 12011320 }
{ nodes: 109548,
  time: Mon Jul 06 2015 16:00:42 GMT+0100 (WEST),
  size_bytes: 12123648,
  size: '11.56 mb' }
{ nodes: 109657,
  time: Mon Jul 06 2015 16:00:42 GMT+0100 (WEST),
  size_bytes: 12138384,
  size: '11.58 mb' }
{ num_full_gc: 52,
  num_inc_gc: 35,
  heap_compactions: 52,
  usage_trend: 15,
  estimated_base: 11175266,
  current_base: 11879640,
  min: 6212224,
  max: 12011320 }
{ nodes: 109770,
  time: Mon Jul 06 2015 16:00:42 GMT+0100 (WEST),
  size_bytes: 12149856,
  size: '11.59 mb' }
{ nodes: 109464,
  time: Mon Jul 06 2015 16:00:43 GMT+0100 (WEST),
  size_bytes: 12111968,
  size: '11.55 mb' }
{ num_full_gc: 53,
  num_inc_gc: 35,
  heap_compactions: 53,
  usage_trend: 15.3,
  estimated_base: 11246761,
  current_base: 11890216,
  min: 6212224,
  max: 12011320 }
{ nodes: 109821,
  time: Mon Jul 06 2015 16:00:43 GMT+0100 (WEST),
  size_bytes: 12155872,
  size: '11.59 mb' }
{ nodes: 109705,
  time: Mon Jul 06 2015 16:00:44 GMT+0100 (WEST),
  size_bytes: 12140312,
  size: '11.58 mb' }
{ num_full_gc: 54,
  num_inc_gc: 35,
  heap_compactions: 54,
  usage_trend: 15.5,
  estimated_base: 11309199,
  current_base: 11871144,
  min: 6212224,
  max: 12011320 }
...
{ nodes: 109611,
  time: Mon Jul 06 2015 16:00:44 GMT+0100 (WEST),
  size_bytes: 12128048,
  size: '11.57 mb' }
{ nodes: 120233,
  time: Mon Jul 06 2015 16:00:45 GMT+0100 (WEST),
  size_bytes: 13204968,
  size: '12.59 mb' }
{ num_full_gc: 55,
  num_inc_gc: 40,
  heap_compactions: 55,
  usage_trend: 16,
  estimated_base: 11421874,
  current_base: 12435952,
  min: 6212224,
  max: 12435952 }
{ nodes: 116296,
  time: Mon Jul 06 2015 16:00:46 GMT+0100 (WEST),
  size_bytes: 12629824,
  size: '12.04 mb' }
{ nodes: 116272,
  time: Mon Jul 06 2015 16:00:46 GMT+0100 (WEST),
  size_bytes: 12612448,
  size: '12.03 mb' }
{ num_full_gc: 56,
  num_inc_gc: 40,
  heap_compactions: 56,
  usage_trend: 16.5,
  estimated_base: 11517305,
  current_base: 12376192,
  min: 6212224,
  max: 12435952 }
{ nodes: 116385,
  time: Mon Jul 06 2015 16:00:47 GMT+0100 (WEST),
  size_bytes: 12623920,
  size: '12.04 mb' }
{ nodes: 116096,
  time: Mon Jul 06 2015 16:00:47 GMT+0100 (WEST),
  size_bytes: 12588080,
  size: '12 mb' }
{ num_full_gc: 57,
  num_inc_gc: 40,
  heap_compactions: 57,
  usage_trend: 16.8,
  estimated_base: 11602069,
  current_base: 12364952,
  min: 6212224,
  max: 12435952 }
{ nodes: 116453,
  time: Mon Jul 06 2015 16:00:48 GMT+0100 (WEST),
  size_bytes: 12631992,
  size: '12.05 mb' }
{ nodes: 116323,
  time: Mon Jul 06 2015 16:00:48 GMT+0100 (WEST),
  size_bytes: 12614520,
  size: '12.03 mb' }
{ num_full_gc: 58,
  num_inc_gc: 40,
  heap_compactions: 58,
  usage_trend: 17.1,
  estimated_base: 11678357,
  current_base: 12364952,
  min: 6212224,
  max: 12435952 }
{ nodes: 116229,
  time: Mon Jul 06 2015 16:00:49 GMT+0100 (WEST),
  size_bytes: 12602264,
  size: '12.02 mb' }
{ nodes: 116339,
  time: Mon Jul 06 2015 16:00:49 GMT+0100 (WEST),
  size_bytes: 12617008,
  size: '12.03 mb' }
{ num_full_gc: 59,
  num_inc_gc: 40,
  heap_compactions: 59,
  usage_trend: 17.3,
  estimated_base: 11747016,
  current_base: 12364952,
  min: 6212224,
  max: 12435952 }
{ nodes: 116452,
  time: Mon Jul 06 2015 16:00:50 GMT+0100 (WEST),
  size_bytes: 12628480,
  size: '12.04 mb' }
{ nodes: 116145,
  time: Mon Jul 06 2015 16:00:50 GMT+0100 (WEST),
  size_bytes: 12590576,
  size: '12.01 mb' }
{ num_full_gc: 60,
  num_inc_gc: 40,
  heap_compactions: 60,
  usage_trend: 17.5,
  estimated_base: 11809427,
  current_base: 12371128,
  min: 6212224,
  max: 12435952 }
{ nodes: 116502,
  time: Mon Jul 06 2015 16:00:51 GMT+0100 (WEST),
  size_bytes: 12634488,
  size: '12.05 mb' }
{ nodes: 116386,
  time: Mon Jul 06 2015 16:00:51 GMT+0100 (WEST),
  size_bytes: 12618920,
  size: '12.03 mb' }
{ num_full_gc: 61,
  num_inc_gc: 40,
  heap_compactions: 61,
  usage_trend: 17.6,
  estimated_base: 11864413,
  current_base: 12359296,
  min: 6212224,
  max: 12435952 }
...
{ nodes: 116292,
  time: Mon Jul 06 2015 16:00:52 GMT+0100 (WEST),
  size_bytes: 12606656,
  size: '12.02 mb' }
{ nodes: 129701,
  time: Mon Jul 06 2015 16:00:53 GMT+0100 (WEST),
  size_bytes: 13878960,
  size: '13.24 mb' }
{ num_full_gc: 62,
  num_inc_gc: 45,
  heap_compactions: 62,
  usage_trend: 18.3,
  estimated_base: 11999090,
  current_base: 13211184,
  min: 6212224,
  max: 13211184 }
{ nodes: 125734,
  time: Mon Jul 06 2015 16:00:53 GMT+0100 (WEST),
  size_bytes: 13293744,
  size: '12.68 mb' }
{ nodes: 125741,
  time: Mon Jul 06 2015 16:00:54 GMT+0100 (WEST),
  size_bytes: 13287328,
  size: '12.67 mb' }
{ num_full_gc: 63,
  num_inc_gc: 45,
  heap_compactions: 63,
  usage_trend: 18.8,
  estimated_base: 12108181,
  current_base: 13090008,
  min: 6212224,
  max: 13211184 }
{ nodes: 125854,
  time: Mon Jul 06 2015 16:00:54 GMT+0100 (WEST),
  size_bytes: 13298800,
  size: '12.68 mb' }
{ nodes: 125563,
  time: Mon Jul 06 2015 16:00:55 GMT+0100 (WEST),
  size_bytes: 13262776,
  size: '12.65 mb' }
{ num_full_gc: 64,
  num_inc_gc: 45,
  heap_compactions: 64,
  usage_trend: 19.3,
  estimated_base: 12204201,
  current_base: 13068384,
  min: 6212224,
  max: 13211184 }
{ nodes: 125920,
  time: Mon Jul 06 2015 16:00:55 GMT+0100 (WEST),
  size_bytes: 13306696,
  size: '12.69 mb' }
{ nodes: 125790,
  time: Mon Jul 06 2015 16:00:56 GMT+0100 (WEST),
  size_bytes: 13289216,
  size: '12.67 mb' }
{ num_full_gc: 65,
  num_inc_gc: 45,
  heap_compactions: 65,
  usage_trend: 19.6,
  estimated_base: 12290619,
  current_base: 13068384,
  min: 6212224,
  max: 13211184 }
{ nodes: 125696,
  time: Mon Jul 06 2015 16:00:56 GMT+0100 (WEST),
  size_bytes: 13276968,
  size: '12.66 mb' }
{ nodes: 125806,
  time: Mon Jul 06 2015 16:00:57 GMT+0100 (WEST),
  size_bytes: 13291704,
  size: '12.68 mb' }
{ num_full_gc: 66,
  num_inc_gc: 45,
  heap_compactions: 66,
  usage_trend: 19.9,
  estimated_base: 12368395,
  current_base: 13068384,
  min: 6212224,
  max: 13211184 }
{ nodes: 125951,
  time: Mon Jul 06 2015 16:00:57 GMT+0100 (WEST),
  size_bytes: 13318504,
  size: '12.7 mb' }
{ nodes: 125644,
  time: Mon Jul 06 2015 16:00:58 GMT+0100 (WEST),
  size_bytes: 13280600,
  size: '12.67 mb' }
{ num_full_gc: 67,
  num_inc_gc: 45,
  heap_compactions: 67,
  usage_trend: 20.1,
  estimated_base: 12440298,
  current_base: 13087432,
  min: 6212224,
  max: 13211184 }
{ nodes: 126001,
  time: Mon Jul 06 2015 16:00:58 GMT+0100 (WEST),
  size_bytes: 13324520,
  size: '12.71 mb' }
{ nodes: 125885,
  time: Mon Jul 06 2015 16:00:59 GMT+0100 (WEST),
  size_bytes: 13308944,
  size: '12.69 mb' }
{ num_full_gc: 68,
  num_inc_gc: 45,
  heap_compactions: 68,
  usage_trend: 20.2,
  estimated_base: 12502261,
  current_base: 13059928,
  min: 6212224,
  max: 13211184 }

{ nodes: 126001,
  time: Mon Jul 06 2015 16:00:58 GMT+0100 (WEST),
  size_bytes: 13324520,
  size: '12.71 mb' }
{ nodes: 125885,
  time: Mon Jul 06 2015 16:00:59 GMT+0100 (WEST),
  size_bytes: 13308944,
  size: '12.69 mb' }
{ num_full_gc: 68,
  num_inc_gc: 45,
  heap_compactions: 68,
  usage_trend: 20.2,
  estimated_base: 12502261,
  current_base: 13059928,
  min: 6212224,
  max: 13211184 }

Represents:

• Before full GC
• After full GC
• Stats

Constantly increasing usage_trend and ever growing size indicates a memory leak.