Wishbone metrics

gistfile1.txt

[root@sandbox tippingbucket]# ./tippingbuckettest debug --config tippingbuckettest.json
2012-11-22 23:47:26,748 INFO Server: Starting TippingBucketTest in foreground.
2012-11-22 23:47:26,751 INFO Server: Instance #0 started.
2012-11-22 23:47:26,752 INFO Server: Started with pids: 4956, 4957
2012-11-22 23:47:26,763 INFO Intance #0:tippingbucket: Initiated.
2012-11-22 23:47:26,772 INFO Intance #0:inputgenerator: Initiated
2012-11-22 23:47:26,772 INFO Intance #0:gotmessage: Initiated.
2012-11-22 23:47:26,773 INFO Intance #0:inputgenerator: Next outage is planned at Thu, 22 Nov 2012 23:47:32 +0000
2012-11-22 23:47:26,774 INFO Intance #0:gotmessage: Started.
2012-11-22 23:47:26,774 INFO Intance #0:tippingbucket: Started.
2012-11-22 23:47:26,775 INFO Intance #0:inputgenerator: Started
2012-11-22 23:47:26,776 INFO Wishbone: Metrics Intance #0:tippingbucket: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 0, 'in': 0}}}
2012-11-22 23:47:26,777 INFO Wishbone: Metrics Intance #0:inputgenerator: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 0, 'in': 0}, 'temp': {'out': 0, 'in': 1}}}
2012-11-22 23:47:26,777 INFO Wishbone: Metrics Intance #0:gotmessage: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 0, 'in': 0}}}
2012-11-22 23:47:30,706 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:30,708 INFO Intance #0:gotmessage: Got message with a size of 25014 characters.
2012-11-22 23:47:32,057 INFO Intance #0:inputgenerator: Outage of 19.8530838711 seconds started.
2012-11-22 23:47:36,779 INFO Wishbone: Metrics Intance #0:tippingbucket: {'functions': {'consume': {'total_time': 0.034440517425537109, 'min_time': 7.152557373046875e-06, 'max_time': 0.0016880035400390625}}, 'queues': {'outbox': {'out': 1, 'in': 1}, 'inbox': {'out': 684, 'in': 684}}}
2012-11-22 23:47:36,780 INFO Wishbone: Metrics Intance #0:inputgenerator: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 684, 'in': 684}, 'temp': {'out': 684, 'in': 1384}}}
2012-11-22 23:47:36,780 INFO Wishbone: Metrics Intance #0:gotmessage: {'functions': {'consume': {'total_time': 0.0011730194091796875, 'min_time': 0.0011730194091796875, 'max_time': 0.0011730194091796875}}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 1, 'in': 1}}}
2012-11-22 23:47:40,745 INFO Intance #0:tippingbucket: Age of buffer exceeded 10 seconds. Flushed.
2012-11-22 23:47:40,746 INFO Intance #0:gotmessage: Got message with a size of 9277 characters.
2012-11-22 23:47:46,782 INFO Wishbone: Metrics Intance #0:tippingbucket: {'functions': {'consume': {'total_time': 0.034440517425537109, 'min_time': 7.152557373046875e-06, 'max_time': 0.0016880035400390625}}, 'queues': {'outbox': {'out': 2, 'in': 2}, 'inbox': {'out': 684, 'in': 684}}}
2012-11-22 23:47:46,783 INFO Wishbone: Metrics Intance #0:inputgenerator: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 684, 'in': 684}, 'temp': {'out': 684, 'in': 2817}}}
2012-11-22 23:47:46,784 INFO Wishbone: Metrics Intance #0:gotmessage: {'functions': {'consume': {'total_time': 0.0019252300262451172, 'min_time': 0.00075221061706542969, 'max_time': 0.0011730194091796875}}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 2, 'in': 2}}}
2012-11-22 23:47:51,915 INFO Intance #0:inputgenerator: Outage finished.
2012-11-22 23:47:51,916 INFO Intance #0:inputgenerator: Next outage is planned at Thu, 22 Nov 2012 23:48:01 +0000
2012-11-22 23:47:51,988 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:52,005 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:52,005 INFO Intance #0:gotmessage: Got message with a size of 25009 characters.
2012-11-22 23:47:52,023 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:52,023 INFO Intance #0:gotmessage: Got message with a size of 25061 characters.
2012-11-22 23:47:52,041 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:52,041 INFO Intance #0:gotmessage: Got message with a size of 25054 characters.
2012-11-22 23:47:52,060 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:52,060 INFO Intance #0:gotmessage: Got message with a size of 25045 characters.
2012-11-22 23:47:52,073 INFO Intance #0:gotmessage: Got message with a size of 25057 characters.
2012-11-22 23:47:53,200 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:53,202 INFO Intance #0:gotmessage: Got message with a size of 25044 characters.
2012-11-22 23:47:56,584 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:47:56,586 INFO Intance #0:gotmessage: Got message with a size of 25035 characters.
2012-11-22 23:47:56,785 INFO Wishbone: Metrics Intance #0:tippingbucket: {'functions': {'consume': {'total_time': 0.10145926475524902, 'min_time': 6.9141387939453125e-06, 'max_time': 0.0016880035400390625}}, 'queues': {'outbox': {'out': 9, 'in': 9}, 'inbox': {'out': 4238, 'in': 4238}}}
2012-11-22 23:47:56,785 INFO Wishbone: Metrics Intance #0:inputgenerator: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 4238, 'in': 4238}, 'temp': {'out': 4238, 'in': 4238}}}
2012-11-22 23:47:56,786 INFO Wishbone: Metrics Intance #0:gotmessage: {'functions': {'consume': {'total_time': 0.0048639774322509766, 'min_time': 0.00021386146545410156, 'max_time': 0.0011730194091796875}}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 9, 'in': 9}}}
2012-11-22 23:48:00,144 INFO Intance #0:tippingbucket: Size of buffer exceeded. Flushed.
2012-11-22 23:48:00,146 INFO Intance #0:gotmessage: Got message with a size of 25048 characters.
2012-11-22 23:48:01,783 INFO Intance #0:inputgenerator: Outage of 11.6982267309 seconds started.
2012-11-22 23:48:06,787 INFO Wishbone: Metrics Intance #0:tippingbucket: {'functions': {'consume': {'total_time': 0.13358592987060547, 'min_time': 6.9141387939453125e-06, 'max_time': 0.0016880035400390625}}, 'queues': {'outbox': {'out': 10, 'in': 10}, 'inbox': {'out': 4923, 'in': 4923}}}
2012-11-22 23:48:06,787 INFO Wishbone: Metrics Intance #0:inputgenerator: {'functions': {}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 4923, 'in': 4923}, 'temp': {'out': 4923, 'in': 5622}}}
2012-11-22 23:48:06,788 INFO Wishbone: Metrics Intance #0:gotmessage: {'functions': {'consume': {'total_time': 0.0060219764709472656, 'min_time': 0.00021386146545410156, 'max_time': 0.0011730194091796875}}, 'queues': {'outbox': {'out': 0, 'in': 0}, 'inbox': {'out': 10, 'in': 10}}}
2012-11-22 23:48:10,247 INFO Intance #0:tippingbucket: Age of buffer exceeded 10 seconds. Flushed.
2012-11-22 23:48:10,248 INFO Intance #0:gotmessage: Got message with a size of 11396 characters.