Skip to content

Instantly share code, notes, and snippets.

@trentm

trentm/1. hello.log

Created February 7, 2012 20:33
Show Gist options
  • Save trentm/1761772 to your computer and use it in GitHub Desktop.
Save trentm/1761772 to your computer and use it in GitHub Desktop.
Download ZIP
TRACE-level log output from https://github.com/trentm/hello-json-logging run
Raw
1. hello.log
{"name":"helloapi","hostname":"banana.local","pid":40168,"level":30,"msg":"Hello API listening at <http://0.0.0.0:8080>","time":"2012-03-28T17:29:48.223Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"level":30,"msg":"Hello API listening at <http://0.0.0.0:8080>","time":"2012-03-28T17:32:39.701Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"level":10,"msg":"New Request:\n\nGET /hello?name=paul HTTP/1.1\nuser-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3\nhost: 0.0.0.0:8080\naccept: */*\n","time":"2012-03-28T17:32:57.881Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"level":30,"req":{"method":"GET","url":"/hello?name=paul","headers":{"user-agent":"curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3","host":"0.0.0.0:8080","accept":"*/*"},"remoteAddress":"127.0.0.1","remotePort":59828},"msg":"start","time":"2012-03-28T17:32:57.881Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"req":{"method":"GET","url":"/hello?name=paul","headers":{"user-agent":"curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3","host":"0.0.0.0:8080","accept":"*/*"},"remoteAddress":"127.0.0.1","remotePort":59828},"msg":"starting request chain","time":"2012-03-28T17:32:57.882Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"msg":"running parseQueryString","time":"2012-03-28T17:32:57.882Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"msg":"req.params now: {\"name\":\"paul\"}","time":"2012-03-28T17:32:57.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"msg":"running ","time":"2012-03-28T17:32:57.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":20,"msg":"caller is \"paul\"","time":"2012-03-28T17:32:57.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"msg":"format entered(type=false)","time":"2012-03-28T17:32:57.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"msg":"format returing: {\"hello\":\"paul\"}","time":"2012-03-28T17:32:57.884Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":10,"res":{"statusCode":200,"headers":{"access-control-allow-origin":"*","access-control-allow-headers":"Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version","access-control-expose-headers":"X-Api-Version, X-Request-Id, X-Response-Time","server":"Hello API","x-request-id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","access-control-allow-methods":"GET","connection":"close","content-length":16,"content-md5":"Xmn3QcFXaIaKw9RPUARGBA==","content-type":"application/json","date":"Wed, 28 Mar 2012 17:32:57 GMT","x-response-time":4}},"msg":"response sent","time":"2012-03-28T17:32:57.886Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40211,"route":"SayHello","req_id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","level":30,"res":{"statusCode":200,"headers":{"access-control-allow-origin":"*","access-control-allow-headers":"Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version","access-control-expose-headers":"X-Api-Version, X-Request-Id, X-Response-Time","server":"Hello API","x-request-id":"ff0f5aba-b332-41b1-8b8b-1e828e69518a","access-control-allow-methods":"GET","connection":"close","content-length":16,"content-md5":"Xmn3QcFXaIaKw9RPUARGBA==","content-type":"application/json","date":"Wed, 28 Mar 2012 17:32:57 GMT","x-response-time":4}},"msg":"finished","time":"2012-03-28T17:32:57.886Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"level":30,"msg":"Hello API listening at <http://0.0.0.0:8080>","time":"2012-03-28T17:37:26.967Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"level":10,"msg":"New Request:\n\nGET /hello?name=paul HTTP/1.1\nuser-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3\nhost: 0.0.0.0:8080\naccept: */*\n","time":"2012-03-28T17:37:29.505Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"level":30,"req":{"method":"GET","url":"/hello?name=paul","headers":{"user-agent":"curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3","host":"0.0.0.0:8080","accept":"*/*"},"remoteAddress":"127.0.0.1","remotePort":59831},"msg":"start","time":"2012-03-28T17:37:29.506Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"req":{"method":"GET","url":"/hello?name=paul","headers":{"user-agent":"curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3","host":"0.0.0.0:8080","accept":"*/*"},"remoteAddress":"127.0.0.1","remotePort":59831},"msg":"starting request chain","time":"2012-03-28T17:37:29.507Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"msg":"running parseQueryString","time":"2012-03-28T17:37:29.507Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"msg":"req.params now: {\"name\":\"paul\"}","time":"2012-03-28T17:37:29.507Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"msg":"running ","time":"2012-03-28T17:37:29.507Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":20,"msg":"caller is \"paul\"","time":"2012-03-28T17:37:29.507Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"msg":"format entered(type=false)","time":"2012-03-28T17:37:29.508Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"msg":"format returing: {\"hello\":\"paul\"}","time":"2012-03-28T17:37:29.508Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":10,"res":{"statusCode":200,"headers":{"access-control-allow-origin":"*","access-control-allow-headers":"Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version","access-control-expose-headers":"X-Api-Version, X-Request-Id, X-Response-Time","server":"Hello API","x-request-id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","access-control-allow-methods":"GET","connection":"close","content-length":16,"content-md5":"Xmn3QcFXaIaKw9RPUARGBA==","content-type":"application/json","date":"Wed, 28 Mar 2012 17:37:29 GMT","x-response-time":3}},"msg":"response sent","time":"2012-03-28T17:37:29.510Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40341,"route":"SayHello","req_id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","level":30,"res":{"statusCode":200,"headers":{"access-control-allow-origin":"*","access-control-allow-headers":"Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version","access-control-expose-headers":"X-Api-Version, X-Request-Id, X-Response-Time","server":"Hello API","x-request-id":"9496dfdd-4ec7-4b59-aae7-3fed57aed5ba","access-control-allow-methods":"GET","connection":"close","content-length":16,"content-md5":"Xmn3QcFXaIaKw9RPUARGBA==","content-type":"application/json","date":"Wed, 28 Mar 2012 17:37:29 GMT","x-response-time":3}},"msg":"finished","time":"2012-03-28T17:37:29.510Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"level":30,"msg":"Hello API listening at <http://0.0.0.0:8080>","time":"2012-03-28T17:39:41.870Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"level":10,"msg":"New Request:\n\nGET /hello?name=paul HTTP/1.1\nuser-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3\nhost: 0.0.0.0:8080\naccept: */*\n","time":"2012-03-28T17:39:44.880Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"level":30,"req":{"method":"GET","url":"/hello?name=paul","headers":{"user-agent":"curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3","host":"0.0.0.0:8080","accept":"*/*"},"remoteAddress":"127.0.0.1","remotePort":59834},"msg":"start","time":"2012-03-28T17:39:44.880Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"req":{"method":"GET","url":"/hello?name=paul","headers":{"user-agent":"curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3","host":"0.0.0.0:8080","accept":"*/*"},"remoteAddress":"127.0.0.1","remotePort":59834},"msg":"starting request chain","time":"2012-03-28T17:39:44.881Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"msg":"running parseQueryString","time":"2012-03-28T17:39:44.881Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"msg":"req.params now: {\"name\":\"paul\"}","time":"2012-03-28T17:39:44.882Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"msg":"running ","time":"2012-03-28T17:39:44.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":20,"msg":"caller is \"paul\"","time":"2012-03-28T17:39:44.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"msg":"format entered(type=false)","time":"2012-03-28T17:39:44.883Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"msg":"format returing: {\"hello\":\"paul\"}","time":"2012-03-28T17:39:44.884Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":10,"res":{"statusCode":200,"headers":{"access-control-allow-origin":"*","access-control-allow-headers":"Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version","access-control-expose-headers":"X-Api-Version, X-Request-Id, X-Response-Time","server":"Hello API","x-request-id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","access-control-allow-methods":"GET","connection":"close","content-length":16,"content-md5":"Xmn3QcFXaIaKw9RPUARGBA==","content-type":"application/json","date":"Wed, 28 Mar 2012 17:39:44 GMT","x-response-time":5}},"msg":"response sent","time":"2012-03-28T17:39:44.885Z","v":0}
{"name":"helloapi","hostname":"banana.local","pid":40442,"route":"SayHello","req_id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","level":30,"res":{"statusCode":200,"headers":{"access-control-allow-origin":"*","access-control-allow-headers":"Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version","access-control-expose-headers":"X-Api-Version, X-Request-Id, X-Response-Time","server":"Hello API","x-request-id":"c49d05d8-afa4-4064-a0d9-913d50f5d02c","access-control-allow-methods":"GET","connection":"close","content-length":16,"content-md5":"Xmn3QcFXaIaKw9RPUARGBA==","content-type":"application/json","date":"Wed, 28 Mar 2012 17:39:44 GMT","x-response-time":5}},"msg":"finished","time":"2012-03-28T17:39:44.886Z","v":0}
Raw
2. cat hello.log PIPE bunyan
[2012-03-28T17:29:48.223Z] INFO: helloapi/40168 on banana.local: Hello API listening at <http://0.0.0.0:8080>
[2012-03-28T17:32:39.701Z] INFO: helloapi/40211 on banana.local: Hello API listening at <http://0.0.0.0:8080>
[2012-03-28T17:32:57.881Z] TRACE: helloapi/40211 on banana.local:
New Request:
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:32:57.881Z] INFO: helloapi/40211 on banana.local: start
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:32:57.882Z] TRACE: helloapi/40211 on banana.local: starting request chain (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:32:57.882Z] TRACE: helloapi/40211 on banana.local: running parseQueryString (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
[2012-03-28T17:32:57.883Z] TRACE: helloapi/40211 on banana.local: req.params now: {"name":"paul"} (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
[2012-03-28T17:32:57.883Z] TRACE: helloapi/40211 on banana.local: running (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
[2012-03-28T17:32:57.883Z] DEBUG: helloapi/40211 on banana.local: caller is "paul" (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
[2012-03-28T17:32:57.883Z] TRACE: helloapi/40211 on banana.local: format entered(type=false) (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
[2012-03-28T17:32:57.884Z] TRACE: helloapi/40211 on banana.local: format returing: {"hello":"paul"} (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
[2012-03-28T17:32:57.886Z] TRACE: helloapi/40211 on banana.local: response sent (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
HTTP/1.1 200 OK
access-control-allow-origin: *
access-control-allow-headers: Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version
access-control-expose-headers: X-Api-Version, X-Request-Id, X-Response-Time
server: Hello API
x-request-id: ff0f5aba-b332-41b1-8b8b-1e828e69518a
access-control-allow-methods: GET
connection: close
content-length: 16
content-md5: Xmn3QcFXaIaKw9RPUARGBA==
content-type: application/json
date: Wed, 28 Mar 2012 17:32:57 GMT
x-response-time: 4
[2012-03-28T17:32:57.886Z] INFO: helloapi/40211 on banana.local: finished (req_id=ff0f5aba-b332-41b1-8b8b-1e828e69518a, route=SayHello)
HTTP/1.1 200 OK
access-control-allow-origin: *
access-control-allow-headers: Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version
access-control-expose-headers: X-Api-Version, X-Request-Id, X-Response-Time
server: Hello API
x-request-id: ff0f5aba-b332-41b1-8b8b-1e828e69518a
access-control-allow-methods: GET
connection: close
content-length: 16
content-md5: Xmn3QcFXaIaKw9RPUARGBA==
content-type: application/json
date: Wed, 28 Mar 2012 17:32:57 GMT
x-response-time: 4
[2012-03-28T17:37:26.967Z] INFO: helloapi/40341 on banana.local: Hello API listening at <http://0.0.0.0:8080>
[2012-03-28T17:37:29.505Z] TRACE: helloapi/40341 on banana.local:
New Request:
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:37:29.506Z] INFO: helloapi/40341 on banana.local: start
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:37:29.507Z] TRACE: helloapi/40341 on banana.local: starting request chain (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:37:29.507Z] TRACE: helloapi/40341 on banana.local: running parseQueryString (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
[2012-03-28T17:37:29.507Z] TRACE: helloapi/40341 on banana.local: req.params now: {"name":"paul"} (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
[2012-03-28T17:37:29.507Z] TRACE: helloapi/40341 on banana.local: running (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
[2012-03-28T17:37:29.507Z] DEBUG: helloapi/40341 on banana.local: caller is "paul" (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
[2012-03-28T17:37:29.508Z] TRACE: helloapi/40341 on banana.local: format entered(type=false) (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
[2012-03-28T17:37:29.508Z] TRACE: helloapi/40341 on banana.local: format returing: {"hello":"paul"} (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
[2012-03-28T17:37:29.510Z] TRACE: helloapi/40341 on banana.local: response sent (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
HTTP/1.1 200 OK
access-control-allow-origin: *
access-control-allow-headers: Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version
access-control-expose-headers: X-Api-Version, X-Request-Id, X-Response-Time
server: Hello API
x-request-id: 9496dfdd-4ec7-4b59-aae7-3fed57aed5ba
access-control-allow-methods: GET
connection: close
content-length: 16
content-md5: Xmn3QcFXaIaKw9RPUARGBA==
content-type: application/json
date: Wed, 28 Mar 2012 17:37:29 GMT
x-response-time: 3
[2012-03-28T17:37:29.510Z] INFO: helloapi/40341 on banana.local: finished (req_id=9496dfdd-4ec7-4b59-aae7-3fed57aed5ba, route=SayHello)
HTTP/1.1 200 OK
access-control-allow-origin: *
access-control-allow-headers: Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version
access-control-expose-headers: X-Api-Version, X-Request-Id, X-Response-Time
server: Hello API
x-request-id: 9496dfdd-4ec7-4b59-aae7-3fed57aed5ba
access-control-allow-methods: GET
connection: close
content-length: 16
content-md5: Xmn3QcFXaIaKw9RPUARGBA==
content-type: application/json
date: Wed, 28 Mar 2012 17:37:29 GMT
x-response-time: 3
[2012-03-28T17:39:41.870Z] INFO: helloapi/40442 on banana.local: Hello API listening at <http://0.0.0.0:8080>
[2012-03-28T17:39:44.880Z] TRACE: helloapi/40442 on banana.local:
New Request:
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:39:44.880Z] INFO: helloapi/40442 on banana.local: start
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:39:44.881Z] TRACE: helloapi/40442 on banana.local: starting request chain (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
GET /hello?name=paul HTTP/1.1
user-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3
host: 0.0.0.0:8080
accept: */*
[2012-03-28T17:39:44.881Z] TRACE: helloapi/40442 on banana.local: running parseQueryString (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
[2012-03-28T17:39:44.882Z] TRACE: helloapi/40442 on banana.local: req.params now: {"name":"paul"} (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
[2012-03-28T17:39:44.883Z] TRACE: helloapi/40442 on banana.local: running (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
[2012-03-28T17:39:44.883Z] DEBUG: helloapi/40442 on banana.local: caller is "paul" (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
[2012-03-28T17:39:44.883Z] TRACE: helloapi/40442 on banana.local: format entered(type=false) (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
[2012-03-28T17:39:44.884Z] TRACE: helloapi/40442 on banana.local: format returing: {"hello":"paul"} (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
[2012-03-28T17:39:44.885Z] TRACE: helloapi/40442 on banana.local: response sent (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
HTTP/1.1 200 OK
access-control-allow-origin: *
access-control-allow-headers: Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version
access-control-expose-headers: X-Api-Version, X-Request-Id, X-Response-Time
server: Hello API
x-request-id: c49d05d8-afa4-4064-a0d9-913d50f5d02c
access-control-allow-methods: GET
connection: close
content-length: 16
content-md5: Xmn3QcFXaIaKw9RPUARGBA==
content-type: application/json
date: Wed, 28 Mar 2012 17:39:44 GMT
x-response-time: 5
[2012-03-28T17:39:44.886Z] INFO: helloapi/40442 on banana.local: finished (req_id=c49d05d8-afa4-4064-a0d9-913d50f5d02c, route=SayHello)
HTTP/1.1 200 OK
access-control-allow-origin: *
access-control-allow-headers: Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version
access-control-expose-headers: X-Api-Version, X-Request-Id, X-Response-Time
server: Hello API
x-request-id: c49d05d8-afa4-4064-a0d9-913d50f5d02c
access-control-allow-methods: GET
connection: close
content-length: 16
content-md5: Xmn3QcFXaIaKw9RPUARGBA==
content-type: application/json
date: Wed, 28 Mar 2012 17:39:44 GMT
x-response-time: 5
Raw
3. cat hello.log PIPE bunyan -j
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40168,
"level": 30,
"msg": "Hello API listening at <http://0.0.0.0:8080>",
"time": "2012-03-28T17:29:48.223Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"level": 30,
"msg": "Hello API listening at <http://0.0.0.0:8080>",
"time": "2012-03-28T17:32:39.701Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"level": 10,
"msg": "New Request:\n\nGET /hello?name=paul HTTP/1.1\nuser-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3\nhost: 0.0.0.0:8080\naccept: */*\n",
"time": "2012-03-28T17:32:57.881Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"level": 30,
"req": {
"method": "GET",
"url": "/hello?name=paul",
"headers": {
"user-agent": "curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3",
"host": "0.0.0.0:8080",
"accept": "*/*"
},
"remoteAddress": "127.0.0.1",
"remotePort": 59828
},
"msg": "start",
"time": "2012-03-28T17:32:57.881Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"req": {
"method": "GET",
"url": "/hello?name=paul",
"headers": {
"user-agent": "curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3",
"host": "0.0.0.0:8080",
"accept": "*/*"
},
"remoteAddress": "127.0.0.1",
"remotePort": 59828
},
"msg": "starting request chain",
"time": "2012-03-28T17:32:57.882Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"msg": "running parseQueryString",
"time": "2012-03-28T17:32:57.882Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"msg": "req.params now: {\"name\":\"paul\"}",
"time": "2012-03-28T17:32:57.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"msg": "running ",
"time": "2012-03-28T17:32:57.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 20,
"msg": "caller is \"paul\"",
"time": "2012-03-28T17:32:57.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"msg": "format entered(type=false)",
"time": "2012-03-28T17:32:57.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"msg": "format returing: {\"hello\":\"paul\"}",
"time": "2012-03-28T17:32:57.884Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 10,
"res": {
"statusCode": 200,
"headers": {
"access-control-allow-origin": "*",
"access-control-allow-headers": "Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version",
"access-control-expose-headers": "X-Api-Version, X-Request-Id, X-Response-Time",
"server": "Hello API",
"x-request-id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"access-control-allow-methods": "GET",
"connection": "close",
"content-length": 16,
"content-md5": "Xmn3QcFXaIaKw9RPUARGBA==",
"content-type": "application/json",
"date": "Wed, 28 Mar 2012 17:32:57 GMT",
"x-response-time": 4
}
},
"msg": "response sent",
"time": "2012-03-28T17:32:57.886Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40211,
"route": "SayHello",
"req_id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"level": 30,
"res": {
"statusCode": 200,
"headers": {
"access-control-allow-origin": "*",
"access-control-allow-headers": "Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version",
"access-control-expose-headers": "X-Api-Version, X-Request-Id, X-Response-Time",
"server": "Hello API",
"x-request-id": "ff0f5aba-b332-41b1-8b8b-1e828e69518a",
"access-control-allow-methods": "GET",
"connection": "close",
"content-length": 16,
"content-md5": "Xmn3QcFXaIaKw9RPUARGBA==",
"content-type": "application/json",
"date": "Wed, 28 Mar 2012 17:32:57 GMT",
"x-response-time": 4
}
},
"msg": "finished",
"time": "2012-03-28T17:32:57.886Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"level": 30,
"msg": "Hello API listening at <http://0.0.0.0:8080>",
"time": "2012-03-28T17:37:26.967Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"level": 10,
"msg": "New Request:\n\nGET /hello?name=paul HTTP/1.1\nuser-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3\nhost: 0.0.0.0:8080\naccept: */*\n",
"time": "2012-03-28T17:37:29.505Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"level": 30,
"req": {
"method": "GET",
"url": "/hello?name=paul",
"headers": {
"user-agent": "curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3",
"host": "0.0.0.0:8080",
"accept": "*/*"
},
"remoteAddress": "127.0.0.1",
"remotePort": 59831
},
"msg": "start",
"time": "2012-03-28T17:37:29.506Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"req": {
"method": "GET",
"url": "/hello?name=paul",
"headers": {
"user-agent": "curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3",
"host": "0.0.0.0:8080",
"accept": "*/*"
},
"remoteAddress": "127.0.0.1",
"remotePort": 59831
},
"msg": "starting request chain",
"time": "2012-03-28T17:37:29.507Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"msg": "running parseQueryString",
"time": "2012-03-28T17:37:29.507Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"msg": "req.params now: {\"name\":\"paul\"}",
"time": "2012-03-28T17:37:29.507Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"msg": "running ",
"time": "2012-03-28T17:37:29.507Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 20,
"msg": "caller is \"paul\"",
"time": "2012-03-28T17:37:29.507Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"msg": "format entered(type=false)",
"time": "2012-03-28T17:37:29.508Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"msg": "format returing: {\"hello\":\"paul\"}",
"time": "2012-03-28T17:37:29.508Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 10,
"res": {
"statusCode": 200,
"headers": {
"access-control-allow-origin": "*",
"access-control-allow-headers": "Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version",
"access-control-expose-headers": "X-Api-Version, X-Request-Id, X-Response-Time",
"server": "Hello API",
"x-request-id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"access-control-allow-methods": "GET",
"connection": "close",
"content-length": 16,
"content-md5": "Xmn3QcFXaIaKw9RPUARGBA==",
"content-type": "application/json",
"date": "Wed, 28 Mar 2012 17:37:29 GMT",
"x-response-time": 3
}
},
"msg": "response sent",
"time": "2012-03-28T17:37:29.510Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40341,
"route": "SayHello",
"req_id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"level": 30,
"res": {
"statusCode": 200,
"headers": {
"access-control-allow-origin": "*",
"access-control-allow-headers": "Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version",
"access-control-expose-headers": "X-Api-Version, X-Request-Id, X-Response-Time",
"server": "Hello API",
"x-request-id": "9496dfdd-4ec7-4b59-aae7-3fed57aed5ba",
"access-control-allow-methods": "GET",
"connection": "close",
"content-length": 16,
"content-md5": "Xmn3QcFXaIaKw9RPUARGBA==",
"content-type": "application/json",
"date": "Wed, 28 Mar 2012 17:37:29 GMT",
"x-response-time": 3
}
},
"msg": "finished",
"time": "2012-03-28T17:37:29.510Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"level": 30,
"msg": "Hello API listening at <http://0.0.0.0:8080>",
"time": "2012-03-28T17:39:41.870Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"level": 10,
"msg": "New Request:\n\nGET /hello?name=paul HTTP/1.1\nuser-agent: curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3\nhost: 0.0.0.0:8080\naccept: */*\n",
"time": "2012-03-28T17:39:44.880Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"level": 30,
"req": {
"method": "GET",
"url": "/hello?name=paul",
"headers": {
"user-agent": "curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3",
"host": "0.0.0.0:8080",
"accept": "*/*"
},
"remoteAddress": "127.0.0.1",
"remotePort": 59834
},
"msg": "start",
"time": "2012-03-28T17:39:44.880Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"req": {
"method": "GET",
"url": "/hello?name=paul",
"headers": {
"user-agent": "curl/7.19.7 (universal-apple-darwin10.0) libcurl/7.19.7 OpenSSL/0.9.8r zlib/1.2.3",
"host": "0.0.0.0:8080",
"accept": "*/*"
},
"remoteAddress": "127.0.0.1",
"remotePort": 59834
},
"msg": "starting request chain",
"time": "2012-03-28T17:39:44.881Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"msg": "running parseQueryString",
"time": "2012-03-28T17:39:44.881Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"msg": "req.params now: {\"name\":\"paul\"}",
"time": "2012-03-28T17:39:44.882Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"msg": "running ",
"time": "2012-03-28T17:39:44.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 20,
"msg": "caller is \"paul\"",
"time": "2012-03-28T17:39:44.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"msg": "format entered(type=false)",
"time": "2012-03-28T17:39:44.883Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"msg": "format returing: {\"hello\":\"paul\"}",
"time": "2012-03-28T17:39:44.884Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 10,
"res": {
"statusCode": 200,
"headers": {
"access-control-allow-origin": "*",
"access-control-allow-headers": "Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version",
"access-control-expose-headers": "X-Api-Version, X-Request-Id, X-Response-Time",
"server": "Hello API",
"x-request-id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"access-control-allow-methods": "GET",
"connection": "close",
"content-length": 16,
"content-md5": "Xmn3QcFXaIaKw9RPUARGBA==",
"content-type": "application/json",
"date": "Wed, 28 Mar 2012 17:39:44 GMT",
"x-response-time": 5
}
},
"msg": "response sent",
"time": "2012-03-28T17:39:44.885Z",
"v": 0
}
{
"name": "helloapi",
"hostname": "banana.local",
"pid": 40442,
"route": "SayHello",
"req_id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"level": 30,
"res": {
"statusCode": 200,
"headers": {
"access-control-allow-origin": "*",
"access-control-allow-headers": "Accept, Accept-Version, Content-Length, Content-MD5, Content-Type, Date, X-Api-Version",
"access-control-expose-headers": "X-Api-Version, X-Request-Id, X-Response-Time",
"server": "Hello API",
"x-request-id": "c49d05d8-afa4-4064-a0d9-913d50f5d02c",
"access-control-allow-methods": "GET",
"connection": "close",
"content-length": 16,
"content-md5": "Xmn3QcFXaIaKw9RPUARGBA==",
"content-type": "application/json",
"date": "Wed, 28 Mar 2012 17:39:44 GMT",
"x-response-time": 5
}
},
"msg": "finished",
"time": "2012-03-28T17:39:44.886Z",
"v": 0
}
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment