Skip to content

Instantly share code, notes, and snippets.

@onsi

onsi/gist:6d4bb670fb1a60d4d48b

Created November 21, 2014 00:16
Show Gist options
  • Save onsi/6d4bb670fb1a60d4d48b to your computer and use it in GitHub Desktop.
Save onsi/6d4bb670fb1a60d4d48b to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
rep [INFO] 2m12.251141164s 15.1.19 rep-nats-server.nats-handler.bid-for-start.handling
==> executor/executor.stdout.log <==
executor [INFO] 2m12.251387692s 750 request.serving
{"method":"GET","request":"/resources/remaining"}
executor [INFO] 2m12.251430188s 750 request.done
{"method":"GET","request":"/resources/remaining"}
executor [INFO] 2m12.251631148s 752 request.serving
{"method":"GET","request":"/resources/total"}
executor [INFO] 2m12.251651116s 752 request.done
{"method":"GET","request":"/resources/total"}
executor [INFO] 2m12.251838764s 754 request.serving
{"method":"GET","request":"/containers?tag=process-guid%3A3ca7a76f-988b-4feb-66bd-36d64a383fbc"}
executor [INFO] 2m12.25225246s 754 request.done
{"method":"GET","request":"/containers?tag=process-guid%3A3ca7a76f-988b-4feb-66bd-36d64a383fbc"}
==> rep/rep.stdout.log <==
rep [INFO] 2m12.253309484s 15.1.20 rep-nats-server.nats-handler.re-bid-then-reserve.handling
==> executor/executor.stdout.log <==
executor [INFO] 2m12.2536195s 756 request.serving
{"method":"GET","request":"/resources/remaining"}
executor [INFO] 2m12.253648172s 756 request.done
{"method":"GET","request":"/resources/remaining"}
executor [INFO] 2m12.253887788s 758 request.serving
{"method":"GET","request":"/resources/total"}
executor [INFO] 2m12.253909804s 758 request.done
{"method":"GET","request":"/resources/total"}
executor [INFO] 2m12.254214188s 760 request.serving
{"method":"GET","request":"/containers?tag=process-guid%3A3ca7a76f-988b-4feb-66bd-36d64a383fbc"}
executor [INFO] 2m12.254775596s 760 request.done
{"method":"GET","request":"/containers?tag=process-guid%3A3ca7a76f-988b-4feb-66bd-36d64a383fbc"}
==> rep/rep.stdout.log <==
rep [INFO] 2m12.254914348s 14.13 auction-delegate.reservation.reserve
{"start-auction":{"desired_lrp":{"action":{"run":{"args":null,"env":[{"name":"PORT","value":"8080"},{"name":"ACTION_LEVEL","value":"COYOTE"},{"name":"OVERRIDE","value":"DAQUIRI"}],"path":"./grace","resource_limits":{}}},"annotation":"arbitrary-data","cpu_weight":100,"disk_mb":128,"domain":"vizzini-1","instances":1,"log_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc","log_source":"VIZ","memory_mb":128,"monitor":{"run":{"args":["-addr=:8080"],"env":null,"path":"/tmp/circus/spy","resource_limits":{}}},"ports":[8080],"process_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc","root_fs":"","routes":["3ca7a76f-988b-4feb-66bd-36d64a383fbc.10.244.0.34.xip.io"],"setup":{"serial":{"actions":[{"download":{"cache_key":"grace","from":"http://onsi-public.s3.amazonaws.com/grace.tar.gz","to":"."}},{"download":{"cache_key":"linux-circus","from":"http://file_server.service.dc1.consul:8080/v1/static/linux-circus/linux-circus.tgz","to":"/tmp/circus"}}]}},"stack":"lucid64","start_timeout":0},"index":0,"instance_guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","state":1,"updated_at":1.416528709966344e+18}}
==> executor/executor.stdout.log <==
executor [INFO] 2m12.255343404s 762 request.serving
{"method":"POST","request":"/containers"}
executor [INFO] 2m12.255891244s 762 request.done
{"method":"POST","request":"/containers"}
==> rep/rep.stdout.log <==
rep [INFO] 2m12.256963884s 15.1.21 rep-nats-server.nats-handler.run.handling
rep [INFO] 2m12.2571523s 14.14 auction-delegate.run.start
{"start-auction":{"desired_lrp":{"action":{"run":{"args":null,"env":[{"name":"PORT","value":"8080"},{"name":"ACTION_LEVEL","value":"COYOTE"},{"name":"OVERRIDE","value":"DAQUIRI"}],"path":"./grace","resource_limits":{}}},"annotation":"arbitrary-data","cpu_weight":100,"disk_mb":128,"domain":"vizzini-1","instances":1,"log_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc","log_source":"VIZ","memory_mb":128,"monitor":{"run":{"args":["-addr=:8080"],"env":null,"path":"/tmp/circus/spy","resource_limits":{}}},"ports":[8080],"process_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc","root_fs":"","routes":["3ca7a76f-988b-4feb-66bd-36d64a383fbc.10.244.0.34.xip.io"],"setup":{"serial":{"actions":[{"download":{"cache_key":"grace","from":"http://onsi-public.s3.amazonaws.com/grace.tar.gz","to":"."}},{"download":{"cache_key":"linux-circus","from":"http://file_server.service.dc1.consul:8080/v1/static/linux-circus/linux-circus.tgz","to":"/tmp/circus"}}]}},"stack":"lucid64","start_timeout":0},"index":0,"instance_guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","state":1,"updated_at":1.416528709966344e+18}}
==> executor/executor.stdout.log <==
executor [INFO] 2m12.258113068s 764 request.serving
{"method":"POST","request":"/containers/5d019b38-3ba2-4baa-7b7a-c94f597bdf10/run?%3Aguid=5d019b38-3ba2-4baa-7b7a-c94f597bdf10\u0026"}
executor [INFO] 2m12.258208556s 764 request.done
{"method":"POST","request":"/containers/5d019b38-3ba2-4baa-7b7a-c94f597bdf10/run?%3Aguid=5d019b38-3ba2-4baa-7b7a-c94f597bdf10\u0026"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m12.259037228s 1.34 pool.a3vn6l9id4r.creating
garden-linux [INFO] 2m12.425729836s 1.34 pool.a3vn6l9id4r.created
garden-linux [INFO] 2m12.544659244s 1.34.2 pool.a3vn6l9id4r.start.started
garden-linux [INFO] 2m12.544753196s 3.9648 garden-server.create.created
{"request":{"env":[{"Key":"CF_INSTANCE_GUID","Value":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"},{"Key":"CF_INSTANCE_INDEX","Value":"0"}],"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","properties":[{"Key":"executor:result","Value":"{\"failed\":false,\"failure_reason\":\"\"}"},{"Key":"executor:disk-mb","Value":"128"},{"Key":"executor:allocated-at","Value":"1416528709975489970"},{"Key":"executor:rootfs","Value":""},{"Key":"executor:log","Value":"{\"guid\":\"3ca7a76f-988b-4feb-66bd-36d64a383fbc\",\"source_name\":\"VIZ\",\"index\":0}"},{"Key":"executor:memory-mb","Value":"128"},{"Key":"tag:lifecycle","Value":"lrp"},{"Key":"executor:state","Value":"created"},{"Key":"executor:health","Value":"down"},{"Key":"executor:monitor","Value":"{\"run\":{\"path\":\"/tmp/circus/spy\",\"args\":[\"-addr=:8080\"],\"env\":null,\"resource_limits\":{}}}"},{"Key":"executor:env","Value":"[{\"name\":\"CF_INSTANCE_GUID\",\"value\":\"5d019b38-3ba2-4baa-7b7a-c94f597bdf10\"},{\"name\":\"CF_INSTANCE_INDEX\",\"value\":\"0\"}]"},{"Key":"executor:cpu-weight","Value":"100"},{"Key":"tag:process-index","Value":"0"},{"Key":"tag:domain","Value":"vizzini-1"},{"Key":"executor:start-timeout","Value":"0"},{"Key":"executor:action","Value":"{\"run\":{\"path\":\"./grace\",\"args\":null,\"env\":[{\"name\":\"PORT\",\"value\":\"8080\"},{\"name\":\"ACTION_LEVEL\",\"value\":\"COYOTE\"},{\"name\":\"OVERRIDE\",\"value\":\"DAQUIRI\"}],\"resource_limits\":{}}}"},{"Key":"tag:process-guid","Value":"3ca7a76f-988b-4feb-66bd-36d64a383fbc"},{"Key":"executor:owner","Value":"executor"},{"Key":"executor:setup","Value":"{\"serial\":{\"actions\":[{\"download\":{\"from\":\"http://onsi-public.s3.amazonaws.com/grace.tar.gz\",\"to\":\".\",\"cache_key\":\"grace\"}},{\"download\":{\"from\":\"http://file_server.service.dc1.consul:8080/v1/static/linux-circus/linux-circus.tgz\",\"to\":\"/tmp/circus\",\"cache_key\":\"linux-circus\"}}]}}"}]}}
garden-linux [INFO] 2m12.55277854s 3.9649 garden-server.net-in.port-mapped
{"container-port":8080,"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","host-port":61007}
garden-linux [INFO] 2m12.554422828s 3.9650 garden-server.limit-memory.limited
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","resulting-limits":{"LimitInBytes":1.34217728e+08}}
garden-linux [INFO] 2m12.554933036s 3.9651 garden-server.limit-disk.limited
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","resulting-limits":{"BlockHard":0,"BlockSoft":0,"ByteHard":0,"ByteSoft":0,"InodeHard":0,"InodeSoft":0}}
garden-linux [INFO] 2m12.555514412s 3.9652 garden-server.limit-cpu.limited
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","resulting-limits":{"LimitInShares":1024}}
garden-linux [INFO] 2m12.566401324s 3.9653 garden-server.info.got-info
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m12.567319084s 2.1 garden-store.DownloadAction.starting
{"cacheKey":"grace","handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","to":"."}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m12.591032364s 3.9655 garden-server.stream-in.streamed-in
{"destination":".","handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m12.591259436s 2.2 garden-store.DownloadAction.starting
{"cacheKey":"linux-circus","handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","to":"/tmp/circus"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m12.619514412s 3.9656 garden-server.stream-in.streamed-in
{"destination":"/tmp/circus","handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m12.619845932s 2.3 garden-store.RunAction.running
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
executor [INFO] 2m12.620080172s 2.3 garden-store.RunAction.creating-process
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m12.62569246s 3.9657 garden-server.run.spawned
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","id":1,"spec":{"Args":null,"Dir":"","Env":["PORT=8080","ACTION_LEVEL=COYOTE","OVERRIDE=DAQUIRI"],"Limits":{"As":null,"Core":null,"Cpu":null,"Data":null,"Fsize":null,"Locks":null,"Memlock":null,"Msgqueue":null,"Nice":null,"Nofile":null,"Nproc":null,"Rss":null,"Rtprio":null,"Sigpending":null,"Stack":null},"Path":"./grace","Privileged":false,"TTY":null}}
==> executor/executor.stdout.log <==
executor [INFO] 2m12.625885996s 2.3 garden-store.RunAction.successful-process-create
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process":1}
executor [INFO] 2m13.120777516s 2.4 garden-store.RunAction.running
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
executor [INFO] 2m13.120928556s 2.4 garden-store.RunAction.creating-process
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m13.129486892s 3.9659 garden-server.run.spawned
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","id":2,"spec":{"Args":["-addr=:8080"],"Dir":"","Env":[],"Limits":{"As":null,"Core":null,"Cpu":null,"Data":null,"Fsize":null,"Locks":null,"Memlock":null,"Msgqueue":null,"Nice":null,"Nofile":null,"Nproc":null,"Rss":null,"Rtprio":null,"Sigpending":null,"Stack":null},"Path":"/tmp/circus/spy","Privileged":false,"TTY":null}}
==> executor/executor.stdout.log <==
executor [INFO] 2m13.129763884s 2.4 garden-store.RunAction.successful-process-create
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process":2}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m13.133867564s 3.9659 garden-server.run.exited
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","id":2,"status":0}
==> executor/executor.stdout.log <==
executor [INFO] 2m13.13407646s 2.4 garden-store.RunAction.process-exit
{"exitStatus":0,"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process":2}
executor [INFO] 2m13.134112556s 2.5.1 garden-store.monitor.MonitorAction.transitioned-to-healthy
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m13.134417196s 3.9660 garden-server.set-property.set-property-complete
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","key":"executor:health","value":"up"}
==> rep/rep.stdout.log <==
rep [INFO] 2m13.135021356s 16 event-consumer.event-received
{"event-type":"container_health"}
rep [INFO] 2m13.135060012s 9.9 container-processor.process-work-queue.processing-container
{"container-guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","container-health":"down","container-lifecycle":"lrp","container-state":"created"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m21.625960492s 3.9679 garden-server.info.got-info
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m31.93465374s 766 request.serving
{"method":"GET","request":"/containers"}
==> rep/rep.stdout.log <==
rep [INFO] 2m31.934365996s reaper-entering-loop
rep [INFO] 2m31.934403116s reaper-getting-tasks-by-cell-id
{"cell-id":"cell_z1-0"}
rep [ERROR] 2m31.936012076s 12.1 task-scheduler.watching.failed
Error: unexpected end of JSON input
==> executor/executor.stdout.log <==
executor [INFO] 2m31.93591326s 768 request.serving
{"method":"GET","request":"/containers/5d019b38-3ba2-4baa-7b7a-c94f597bdf10?%3Aguid=5d019b38-3ba2-4baa-7b7a-c94f597bdf10\u0026"}
executor [INFO] 2m31.936314924s 770 request.serving
{"method":"GET","request":"/containers?tag=lifecycle%3Atask"}
executor [INFO] 2m31.937519404s 770 request.done
{"method":"GET","request":"/containers?tag=lifecycle%3Atask"}
==> rep/rep.stdout.log <==
rep [INFO] 2m31.937827116s reaper-exiting-loop
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m31.9486211s 3.9704 garden-server.info.got-info
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m31.949394732s 766 request.done
{"method":"GET","request":"/containers"}
==> rep/rep.stdout.log <==
rep [INFO] 2m31.951373612s 9.10 container-processor.process-work-queue.processing-container
{"container-guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","container-health":"up","container-lifecycle":"lrp","container-state":"created"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m31.953014828s 3.9705 garden-server.info.got-info
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m31.953525548s 768 request.done
{"method":"GET","request":"/containers/5d019b38-3ba2-4baa-7b7a-c94f597bdf10?%3Aguid=5d019b38-3ba2-4baa-7b7a-c94f597bdf10\u0026"}
==> rep/rep.stdout.log <==
rep [INFO] 2m31.988179756s 13 stop-lrp-listener.received-stop
{"instance":{"index":0,"instance_guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc"}}
rep [INFO] 2m31.988244524s 8.7 lrp-stopper.stop.received
{"stop-instance":{"index":0,"instance_guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc"}}
rep [INFO] 2m31.988672556s 8.7 lrp-stopper.stop.stopping
{"container":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","stop-instance":{"index":0,"instance_guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc"}}
==> executor/executor.stdout.log <==
executor [INFO] 2m31.989841964s 772 request.serving
{"method":"DELETE","request":"/containers/5d019b38-3ba2-4baa-7b7a-c94f597bdf10?%3Aguid=5d019b38-3ba2-4baa-7b7a-c94f597bdf10\u0026"}
executor [INFO] 2m31.989923628s 2.5.1 garden-store.monitor.MonitorAction.cancelling
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m32.0044803s 3.9657 garden-server.run.exited
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","id":1,"status":2}
==> executor/executor.stdout.log <==
executor [INFO] 2m32.00480926s 2.3 garden-store.RunAction.process-exit
{"exitStatus":2,"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process":1}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m32.013986092s 3.9707 garden-server.info.got-info
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
garden-linux [INFO] 2m33.008866348s 3.9706 garden-server.stop.stopped
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
garden-linux [INFO] 2m33.010031404s 3.9711 garden-server.set-property.set-property-complete
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","key":"executor:result","value":"{\"failed\":true,\"failure_reason\":\"steps cancelled\"}"}
garden-linux [INFO] 2m33.0105283s 3.9712 garden-server.set-property.set-property-complete
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","key":"executor:state","value":"completed"}
garden-linux [INFO] 2m33.023230508s 3.9713 garden-server.info.got-info
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
garden-linux [INFO] 2m33.024344876s 1.36 pool.destroy.destroying
{"id":"a3vn6l9id4r"}
==> rep/rep.stdout.log <==
rep [INFO] 2m33.024309548s 16 event-consumer.event-received
{"event-type":"container_complete"}
rep [INFO] 2m33.024355372s 9.11 container-processor.process-work-queue.processing-container
{"container-guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","container-health":"up","container-lifecycle":"lrp","container-state":"completed"}
rep [INFO] 2m33.025423916s 42 process-lrp.removed-actual-lrp
{"container-guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","container-health":"up","container-state":"completed"}
==> garden-linux/garden-linux.stdout.log <==
garden-linux [INFO] 2m33.200557612s 1.36 pool.destroy.destroyed
{"id":"a3vn6l9id4r"}
garden-linux [INFO] 2m33.200608556s 3.9714 garden-server.destroy.destroyed
{"handle":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10"}
==> executor/executor.stdout.log <==
executor [INFO] 2m33.2008963s 772 request.done
{"method":"DELETE","request":"/containers/5d019b38-3ba2-4baa-7b7a-c94f597bdf10?%3Aguid=5d019b38-3ba2-4baa-7b7a-c94f597bdf10\u0026"}
==> rep/rep.stdout.log <==
rep [ERROR] 2m33.20199198s 8.7 lrp-stopper.stop.failed-to-remove-actual-lrp
Error: the requested key could not be found
{"process-guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc","stop-instance":{"index":0,"instance_guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","process_guid":"3ca7a76f-988b-4feb-66bd-36d64a383fbc"},"stopInstance-guid":"5d019b38-3ba2-4baa-7b7a-c94f597bdf10","stopInstance-index":0}
^C
root@f84ba50d-555b-487a-8d9a-abc3c19be962:/var/vcap/sys/log#
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment