Skip to content

Instantly share code, notes, and snippets.

@iwankgb

iwankgb/snap.log Secret

Created January 20, 2017 12:46
Show Gist options
  • Save iwankgb/03ca6ac4d18cd1247fd22905645b3dbb to your computer and use it in GitHub Desktop.
Save iwankgb/03ca6ac4d18cd1247fd22905645b3dbb to your computer and use it in GitHub Desktop.
time="2017-01-20T13:19:28+01:00" level=debug msg="Process job completed" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=859 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[retention:{default} database:{serenity} scheme:{http} precision:{ns} password:{admin} port:{8086} isMultiFields:{false} skip-verify:{false} user:{admin} host:{172.16.0.2}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=859 index="publisher:influxdb:v19:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 16 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission complete" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission complete" _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=2 parent-node-type=collector task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="task spin loop" _module=scheduler-task
time="2017-01-20T13:19:28+01:00" level=debug msg="event received" _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=6 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f
time="2017-01-20T13:19:28+01:00" level=debug msg="Starting workflow" _block=workflow-start _module=scheduler-workflow task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting collector job" _module=scheduler-job block=run job-type=collector metric-count=6
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/slo:1]" _module=routing-cache hits=211 misses=221 namespace="/intel/serenity2/lc/slo:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/slo/peakload:1]" _module=routing-cache hits=211 misses=221 namespace="/intel/serenity2/lc/slo/peakload:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/sli/targetloadmetric:1]" _module=routing-cache hits=211 misses=221 namespace="/intel/serenity2/lc/sli/targetloadmetric:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/sli/targetmetric:1]" _module=routing-cache hits=211 misses=221 namespace="/intel/serenity2/lc/sli/targetmetric:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/memcached/*/load:1]" _module=routing-cache hits=210 misses=222 namespace="/intel/serenity2/memcached/*/load:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/memcached/*/tail_latency:1]" _module=routing-cache hits=125 misses=307 namespace="/intel/serenity2/memcached/*/tail_latency:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="collector run completed" _module=scheduler-job block=run job-type=collector metric-count=6
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=2 parent-node-type=collector task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=860 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting process job" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting processor job" _module=scheduler-job block=run job-type=processor plugin-config=map[best_effort_growth_slo_threshold:{0.5} debug:{false} docker_endpoint:{tcp://172.16.0.8:8888} kubernetes_address:{http://172.16.0.2:8080} load_eviction_threshold:{0.8} reserved_high_priority_threads:{0-3,8-11} shrink_effort_growth_slo_threshold:{0.6} slo_eviction_threshold:{0.8}] plugin-name=serenity-qos plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=430 index="processor:serenity-qos:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[password:{admin} port:{8086} isMultiFields:{false} scheme:{http} retention:{default} user:{admin} database:{serenity} host:{172.16.0.2} skip-verify:{false} precision:{ns}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=860 index="publisher:influxdb:v19:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 6 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Process job completed" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=861 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[isMultiFields:{false} skip-verify:{false} user:{admin} host:{172.16.0.2} retention:{default} database:{serenity} scheme:{http} precision:{ns} password:{admin} port:{8086}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=861 index="publisher:influxdb:v19:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 16 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission complete" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission complete" _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=2 parent-node-type=collector task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="task spin loop" _module=scheduler-task
time="2017-01-20T13:19:28+01:00" level=debug msg="event received" _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=6 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f
time="2017-01-20T13:19:28+01:00" level=debug msg="Starting workflow" _block=workflow-start _module=scheduler-workflow task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting collector job" _module=scheduler-job block=run job-type=collector metric-count=6
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/lc/slo:1]" _module=routing-cache hits=211 misses=222 namespace="/intel/serenity2/lc/slo:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/lc/slo/peakload:1]" _module=routing-cache hits=211 misses=222 namespace="/intel/serenity2/lc/slo/peakload:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/lc/sli/targetloadmetric:1]" _module=routing-cache hits=211 misses=222 namespace="/intel/serenity2/lc/sli/targetloadmetric:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/lc/sli/targetmetric:1]" _module=routing-cache hits=211 misses=222 namespace="/intel/serenity2/lc/sli/targetmetric:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=221 index="collector:serenity-slo:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/memcached/*/load:1]" _module=routing-cache hits=210 misses=223 namespace="/intel/serenity2/memcached/*/load:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/memcached/*/tail_latency:1]" _module=routing-cache hits=125 misses=308 namespace="/intel/serenity2/memcached/*/tail_latency:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=368 index="collector:serenity-memcached-sli:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="CollectMetrics called" _module=plugin-exec io=stderr plugin=serenity-slo
time="2017-01-20T13:19:28+01:00" level=debug msg="CollectMetrics called" _module=plugin-exec io=stderr plugin=serenity-memcached-sli
time="2017-01-20T13:19:28+01:00" level=debug msg="collector run completed" _module=scheduler-job block=run job-type=collector metric-count=5
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=2 parent-node-type=collector task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=862 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting process job" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting processor job" _module=scheduler-job block=run job-type=processor plugin-config=map[reserved_high_priority_threads:{0-3,8-11} shrink_effort_growth_slo_threshold:{0.6} slo_eviction_threshold:{0.8} best_effort_growth_slo_threshold:{0.5} debug:{false} docker_endpoint:{tcp://172.16.0.8:8888} kubernetes_address:{http://172.16.0.2:8080} load_eviction_threshold:{0.8}] plugin-name=serenity-qos plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=431 index="processor:serenity-qos:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[isMultiFields:{false} password:{admin} port:{8086} database:{serenity} host:{172.16.0.2} skip-verify:{false} precision:{ns} scheme:{http} retention:{default} user:{admin}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=862 index="publisher:influxdb:v19:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 5 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="level=error msg=\"Cannot find SLI metric: cannot find metric \\\"/intel/serenity2/memcached/hp/tail_latency\\\" in metrics\" " _module=plugin-exec io=stderr plugin=serenity-qos
time="2017-01-20T13:19:28+01:00" level=debug msg="level=error msg=\"Process: error while preparing metrics for QoS Protection: cannot find metric \\\"/intel/serenity2/memcached/hp/tail_latency\\\" in metrics\" " _module=plugin-exec io=stderr plugin=serenity-qos
time="2017-01-20T13:19:28+01:00" level=debug msg="Process job completed" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=863 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[user:{admin} host:{172.16.0.2} isMultiFields:{false} skip-verify:{false} precision:{ns} password:{admin} port:{8086} retention:{default} database:{serenity} scheme:{http}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=862 index="publisher:influxdb:v19:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 1 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission complete" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission complete" _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=2 parent-node-type=collector task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="task spin loop" _module=scheduler-task
time="2017-01-20T13:19:28+01:00" level=debug msg="event received" _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=5 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f
time="2017-01-20T13:19:28+01:00" level=debug msg="Starting workflow" _block=workflow-start _module=scheduler-workflow task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting collector job" _module=scheduler-job block=run job-type=collector metric-count=6
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/slo:1]" _module=routing-cache hits=212 misses=222 namespace="/intel/serenity2/lc/slo:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/slo/peakload:1]" _module=routing-cache hits=212 misses=222 namespace="/intel/serenity2/lc/slo/peakload:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/sli/targetloadmetric:1]" _module=routing-cache hits=212 misses=222 namespace="/intel/serenity2/lc/sli/targetloadmetric:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/lc/sli/targetmetric:1]" _module=routing-cache hits=212 misses=222 namespace="/intel/serenity2/lc/sli/targetmetric:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache hit [/intel/serenity2/memcached/*/load:1]" _module=routing-cache hits=211 misses=223 namespace="/intel/serenity2/memcached/*/load:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="cache miss [/intel/serenity2/memcached/*/tail_latency:1]" _module=routing-cache hits=125 misses=309 namespace="/intel/serenity2/memcached/*/tail_latency:1"
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=369 index="collector:serenity-memcached-sli:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="CollectMetrics called" _module=plugin-exec io=stderr plugin=serenity-memcached-sli
time="2017-01-20T13:19:28+01:00" level=debug msg="collector run completed" _module=scheduler-job block=run job-type=collector metric-count=6
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=2 parent-node-type=collector task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=864 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting process job" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting processor job" _module=scheduler-job block=run job-type=processor plugin-config=map[kubernetes_address:{http://172.16.0.2:8080} load_eviction_threshold:{0.8} reserved_high_priority_threads:{0-3,8-11} shrink_effort_growth_slo_threshold:{0.6} slo_eviction_threshold:{0.8} best_effort_growth_slo_threshold:{0.5} debug:{false} docker_endpoint:{tcp://172.16.0.8:8888}] plugin-name=serenity-qos plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=432 index="processor:serenity-qos:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[scheme:{http} retention:{default} user:{admin} database:{serenity} host:{172.16.0.2} skip-verify:{false} precision:{ns} password:{admin} port:{8086} isMultiFields:{false}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=864 index="publisher:influxdb:v19:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 6 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="API request" _module="_mgmt-rest" index=30 method=PUT url="/v1/tasks/dc2810f5-76e2-4c91-a4b4-08918d55df8f/stop"
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin unsubscription" _block=subscriptionGroup.unsubscribePlugins _module=control name=serenity-slo type=collector version=1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin unsubscription" _block=subscriptionGroup.unsubscribePlugins _module=control name=serenity-memcached-sli type=collector version=1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin unsubscription" _block=subscriptionGroup.unsubscribePlugins _module=control name=serenity-qos type=processor version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="handling plugin unsubscription event" _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=serenity-memcached-sli plugin-type=collector plugin-version=1
time="2017-01-20T13:19:28+01:00" level=debug msg="killing an available plugin in pool collector:serenity-memcached-sli:1" _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=370 index="collector:serenity-memcached-sli:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=info msg="stopping available plugin" _module=control-aplugin block=stop plugin_name=collector:serenity-memcached-sli:v1:id5
time="2017-01-20T13:19:28+01:00" level=info msg="Nothing to do for this event" _block=handle-events _module=control-runner event=Control.PluginUnsubscribed
time="2017-01-20T13:19:28+01:00" level=debug msg="handling plugin unsubscription event" _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=serenity-slo plugin-type=collector plugin-version=1
time="2017-01-20T13:19:28+01:00" level=debug msg="killing an available plugin in pool collector:serenity-slo:1" _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=222 index="collector:serenity-slo:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=info msg="stopping available plugin" _module=control-aplugin block=stop plugin_name=collector:serenity-slo:v1:id5
time="2017-01-20T13:19:28+01:00" level=info msg="Nothing to do for this event" _block=handle-events _module=control-runner event=Control.PluginUnsubscribed
time="2017-01-20T13:19:28+01:00" level=debug msg="Kill called by agent, reason: unsubscription event" _module=plugin-exec io=stderr plugin=serenity-memcached-sli
time="2017-01-20T13:19:28+01:00" level=debug _module=plugin-exec io=stderr plugin=serenity-memcached-sli
time="2017-01-20T13:19:28+01:00" level=debug msg="Kill called by agent, reason: unsubscription event" _module=plugin-exec io=stderr plugin=serenity-slo
time="2017-01-20T13:19:28+01:00" level=debug _module=plugin-exec io=stderr plugin=serenity-slo
time="2017-01-20T13:19:28+01:00" level=info msg="hard killing available plugin" _module=control-aplugin block=kill plugin_name=collector:serenity-memcached-sli:v1:id5
time="2017-01-20T13:19:28+01:00" level=info msg="hard killing available plugin" _module=control-aplugin block=kill plugin_name=collector:serenity-slo:v1:id5
time="2017-01-20T13:19:28+01:00" level=debug msg="Process job completed" _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=serenity-qos process-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Batch submission of process and publish nodes" _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=2 parent-node-type=processor task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[file:{/tmp/serenity-qos-metrics.log}] plugin-name=file plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=865 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin unsubscription" _block=subscriptionGroup.unsubscribePlugins _module=control name=influxdb type=publisher version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="handling plugin unsubscription event" _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=serenity-qos plugin-type=processor plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="killing an available plugin in pool processor:serenity-qos:-1" _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=433 index="processor:serenity-qos:v1:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=info msg="stopping available plugin" _module=control-aplugin block=stop plugin_name=processor:serenity-qos:v1:id5
time="2017-01-20T13:19:28+01:00" level=debug msg="Submitting publish job" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="starting publisher job" _module=scheduler-job block=run job-type=publisher plugin-config=map[user:{admin} host:{172.16.0.2} isMultiFields:{false} skip-verify:{false} password:{admin} port:{8086} retention:{default} database:{serenity} scheme:{http} precision:{ns}] plugin-name=influxdb plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=info msg="Nothing to do for this event" _block=handle-events _module=control-runner event=Control.PluginUnsubscribed
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"Publishing started\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Kill called by agent, reason: unsubscription event" _module=plugin-exec io=stderr plugin=serenity-qos
time="2017-01-20T13:19:28+01:00" level=debug _module=plugin-exec io=stderr plugin=serenity-qos
time="2017-01-20T13:19:28+01:00" level=debug msg="time=\"2017-01-20T13:19:28+01:00\" level=info msg=\"publishing 16 metrics to map[file:{/tmp/serenity-qos-metrics.log}]\" " _module=plugin-exec io=stdout plugin=file
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=file publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=info msg="hard killing available plugin" _module=control-aplugin block=kill plugin_name=processor:serenity-qos:v1:id5
time="2017-01-20T13:19:28+01:00" level=debug msg="Publish job completed" _block=submit-publish-job _module=scheduler-workflow parent-node-type=collector publish-name=influxdb publish-version=-1 task-id=dc2810f5-76e2-4c91-a4b4-08918d55df8f task-name=serenity
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin unsubscription" _block=subscriptionGroup.unsubscribePlugins _module=control name=file type=publisher version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin unsubscription" _block=subscriptionGroup.unsubscribePlugins _module=control name=influxdb type=publisher version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="handling plugin unsubscription event" _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=file plugin-type=publisher plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=debug msg="killing an available plugin in pool publisher:file:-1" _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
time="2017-01-20T13:19:28+01:00" level=debug msg="plugin selected" _module=control-routing block=select hitcount=866 index="publisher:file:v2:id5" pool size=1 strategy=least-recently-used
time="2017-01-20T13:19:28+01:00" level=info msg="stopping available plugin" _module=control-aplugin block=stop plugin_name=publisher:file:v2:id5
time="2017-01-20T13:19:28+01:00" level=info msg="Nothing to do for this event" _block=handle-events _module=control-runner event=Control.PluginUnsubscribed
time="2017-01-20T13:19:28+01:00" level=debug msg="handling plugin unsubscription event" _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=influxdb plugin-type=publisher plugin-version=-1
time="2017-01-20T13:19:28+01:00" level=info msg="Nothing to do for this event" _block=handle-events _module=control-runner event=Control.PluginUnsubscribed
time="2017-01-20T13:19:28+01:00" level=info msg="hard killing available plugin" _module=control-aplugin block=kill plugin_name=publisher:file:v2:id5
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment