~/snap ❯❯❯ snapd -l 1 -t 0
INFO[2016-08-01T13:12:28-04:00] setting log level to: debug
INFO[2016-08-01T13:12:28-04:00] Starting snapd (version: v0.15.0-beta)
INFO[2016-08-01T13:12:28-04:00] setting GOMAXPROCS to: 1 core(s)
DEBU[2016-08-01T13:12:28-04:00] pevent controller created                     _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] metric catalog created                        _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] plugin manager created                        _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] signing manager created                       _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] runner created                                _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] started                                       _block=start _module=control-runner
INFO[2016-08-01T13:12:28-04:00] Setting work manager queue size               _block=New _module=scheduler value=25
INFO[2016-08-01T13:12:28-04:00] Setting work manager pool size                _block=New _module=scheduler value=4
DEBU[2016-08-01T13:12:28-04:00] metric manager linked                         _block=set-metric-manager _module=scheduler
INFO[2016-08-01T13:12:28-04:00] Configuring REST API with HTTPS set to: false  _module=_mgmt-rest
INFO[2016-08-01T13:12:28-04:00] REST API is enabled
INFO[2016-08-01T13:12:28-04:00] control started                               _block=start _module=control
INFO[2016-08-01T13:12:28-04:00] auto discover path is disabled                _block=start _module=control
INFO[2016-08-01T13:12:28-04:00] module started                                _module=snapd block=main snap-module=control
INFO[2016-08-01T13:12:28-04:00] scheduler started                             _block=start-scheduler _module=scheduler
INFO[2016-08-01T13:12:28-04:00] auto discover path is disabled                _block=start-scheduler _module=scheduler
INFO[2016-08-01T13:12:28-04:00] module started                                _module=snapd block=main snap-module=scheduler
INFO[2016-08-01T13:12:28-04:00] Starting REST API on :8181                    _module=_mgmt-rest
INFO[2016-08-01T13:12:28-04:00] REST started                                  _block=start _module=_mgmt-rest
INFO[2016-08-01T13:12:28-04:00] module started                                _module=snapd block=main snap-module=REST
INFO[2016-08-01T13:12:28-04:00] setting plugin trust level to: disabled
INFO[2016-08-01T13:12:28-04:00] snapd started                                 _module=snapd block=main
INFO[2016-08-01T13:12:45-04:00] API request                                   _module=_mgmt-rest index=1 method=POST url=/v1/plugins
DEBU[2016-08-01T13:12:46-04:00] wrote 10066966 to /tmp/jmolet/894139871/snap-plugin-collector-mock1
INFO[2016-08-01T13:12:47-04:00] Loading plugin: /tmp/jmolet/894139871/snap-plugin-collector-mock1  _module=_mgmt-rest
INFO[2016-08-01T13:12:47-04:00] plugin load called                            _block=load _module=control
INFO[2016-08-01T13:12:47-04:00] plugin load called                            _block=load-plugin _module=control-plugin-mgr path=snap-plugin-collector-mock1
DEBU[2016-08-01T13:12:47-04:00] Getting plugin config                         _block_=getPluginConfigDataNode _module=config config-cache-key=0:mock:1 config-cache-value=&{mutex:0xc8202ea740 table:map[]}
INFO[2016-08-01T13:12:47-04:00] API response                                  _module=_mgmt-rest index=1 method=POST status=Created status-code=201 url=/v1/plugins
INFO[2016-08-01T13:12:47-04:00] No previous pool found for loaded plugin      _block=subscribe-pool _module=control-runner event=Control.PluginLoaded plugin-name=mock plugin-type=collector plugin-version=1
INFO[2016-08-01T13:12:47-04:00] API request                                   _module=_mgmt-rest index=2 method=POST url=/v1/plugins
DEBU[2016-08-01T13:12:48-04:00] wrote 10057830 to /tmp/jmolet/719500722/snap-plugin-processor-passthru
INFO[2016-08-01T13:12:49-04:00] Loading plugin: /tmp/jmolet/719500722/snap-plugin-processor-passthru  _module=_mgmt-rest
INFO[2016-08-01T13:12:49-04:00] plugin load called                            _block=load _module=control
INFO[2016-08-01T13:12:49-04:00] plugin load called                            _block=load-plugin _module=control-plugin-mgr path=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
INFO[2016-08-01T13:12:49-04:00] API response                                  _module=_mgmt-rest index=2 method=POST status=Created status-code=201 url=/v1/plugins
INFO[2016-08-01T13:12:49-04:00] No previous pool found for loaded plugin      _block=subscribe-pool _module=control-runner event=Control.PluginLoaded plugin-name=passthru plugin-type=processor plugin-version=1
INFO[2016-08-01T13:12:49-04:00] API request                                   _module=_mgmt-rest index=3 method=POST url=/v1/plugins
DEBU[2016-08-01T13:12:51-04:00] wrote 10061942 to /tmp/jmolet/092505449/snap-plugin-publisher-mock-file
INFO[2016-08-01T13:12:51-04:00] Loading plugin: /tmp/jmolet/092505449/snap-plugin-publisher-mock-file  _module=_mgmt-rest
INFO[2016-08-01T13:12:51-04:00] plugin load called                            _block=load _module=control
INFO[2016-08-01T13:12:51-04:00] plugin load called                            _block=load-plugin _module=control-plugin-mgr path=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
INFO[2016-08-01T13:12:51-04:00] API response                                  _module=_mgmt-rest index=3 method=POST status=Created status-code=201 url=/v1/plugins
INFO[2016-08-01T13:12:51-04:00] No previous pool found for loaded plugin      _block=subscribe-pool _module=control-runner event=Control.PluginLoaded plugin-name=mock-file plugin-type=publisher plugin-version=3
INFO[2016-08-01T13:13:07-04:00] API request                                   _module=_mgmt-rest index=4 method=GET url=/v1/plugins
INFO[2016-08-01T13:13:07-04:00] API response                                  _module=_mgmt-rest index=4 method=GET status=OK status-code=200 url=/v1/plugins
INFO[2016-08-01T13:14:04-04:00] API request                                   _module=_mgmt-rest index=5 method=POST url=/v1/tasks
DEBU[2016-08-01T13:14:04-04:00] Setting stop-on-failure limit for task        _block=OptionStopOnFailure _module=core consecutive failure limit=10 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:04-04:00] subscription called on metric                 _block=validate-metric-subscription _module=control namespace=/intel/mock/*/baz version=0
INFO[2016-08-01T13:14:04-04:00] subscription called on metric                 _block=validate-metric-subscription _module=control namespace=/intel/mock/bar version=0
INFO[2016-08-01T13:14:04-04:00] subscription called on metric                 _block=validate-metric-subscription _module=control namespace=/intel/mock/foo version=0
DEBU[2016-08-01T13:14:04-04:00] Getting plugin config                         _block_=getPluginConfigDataNode _module=config config-cache-key=1:passthru:-1 config-cache-value=&{mutex:0xc820245808 table:map[]}
INFO[2016-08-01T13:14:04-04:00] validating dependencies for plugin passthru:-1  _block=validate-plugin-subscription _module=control plugin=passthru:-1
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
DEBU[2016-08-01T13:14:04-04:00] Getting plugin config                         _block_=getPluginConfigDataNode _module=config config-cache-key=2:mock-file:-1 config-cache-value=&{mutex:0xc820245bf8 table:map[]}
INFO[2016-08-01T13:14:04-04:00] validating dependencies for plugin mock-file:-1  _block=validate-plugin-subscription _module=control plugin=mock-file:-1
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] task created                                  _block=create-task _module=scheduler source=user task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-state=Stopped
INFO[2016-08-01T13:14:04-04:00] starting task on creation                     _block=create-task _module=scheduler source=user task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:04-04:00] available plugin started                      _block=start-plugin _module=control-runner available-plugin=collector:mock:v1:id1 available-plugin-type=collector
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] Nothing to do for this event                  _block=handle-events _module=control-runner event=Control.PluginSubscribed
DEBU[2016-08-01T13:14:05-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
INFO[2016-08-01T13:14:05-04:00] available plugin started                      _block=start-plugin _module=control-runner available-plugin=processor:passthru:v1:id1 available-plugin-type=processor
INFO[2016-08-01T13:14:05-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:05-04:00] Nothing to do for this event                  _block=handle-events _module=control-runner event=Control.PluginSubscribed
DEBU[2016-08-01T13:14:06-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
INFO[2016-08-01T13:14:06-04:00] available plugin started                      _block=start-plugin _module=control-runner available-plugin=publisher:mock-file:v3:id1 available-plugin-type=publisher
INFO[2016-08-01T13:14:06-04:00] task started                                  _block=start-task _module=scheduler source=user task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-state=Running
INFO[2016-08-01T13:14:06-04:00] API response                                  _module=_mgmt-rest index=5 method=POST status=Created status-code=201 url=/v1/tasks
DEBU[2016-08-01T13:14:06-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.TaskCreated
INFO[2016-08-01T13:14:06-04:00] Nothing to do for this event                  _block=handle-events _module=control-runner event=Control.PluginSubscribed
DEBU[2016-08-01T13:14:06-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:06-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.TaskStarted task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:07-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:07-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=0 misses=1 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:07-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=0 misses=1 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:07-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=0 misses=1 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:07-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:07-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:07-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:07-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:07-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:07-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[file:{Value:/tmp/snap_published_mock_file.log} debug:{Value:true}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:07-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:07-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:07-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:07-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:07-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:07-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=1 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:07-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:08-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:08-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=1 misses=1 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:08-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=1 misses=1 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:08-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=1 misses=1 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:08-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:08-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:08-04:00] plugin selected                               _module=control-routing block=select hitcount=1 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:08-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:08-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:08-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:08-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:08-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:08-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:08-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:08-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=2 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:08-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:09-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:09-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=1 misses=2 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:09-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=1 misses=2 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:09-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=1 misses=2 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:09-04:00] plugin selected                               _module=control-routing block=select hitcount=1 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:09-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:09-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:09-04:00] plugin selected                               _module=control-routing block=select hitcount=2 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:09-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:09-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:09-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:09-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:09-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:09-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:09-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:09-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=3 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:09-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:10-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:10-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=2 misses=2 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:10-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=2 misses=2 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:10-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=2 misses=2 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:10-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:10-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:10-04:00] plugin selected                               _module=control-routing block=select hitcount=3 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:10-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:10-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:10-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:10-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:10-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:10-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:10-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:10-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=4 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:10-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:11-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:11-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=2 misses=3 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:11-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=2 misses=3 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:11-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=2 misses=3 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:11-04:00] plugin selected                               _module=control-routing block=select hitcount=2 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:11-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:11-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:11-04:00] plugin selected                               _module=control-routing block=select hitcount=4 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:11-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:11-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:11-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:11-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:11-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:11-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:11-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:11-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=5 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:11-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:12-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:12-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=3 misses=3 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:12-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=3 misses=3 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:12-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=3 misses=3 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:12-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:12-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:12-04:00] plugin selected                               _module=control-routing block=select hitcount=5 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:12-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:12-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:12-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:12-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:12-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:12-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:12-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:12-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=6 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:12-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:13-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:13-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=3 misses=4 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:13-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=3 misses=4 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:13-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=3 misses=4 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:13-04:00] plugin selected                               _module=control-routing block=select hitcount=3 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:13-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:13-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:13-04:00] plugin selected                               _module=control-routing block=select hitcount=6 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:13-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:13-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:13-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:13-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:13-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:13-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:13-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:13-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=7 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:13-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:14-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:14-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=4 misses=4 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:14-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=4 misses=4 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:14-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=4 misses=4 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:14-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:14-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:14-04:00] plugin selected                               _module=control-routing block=select hitcount=7 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:14-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:14-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:14-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:14-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:14-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:14-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:14-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:14-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=8 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:14-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:15-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:15-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=4 misses=5 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:15-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=4 misses=5 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:15-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=4 misses=5 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:15-04:00] plugin selected                               _module=control-routing block=select hitcount=4 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:15-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:15-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:15-04:00] plugin selected                               _module=control-routing block=select hitcount=8 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:15-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:15-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:15-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:15-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:15-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:15-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:15-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:15-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=9 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:15-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:16-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:16-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=5 misses=5 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:16-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=5 misses=5 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:16-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=5 misses=5 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:16-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:16-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=9 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:16-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:16-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:16-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:16-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:16-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:16-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:16-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=10 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
ERRO[2016-08-01T13:14:16-04:00] Task disabled due to consecutive failures     _block=spin _module=scheduler-task consecutive failures=10 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] event received                                _block=handle-events _module=scheduler-events disabled-reason=Task disabled with error: Publish call error: EOF event-namespace=Scheduler.TaskDisabled task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] handling plugin unsubscription event          _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=mock plugin-type=collector plugin-version=1
DEBU[2016-08-01T13:14:16-04:00] killing an available plugin in pool  collector:mock:1  _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=5 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
INFO[2016-08-01T13:14:16-04:00] stopping available plugin                     _module=control-aplugin aplugin=collector:mock:v1:id1 block=stop
DEBU[2016-08-01T13:14:16-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] handling plugin unsubscription event          _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=passthru plugin-type=processor plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] killing an available plugin in pool  processor:passthru:-1  _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=10 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
INFO[2016-08-01T13:14:16-04:00] stopping available plugin                     _module=control-aplugin aplugin=processor:passthru:v1:id1 block=stop
DEBU[2016-08-01T13:14:16-04:00] handling plugin unsubscription event          _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=mock-file plugin-type=publisher plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] killing an available plugin in pool  publisher:mock-file:-1  _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
INFO[2016-08-01T13:14:16-04:00] stopping available plugin                     _module=control-aplugin aplugin=publisher:mock-file:v3:id1 block=stop
INFO[2016-08-01T13:14:16-04:00] hard killing available plugin                 _module=control-aplugin aplugin=collector:mock:v1:id1 block=kill
INFO[2016-08-01T13:14:16-04:00] hard killing available plugin                 _module=control-aplugin aplugin=processor:passthru:v1:id1 block=kill
INFO[2016-08-01T13:14:16-04:00] hard killing available plugin                 _module=control-aplugin aplugin=publisher:mock-file:v3:id1 block=kill
^CINFO[2016-08-01T13:14:28-04:00] shutting down modules                         _module=snapd block=main
INFO[2016-08-01T13:14:28-04:00] stopping module                               _module=snapd block=main snap-module=control
DEBU[2016-08-01T13:14:28-04:00] stopped                                       _block=start-plugin _module=control-runner
DEBU[2016-08-01T13:14:28-04:00] Removing plugin                               _module=control-plugin-mgr plugin-name=mock plugin-path=/tmp/jmolet/894139871/snap-plugin-collector-mock1 plugin-type=collector plugin-version=1
DEBU[2016-08-01T13:14:28-04:00] Removing plugin                               _module=control-plugin-mgr plugin-name=passthru plugin-path=/tmp/jmolet/719500722/snap-plugin-processor-passthru plugin-type=processor plugin-version=1
DEBU[2016-08-01T13:14:28-04:00] Removing plugin                               _module=control-plugin-mgr plugin-name=mock-file plugin-path=/tmp/jmolet/092505449/snap-plugin-publisher-mock-file plugin-type=publisher plugin-version=3
INFO[2016-08-01T13:14:28-04:00] control stopped                               _block=stop _module=control
INFO[2016-08-01T13:14:28-04:00] stopping module                               _module=snapd block=main snap-module=scheduler
INFO[2016-08-01T13:14:28-04:00] scheduler stopped                             _block=stop-scheduler _module=scheduler
INFO[2016-08-01T13:14:28-04:00] stopping module                               _module=snapd block=main snap-module=REST
INFO[2016-08-01T13:14:28-04:00] REST stopped                                  _block=stop _module=_mgmt-rest
INFO[2016-08-01T13:14:28-04:00] exiting on signal                             _module=snapd block=main signal=interrupt
~/snap ❯❯❯ exit

Script done on Mon 01 Aug 2016 01:14:31 PM EDT
~/snap ❯❯❯
~/snap ❯❯❯
~/snap ❯❯❯ vim capture.txt
~/snap ❯❯❯ cat capture.txt
Script started on Mon 01 Aug 2016 01:12:13 PM EDT
~/snap ❯❯❯ snapd -l 1 -t 0
INFO[2016-08-01T13:12:28-04:00] setting log level to: debug
INFO[2016-08-01T13:12:28-04:00] Starting snapd (version: v0.15.0-beta)
INFO[2016-08-01T13:12:28-04:00] setting GOMAXPROCS to: 1 core(s)
DEBU[2016-08-01T13:12:28-04:00] pevent controller created                     _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] metric catalog created                        _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] plugin manager created                        _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] signing manager created                       _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] runner created                                _block=new _module=control
DEBU[2016-08-01T13:12:28-04:00] started                                       _block=start _module=control-runner
INFO[2016-08-01T13:12:28-04:00] Setting work manager queue size               _block=New _module=scheduler value=25
INFO[2016-08-01T13:12:28-04:00] Setting work manager pool size                _block=New _module=scheduler value=4
DEBU[2016-08-01T13:12:28-04:00] metric manager linked                         _block=set-metric-manager _module=scheduler
INFO[2016-08-01T13:12:28-04:00] Configuring REST API with HTTPS set to: false  _module=_mgmt-rest
INFO[2016-08-01T13:12:28-04:00] REST API is enabled
INFO[2016-08-01T13:12:28-04:00] control started                               _block=start _module=control
INFO[2016-08-01T13:12:28-04:00] auto discover path is disabled                _block=start _module=control
INFO[2016-08-01T13:12:28-04:00] module started                                _module=snapd block=main snap-module=control
INFO[2016-08-01T13:12:28-04:00] scheduler started                             _block=start-scheduler _module=scheduler
INFO[2016-08-01T13:12:28-04:00] auto discover path is disabled                _block=start-scheduler _module=scheduler
INFO[2016-08-01T13:12:28-04:00] module started                                _module=snapd block=main snap-module=scheduler
INFO[2016-08-01T13:12:28-04:00] Starting REST API on :8181                    _module=_mgmt-rest
INFO[2016-08-01T13:12:28-04:00] REST started                                  _block=start _module=_mgmt-rest
INFO[2016-08-01T13:12:28-04:00] module started                                _module=snapd block=main snap-module=REST
INFO[2016-08-01T13:12:28-04:00] setting plugin trust level to: disabled
INFO[2016-08-01T13:12:28-04:00] snapd started                                 _module=snapd block=main
INFO[2016-08-01T13:12:45-04:00] API request                                   _module=_mgmt-rest index=1 method=POST url=/v1/plugins
DEBU[2016-08-01T13:12:46-04:00] wrote 10066966 to /tmp/jmolet/894139871/snap-plugin-collector-mock1
INFO[2016-08-01T13:12:47-04:00] Loading plugin: /tmp/jmolet/894139871/snap-plugin-collector-mock1  _module=_mgmt-rest
INFO[2016-08-01T13:12:47-04:00] plugin load called                            _block=load _module=control
INFO[2016-08-01T13:12:47-04:00] plugin load called                            _block=load-plugin _module=control-plugin-mgr path=snap-plugin-collector-mock1
DEBU[2016-08-01T13:12:47-04:00] Getting plugin config                         _block_=getPluginConfigDataNode _module=config config-cache-key=0:mock:1 config-cache-value=&{mutex:0xc8202ea740 table:map[]}
INFO[2016-08-01T13:12:47-04:00] API response                                  _module=_mgmt-rest index=1 method=POST status=Created status-code=201 url=/v1/plugins
INFO[2016-08-01T13:12:47-04:00] No previous pool found for loaded plugin      _block=subscribe-pool _module=control-runner event=Control.PluginLoaded plugin-name=mock plugin-type=collector plugin-version=1
INFO[2016-08-01T13:12:47-04:00] API request                                   _module=_mgmt-rest index=2 method=POST url=/v1/plugins
DEBU[2016-08-01T13:12:48-04:00] wrote 10057830 to /tmp/jmolet/719500722/snap-plugin-processor-passthru
INFO[2016-08-01T13:12:49-04:00] Loading plugin: /tmp/jmolet/719500722/snap-plugin-processor-passthru  _module=_mgmt-rest
INFO[2016-08-01T13:12:49-04:00] plugin load called                            _block=load _module=control
INFO[2016-08-01T13:12:49-04:00] plugin load called                            _block=load-plugin _module=control-plugin-mgr path=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:12:49-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
INFO[2016-08-01T13:12:49-04:00] API response                                  _module=_mgmt-rest index=2 method=POST status=Created status-code=201 url=/v1/plugins
INFO[2016-08-01T13:12:49-04:00] No previous pool found for loaded plugin      _block=subscribe-pool _module=control-runner event=Control.PluginLoaded plugin-name=passthru plugin-type=processor plugin-version=1
INFO[2016-08-01T13:12:49-04:00] API request                                   _module=_mgmt-rest index=3 method=POST url=/v1/plugins
DEBU[2016-08-01T13:12:51-04:00] wrote 10061942 to /tmp/jmolet/092505449/snap-plugin-publisher-mock-file
INFO[2016-08-01T13:12:51-04:00] Loading plugin: /tmp/jmolet/092505449/snap-plugin-publisher-mock-file  _module=_mgmt-rest
INFO[2016-08-01T13:12:51-04:00] plugin load called                            _block=load _module=control
INFO[2016-08-01T13:12:51-04:00] plugin load called                            _block=load-plugin _module=control-plugin-mgr path=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:12:51-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
INFO[2016-08-01T13:12:51-04:00] API response                                  _module=_mgmt-rest index=3 method=POST status=Created status-code=201 url=/v1/plugins
INFO[2016-08-01T13:12:51-04:00] No previous pool found for loaded plugin      _block=subscribe-pool _module=control-runner event=Control.PluginLoaded plugin-name=mock-file plugin-type=publisher plugin-version=3
INFO[2016-08-01T13:13:07-04:00] API request                                   _module=_mgmt-rest index=4 method=GET url=/v1/plugins
INFO[2016-08-01T13:13:07-04:00] API response                                  _module=_mgmt-rest index=4 method=GET status=OK status-code=200 url=/v1/plugins
INFO[2016-08-01T13:14:04-04:00] API request                                   _module=_mgmt-rest index=5 method=POST url=/v1/tasks
DEBU[2016-08-01T13:14:04-04:00] Setting stop-on-failure limit for task        _block=OptionStopOnFailure _module=core consecutive failure limit=10 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:04-04:00] subscription called on metric                 _block=validate-metric-subscription _module=control namespace=/intel/mock/*/baz version=0
INFO[2016-08-01T13:14:04-04:00] subscription called on metric                 _block=validate-metric-subscription _module=control namespace=/intel/mock/bar version=0
INFO[2016-08-01T13:14:04-04:00] subscription called on metric                 _block=validate-metric-subscription _module=control namespace=/intel/mock/foo version=0
DEBU[2016-08-01T13:14:04-04:00] Getting plugin config                         _block_=getPluginConfigDataNode _module=config config-cache-key=1:passthru:-1 config-cache-value=&{mutex:0xc820245808 table:map[]}
INFO[2016-08-01T13:14:04-04:00] validating dependencies for plugin passthru:-1  _block=validate-plugin-subscription _module=control plugin=passthru:-1
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
DEBU[2016-08-01T13:14:04-04:00] Getting plugin config                         _block_=getPluginConfigDataNode _module=config config-cache-key=2:mock-file:-1 config-cache-value=&{mutex:0xc820245bf8 table:map[]}
INFO[2016-08-01T13:14:04-04:00] validating dependencies for plugin mock-file:-1  _block=validate-plugin-subscription _module=control plugin=mock-file:-1
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] task created                                  _block=create-task _module=scheduler source=user task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-state=Stopped
INFO[2016-08-01T13:14:04-04:00] starting task on creation                     _block=create-task _module=scheduler source=user task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:04-04:00] available plugin started                      _block=start-plugin _module=control-runner available-plugin=collector:mock:v1:id1 available-plugin-type=collector
INFO[2016-08-01T13:14:04-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:04-04:00] Nothing to do for this event                  _block=handle-events _module=control-runner event=Control.PluginSubscribed
DEBU[2016-08-01T13:14:05-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:05-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
INFO[2016-08-01T13:14:05-04:00] available plugin started                      _block=start-plugin _module=control-runner available-plugin=processor:passthru:v1:id1 available-plugin-type=processor
INFO[2016-08-01T13:14:05-04:00] finding latest plugin                         _module=control-plugin-mgr
INFO[2016-08-01T13:14:05-04:00] Nothing to do for this event                  _block=handle-events _module=control-runner event=Control.PluginSubscribed
DEBU[2016-08-01T13:14:06-04:00] method Decode reply type not a pointer: interface {}  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Decrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method DecryptKey has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Encode has wrong number of ins: 2      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Encrypt has wrong number of ins: 2     _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method EncryptKey has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method KillChan has wrong number of ins: 1    _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method ListenAddress has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method ListenPort has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Logger has wrong number of ins: 1      _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method ResetHeartbeat has wrong number of ins: 1  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method SetEncrypter has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method SetListenAddress has wrong number of ins: 2  _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:06-04:00] method Token has wrong number of ins: 1       _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
INFO[2016-08-01T13:14:06-04:00] available plugin started                      _block=start-plugin _module=control-runner available-plugin=publisher:mock-file:v3:id1 available-plugin-type=publisher
INFO[2016-08-01T13:14:06-04:00] task started                                  _block=start-task _module=scheduler source=user task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-state=Running
INFO[2016-08-01T13:14:06-04:00] API response                                  _module=_mgmt-rest index=5 method=POST status=Created status-code=201 url=/v1/tasks
DEBU[2016-08-01T13:14:06-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.TaskCreated
INFO[2016-08-01T13:14:06-04:00] Nothing to do for this event                  _block=handle-events _module=control-runner event=Control.PluginSubscribed
DEBU[2016-08-01T13:14:06-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:06-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.TaskStarted task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:07-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:07-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=0 misses=1 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:07-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=0 misses=1 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:07-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=0 misses=1 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:07-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:07-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:07-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:07-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:07-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:07-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[file:{Value:/tmp/snap_published_mock_file.log} debug:{Value:true}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:07-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:07-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:07-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:07-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:07-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:07-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=1 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:07-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:07-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:08-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:08-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=1 misses=1 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:08-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=1 misses=1 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:08-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=1 misses=1 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:08-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:08-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:08-04:00] plugin selected                               _module=control-routing block=select hitcount=1 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:08-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:08-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:08-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:08-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:08-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:08-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:08-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:08-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=2 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:08-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:08-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:09-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:09-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=1 misses=2 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:09-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=1 misses=2 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:09-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=1 misses=2 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:09-04:00] plugin selected                               _module=control-routing block=select hitcount=1 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:09-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:09-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:09-04:00] plugin selected                               _module=control-routing block=select hitcount=2 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:09-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:09-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:09-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:09-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:09-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:09-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:09-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:09-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=3 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:09-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:09-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:10-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:10-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=2 misses=2 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:10-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=2 misses=2 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:10-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=2 misses=2 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:10-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:10-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:10-04:00] plugin selected                               _module=control-routing block=select hitcount=3 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:10-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:10-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:10-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:10-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:10-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:10-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:10-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:10-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=4 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:10-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:10-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:11-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:11-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=2 misses=3 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:11-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=2 misses=3 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:11-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=2 misses=3 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:11-04:00] plugin selected                               _module=control-routing block=select hitcount=2 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:11-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:11-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:11-04:00] plugin selected                               _module=control-routing block=select hitcount=4 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:11-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:11-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:11-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:11-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:11-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:11-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:11-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:11-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=5 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:11-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:11-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:12-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:12-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=3 misses=3 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:12-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=3 misses=3 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:12-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=3 misses=3 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:12-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:12-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:12-04:00] plugin selected                               _module=control-routing block=select hitcount=5 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:12-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:12-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:12-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:12-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:12-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:12-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:12-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:12-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=6 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:12-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:12-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:13-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:13-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=3 misses=4 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:13-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=3 misses=4 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:13-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=3 misses=4 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:13-04:00] plugin selected                               _module=control-routing block=select hitcount=3 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:13-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:13-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:13-04:00] plugin selected                               _module=control-routing block=select hitcount=6 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:13-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:13-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:13-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:13-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:13-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:13-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:13-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:13-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=7 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:13-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:13-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:14-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:14-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=4 misses=4 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:14-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=4 misses=4 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:14-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=4 misses=4 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:14-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:14-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:14-04:00] plugin selected                               _module=control-routing block=select hitcount=7 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:14-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:14-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:14-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:14-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:14-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:14-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:14-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:14-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=8 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:14-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:14-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:15-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:15-04:00] cache miss [/intel/mock/*/baz:0]              _module=routing-cache hits=4 misses=5 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:15-04:00] cache miss [/intel/mock/bar:0]                _module=routing-cache hits=4 misses=5 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:15-04:00] cache miss [/intel/mock/foo:0]                _module=routing-cache hits=4 misses=5 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:15-04:00] plugin selected                               _module=control-routing block=select hitcount=4 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:15-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:15-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:15-04:00] plugin selected                               _module=control-routing block=select hitcount=8 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:15-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:15-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:15-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:15-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:15-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:15-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:15-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:15-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=9 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:15-04:00] task spin loop                                _module=scheduler-task
DEBU[2016-08-01T13:14:15-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
INFO[2016-08-01T13:14:16-04:00] Starting workflow for task (52523658-c74d-409c-b3b0-ad974e1f29ca\Task-52523658-c74d-409c-b3b0-ad974e1f29ca)  _block=workflow-start _module=scheduler-workflow task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] starting collector job                        _module=scheduler-job block=run job-type=collector metric-count=3
DEBU[2016-08-01T13:14:16-04:00] cache hit [/intel/mock/*/baz:0]               _module=routing-cache hits=5 misses=5 namespace=/intel/mock/*/baz:0
DEBU[2016-08-01T13:14:16-04:00] cache hit [/intel/mock/bar:0]                 _module=routing-cache hits=5 misses=5 namespace=/intel/mock/bar:0
DEBU[2016-08-01T13:14:16-04:00] cache hit [/intel/mock/foo:0]                 _module=routing-cache hits=5 misses=5 namespace=/intel/mock/foo:0
DEBU[2016-08-01T13:14:16-04:00] collector run completed                       _module=scheduler-job block=run job-type=collector metric-count=12
DEBU[2016-08-01T13:14:16-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Submitting process job                        _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] starting processor job                        _module=scheduler-job block=run content-type=snap.gob job-type=processor plugin-config=map[debug:{Value:true}] plugin-name=passthru plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=9 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:16-04:00] level=debug msg="processing started"          _module=plugin-exec io=stderr plugin=snap-plugin-processor-passthru
DEBU[2016-08-01T13:14:16-04:00] Process job completed                         _block=submit-process-job _module=scheduler-workflow parent-node-type=collector process-name=passthru process-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Batch submission of process and publish nodes  _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Submitting publish job                        _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] starting publisher job                        _module=scheduler-job block=run content-type=snap.gob job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
DEBU[2016-08-01T13:14:16-04:00] level=debug msg="publishing started"          _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
DEBU[2016-08-01T13:14:16-04:00] level=error msg="error decoding" content=[13 255 147 2 1 2 255 148 0 1 255 136 0 0 255 155 255 135 3 1 1 10 77 101 116 114 105 99 84 121 112 101 1 255 136 0 1 9 1 10 78 97 109 101 115 112 97 99 101 95 1 255 140 0 1 19 76 97 115 116 65 100 118 101 114 116 105 115 101 100 84 105 109 101 95 1 255 142 0 1 8 86 101 114 115 105 111 110 95 1 4 0 1 7 67 111 110 102 105 103 95 1 255 144 0 1 5 68 97 116 97 95 1 16 0 1 5 84 97 103 115 95 1 255 146 0 1 5 85 110 105 116 95 1 12 0 1 12 68 101 115 99 114 105 112 116 105 111 110 95 1 12 0 1 10 84 105 109 101 115 116 97 109 112 95 1 255 142 0 0 0 38 255 139 2 1 1 23 91 93 99 111 114 101 46 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 140 0 1 255 138 0 0 65 255 137 3 1 1 16 78 97 109 101 115 112 97 99 101 69 108 101 109 101 110 116 1 255 138 0 1 3 1 5 86 97 108 117 101 1 12 0 1 11 68 101 115 99 114 105 112 116 105 111 110 1 12 0 1 4 78 97 109 101 1 12 0 0 0 16 255 141 5 1 1 4 84 105 109 101 1 255 142 0 0 0 10 255 143 5 1 2 255 150 0 0 0 33 255 145 4 1 1 17 109 97 112 91 115 116 114 105 110 103 93 115 116 114 105 110 103 1 255 146 0 1 12 1 12 0 0] error=EOF   _module=plugin-exec io=stderr plugin=snap-plugin-publisher-mock-file
ERRO[2016-08-01T13:14:16-04:00] error with publisher job                      _module=scheduler-job block=run content-type=snap.gob error=Publish call error: EOF job-type=publisher plugin-config=map[debug:{Value:true} file:{Value:/tmp/snap_published_mock_file.log}] plugin-name=mock-file plugin-version=-1
WARN[2016-08-01T13:14:16-04:00] Publish job failed                            _block=submit-publish-job _module=scheduler-workflow parent-node-type=processor publish-name=mock-file publish-version=-1 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=0 count-publish-nodes=1 parent-node-type=processor task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] Batch submission complete                     _block=work-jobs _module=scheduler-workflow count-process-nodes=1 count-publish-nodes=0 parent-node-type=collector task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
WARN[2016-08-01T13:14:16-04:00] Task failed                                   _block=spin _module=scheduler-task consecutive failure limit=10 consecutive failures=10 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
ERRO[2016-08-01T13:14:16-04:00] Task disabled due to consecutive failures     _block=spin _module=scheduler-task consecutive failures=10 error=Publish call error: EOF task-id=52523658-c74d-409c-b3b0-ad974e1f29ca task-name=Task-52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] event received                                _block=handle-events _module=scheduler-events disabled-reason=Task disabled with error: Publish call error: EOF event-namespace=Scheduler.TaskDisabled task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] handling plugin unsubscription event          _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=mock plugin-type=collector plugin-version=1
DEBU[2016-08-01T13:14:16-04:00] killing an available plugin in pool  collector:mock:1  _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=5 index=collector:mock:v1:id1 pool size=1 strategy=least-recently-used
INFO[2016-08-01T13:14:16-04:00] stopping available plugin                     _module=control-aplugin aplugin=collector:mock:v1:id1 block=stop
DEBU[2016-08-01T13:14:16-04:00] event received                                _block=handle-events _module=scheduler-events event-namespace=Scheduler.MetricsCollected metric-count=12 task-id=52523658-c74d-409c-b3b0-ad974e1f29ca
DEBU[2016-08-01T13:14:16-04:00] handling plugin unsubscription event          _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=passthru plugin-type=processor plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] killing an available plugin in pool  processor:passthru:-1  _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=10 index=processor:passthru:v1:id1 pool size=1 strategy=least-recently-used
INFO[2016-08-01T13:14:16-04:00] stopping available plugin                     _module=control-aplugin aplugin=processor:passthru:v1:id1 block=stop
DEBU[2016-08-01T13:14:16-04:00] handling plugin unsubscription event          _block=subscribe-pool _module=control-runner event=Control.PluginUnsubscribed plugin-name=mock-file plugin-type=publisher plugin-version=-1
DEBU[2016-08-01T13:14:16-04:00] killing an available plugin in pool  publisher:mock-file:-1  _block=handle-unsubscription _module=control-runner pool-count=1 pool-subscription-count=0
DEBU[2016-08-01T13:14:16-04:00] plugin selected                               _module=control-routing block=select hitcount=0 index=publisher:mock-file:v3:id1 pool size=1 strategy=least-recently-used
INFO[2016-08-01T13:14:16-04:00] stopping available plugin                     _module=control-aplugin aplugin=publisher:mock-file:v3:id1 block=stop
INFO[2016-08-01T13:14:16-04:00] hard killing available plugin                 _module=control-aplugin aplugin=collector:mock:v1:id1 block=kill
INFO[2016-08-01T13:14:16-04:00] hard killing available plugin                 _module=control-aplugin aplugin=processor:passthru:v1:id1 block=kill
INFO[2016-08-01T13:14:16-04:00] hard killing available plugin                 _module=control-aplugin aplugin=publisher:mock-file:v3:id1 block=kill
^CINFO[2016-08-01T13:14:28-04:00] shutting down modules                         _module=snapd block=main
INFO[2016-08-01T13:14:28-04:00] stopping module                               _module=snapd block=main snap-module=control
DEBU[2016-08-01T13:14:28-04:00] stopped                                       _block=start-plugin _module=control-runner
DEBU[2016-08-01T13:14:28-04:00] Removing plugin                               _module=control-plugin-mgr plugin-name=mock plugin-path=/tmp/jmolet/894139871/snap-plugin-collector-mock1 plugin-type=collector plugin-version=1
DEBU[2016-08-01T13:14:28-04:00] Removing plugin                               _module=control-plugin-mgr plugin-name=passthru plugin-path=/tmp/jmolet/719500722/snap-plugin-processor-passthru plugin-type=processor plugin-version=1
DEBU[2016-08-01T13:14:28-04:00] Removing plugin                               _module=control-plugin-mgr plugin-name=mock-file plugin-path=/tmp/jmolet/092505449/snap-plugin-publisher-mock-file plugin-type=publisher plugin-version=3
INFO[2016-08-01T13:14:28-04:00] control stopped                               _block=stop _module=control
INFO[2016-08-01T13:14:28-04:00] stopping module                               _module=snapd block=main snap-module=scheduler
INFO[2016-08-01T13:14:28-04:00] scheduler stopped                             _block=stop-scheduler _module=scheduler
INFO[2016-08-01T13:14:28-04:00] stopping module                               _module=snapd block=main snap-module=REST
INFO[2016-08-01T13:14:28-04:00] REST stopped                                  _block=stop _module=_mgmt-rest
INFO[2016-08-01T13:14:28-04:00] exiting on signal                             _module=snapd block=main signal=interrupt