Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

"zpm deploy -l debug" should include request/response data #131

Closed
etoews opened this issue Jun 13, 2014 · 5 comments · Fixed by #171
Closed

"zpm deploy -l debug" should include request/response data #131

etoews opened this issue Jun 13, 2014 · 5 comments · Fixed by #171
Assignees
@larsbutler
Labels
enhancement

Comments

@etoews
Copy link

etoews commented Jun 13, 2014

When running zpm deploy -l debug I only see:

INFO:deploying hello.zapp
INFO:updated mycontainer/hello.zapp succesfully
INFO:updated mycontainer/hello.json succesfully
INFO:updated mycontainer/index.html succesfully
INFO:updated mycontainer/style.css succesfully
INFO:updated mycontainer/zerocloud.js succesfully
INFO:app deployed to
https://zebra.zerovm.org/v1/AUTH_xxx/mycontainer/

If it contained the full request/response data ala nova --debug, that would be a huge help to creating clients for ZeroVM.

Also, it's weird that there aren't any debug level messages in the above.
@larsbutler
Copy link
Member

Hey @everett-toews,

I agree, having the request response data shown at DEBUG would be useful.

The reason why there are no debug messages displayed is because at present there simply aren't any debug level messages in this code path. (It seems there are only debug messages when an app is executed, using zpm execute or zpm deploy --execute.)

@larsbutler larsbutler self-assigned this Jul 31, 2014
@larsbutler larsbutler mentioned this issue Jul 31, 2014
Merged
@larsbutler
Copy link
Member

@everett-toews Patch submitted; is this what you're looking for?

@pkit pkit closed this as completed in #171 Jul 31, 2014
@etoews
Copy link
Author

etoews commented Aug 1, 2014

@larsbutler Probably?

Can you paste in some sample output please?

@larsbutler
Copy link
Member

@everett-toews Sure. Here are a few examples. Auth tokens and and AUTH_ URLs replaced with fake ones. Since we're using python-swiftclient to handle all of our requests, having this output was simply a matter of configuring the logging properly.

Deploy, no execute, INFO:

$ zpm deploy -l info test1 hello.zapp
INFO:zpmlib.commands: deploying hello.zapp
INFO:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test1?format=json -X GET -H "X-Auth-Token: AUTH_tkABC123"
INFO:swiftclient: RESP STATUS: 404 Not Found
INFO:swiftclient: RESP HEADERS: [('date', 'Mon, 04 Aug 2014 09:43:17 GMT'), ('content-length', '70'), ('content-type', 'text/html; charset=UTF-8'), ('x-trans-id', 'tx3a9c83f79a5b41e59dad9-0053df55b5')]
INFO:swiftclient: RESP BODY: <html><h1>Not Found</h1><p>The resource could not be found.</p></html>
INFO:zpmlib.zpm: Container 'test1' not found. Creating it...
app deployed to
  https://zebra.zerovm.org/v1/AUTH_ABC123/test1/index.html

Deploy, no execute, DEBUG:

$ zpm deploy -l debug test2 hello.zapp
INFO:zpmlib.commands: deploying hello.zapp
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/auth/v1.0 -X GET
DEBUG:swiftclient: RESP STATUS: 200 OK
DEBUG:swiftclient: RESP HEADERS: [('content-length', '115'), ('set-cookie', 'session=AUTH_tkABC123; Domain=zebra.zerovm.org; expires=Tue, 05 Aug 2014 09:41:56 GMT; httponly; Path=/; secure, storage=https%3A%2F%2Fzebra.zerovm.org%2Fv1%2FAUTH_ABC123; Domain=zebra.zerovm.org; expires=Tue, 05 Aug 2014 09:41:56 GMT; Path=/; secure'), ('x-trans-id', 'txc65afb1dfeae4465bca74-0053df55ce'), ('x-auth-token-expires', '86294'), ('x-auth-token', 'AUTH_tkABC123'), ('x-storage-token', 'AUTH_tkABC123'), ('date', 'Mon, 04 Aug 2014 09:43:42 GMT'), ('x-storage-url', 'https://zebra.zerovm.org/v1/AUTH_ABC123'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: RESP BODY: {"storage": {"default": "local", "local": "https://zebra.zerovm.org/v1/AUTH_ABC123"}}
INFO:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2?format=json -X GET -H "X-Auth-Token: AUTH_tkABC123"
INFO:swiftclient: RESP STATUS: 404 Not Found
INFO:swiftclient: RESP HEADERS: [('date', 'Mon, 04 Aug 2014 09:43:42 GMT'), ('content-length', '70'), ('content-type', 'text/html; charset=UTF-8'), ('x-trans-id', 'tx252d64868fcc4f4a9668c-0053df55ce')]
INFO:swiftclient: RESP BODY: <html><h1>Not Found</h1><p>The resource could not be found.</p></html>
INFO:zpmlib.zpm: Container 'test2' not found. Creating it...
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2 -X PUT -H "Content-Length: 0" -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('date', 'Mon, 04 Aug 2014 09:43:44 GMT'), ('content-length', '0'), ('content-type', 'text/html; charset=UTF-8'), ('x-trans-id', 'tx679793a460c54271a27f9-0053df55cf')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2/hello.zapp -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:43:46 GMT'), ('etag', '711669fa5f8a95d98916d3794975f065'), ('x-trans-id', 'tx64d7f1d0f0344b158fcba-0053df55d1'), ('date', 'Mon, 04 Aug 2014 09:43:45 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2/boot/system.map -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:43:47 GMT'), ('etag', 'f4b7438b8b3c9f75755667d2399b753b'), ('x-trans-id', 'tx77f7a96d1bd54e0ab37b8-0053df55d2'), ('date', 'Mon, 04 Aug 2014 09:43:46 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2/index.html -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:43:47 GMT'), ('etag', '19a250cfe4b1993772d654a95a3c509b'), ('x-trans-id', 'tx983ddfe854d1401191f76-0053df55d2'), ('date', 'Mon, 04 Aug 2014 09:43:46 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2/style.css -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:43:48 GMT'), ('etag', '88bc79a1dff0fead7eef65ada1266db6'), ('x-trans-id', 'txffe21ecd50af47f38b4ad-0053df55d3'), ('date', 'Mon, 04 Aug 2014 09:43:47 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test2/zerocloud.js -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:43:49 GMT'), ('etag', '873f7faa40c9934c77d304af155a4434'), ('x-trans-id', 'tx7a20bd8b15bf40d0942c1-0053df55d4'), ('date', 'Mon, 04 Aug 2014 09:43:48 GMT'), ('content-type', 'text/html; charset=UTF-8')]
app deployed to
  https://zebra.zerovm.org/v1/AUTH_ABC123/test2/index.html

Deploy + execute, INFO:

$ zpm deploy -l info test3 hello.zapp --execute
INFO:zpmlib.commands: deploying hello.zapp
INFO:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test3?format=json -X GET -H "X-Auth-Token: AUTH_tkABC123"
INFO:swiftclient: RESP STATUS: 404 Not Found
INFO:swiftclient: RESP HEADERS: [('date', 'Mon, 04 Aug 2014 09:44:12 GMT'), ('content-length', '70'), ('content-type', 'text/html; charset=UTF-8'), ('x-trans-id', 'tx6d375ea4b0ca49ea8382c-0053df55ec')]
INFO:swiftclient: RESP BODY: <html><h1>Not Found</h1><p>The resource could not be found.</p></html>
INFO:zpmlib.zpm: Container 'test3' not found. Creating it...
hello from zerovm!

app deployed to
  https://zebra.zerovm.org/v1/AUTH_ABC123/test3/index.html

Deploy + execute, DEBUG:

$ zpm deploy -l debug test4 hello.zapp --execute
INFO:zpmlib.commands: deploying hello.zapp
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/auth/v1.0 -X GET
DEBUG:swiftclient: RESP STATUS: 200 OK
DEBUG:swiftclient: RESP HEADERS: [('content-length', '115'), ('set-cookie', 'session=AUTH_tkABC123; Domain=zebra.zerovm.org; expires=Tue, 05 Aug 2014 09:41:56 GMT; httponly; Path=/; secure, storage=https%3A%2F%2Fzebra.zerovm.org%2Fv1%2FAUTH_ABC123; Domain=zebra.zerovm.org; expires=Tue, 05 Aug 2014 09:41:56 GMT; Path=/; secure'), ('x-trans-id', 'tx66b9f8a379134ca794da3-0053df5601'), ('x-auth-token-expires', '86243'), ('x-auth-token', 'AUTH_tkABC123'), ('x-storage-token', 'AUTH_tkABC123'), ('date', 'Mon, 04 Aug 2014 09:44:33 GMT'), ('x-storage-url', 'https://zebra.zerovm.org/v1/AUTH_ABC123'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: RESP BODY: {"storage": {"default": "local", "local": "https://zebra.zerovm.org/v1/AUTH_ABC123"}}
INFO:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4?format=json -X GET -H "X-Auth-Token: AUTH_tkABC123"
INFO:swiftclient: RESP STATUS: 404 Not Found
INFO:swiftclient: RESP HEADERS: [('date', 'Mon, 04 Aug 2014 09:44:34 GMT'), ('content-length', '70'), ('content-type', 'text/html; charset=UTF-8'), ('x-trans-id', 'tx39d11a06bb37495e8d466-0053df5602')]
INFO:swiftclient: RESP BODY: <html><h1>Not Found</h1><p>The resource could not be found.</p></html>
INFO:zpmlib.zpm: Container 'test4' not found. Creating it...
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4 -X PUT -H "Content-Length: 0" -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('date', 'Mon, 04 Aug 2014 09:44:34 GMT'), ('content-length', '0'), ('content-type', 'text/html; charset=UTF-8'), ('x-trans-id', 'tx2486703f36ad4f868ec52-0053df5602')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4/hello.zapp -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:44:36 GMT'), ('etag', '711669fa5f8a95d98916d3794975f065'), ('x-trans-id', 'tx12970a6129264d16bc4ab-0053df5603'), ('date', 'Mon, 04 Aug 2014 09:44:36 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4/boot/system.map -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:44:38 GMT'), ('etag', '9e12d717037c81dfd3ade2eb29b8ca4f'), ('x-trans-id', 'tx70e0661f697b4d348ba4e-0053df5605'), ('date', 'Mon, 04 Aug 2014 09:44:37 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4/index.html -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:44:38 GMT'), ('etag', '19a250cfe4b1993772d654a95a3c509b'), ('x-trans-id', 'txf3dbde8f085341d28dc50-0053df5605'), ('date', 'Mon, 04 Aug 2014 09:44:37 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4/style.css -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:44:39 GMT'), ('etag', '88bc79a1dff0fead7eef65ada1266db6'), ('x-trans-id', 'tx2711970c5dfa4f73b96e9-0053df5606'), ('date', 'Mon, 04 Aug 2014 09:44:38 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4/zerocloud.js -X PUT -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 201 Created
DEBUG:swiftclient: RESP HEADERS: [('content-length', '0'), ('last-modified', 'Mon, 04 Aug 2014 09:44:40 GMT'), ('etag', '873f7faa40c9934c77d304af155a4434'), ('x-trans-id', 'tx1ffbb7f3abb24aaab16fd-0053df5607'), ('date', 'Mon, 04 Aug 2014 09:44:39 GMT'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/auth/v1.0 -X GET
DEBUG:swiftclient: RESP STATUS: 200 OK
DEBUG:swiftclient: RESP HEADERS: [('content-length', '115'), ('set-cookie', 'session=AUTH_tkABC123; Domain=zebra.zerovm.org; expires=Tue, 05 Aug 2014 09:41:56 GMT; httponly; Path=/; secure, storage=https%3A%2F%2Fzebra.zerovm.org%2Fv1%2FAUTH_ABC123; Domain=zebra.zerovm.org; expires=Tue, 05 Aug 2014 09:41:56 GMT; Path=/; secure'), ('x-trans-id', 'txe4778c4fc1994a6aa71cb-0053df5607'), ('x-auth-token-expires', '86237'), ('x-auth-token', 'AUTH_tkABC123'), ('x-storage-token', 'AUTH_tkABC123'), ('date', 'Mon, 04 Aug 2014 09:44:39 GMT'), ('x-storage-url', 'https://zebra.zerovm.org/v1/AUTH_ABC123'), ('content-type', 'text/html; charset=UTF-8')]
DEBUG:swiftclient: RESP BODY: {"storage": {"default": "local", "local": "https://zebra.zerovm.org/v1/AUTH_ABC123"}}
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123/test4/boot/system.map -X GET -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 200 OK
DEBUG:swiftclient: RESP HEADERS: [('content-length', '245'), ('accept-ranges', 'bytes'), ('last-modified', 'Mon, 04 Aug 2014 09:44:38 GMT'), ('etag', '9e12d717037c81dfd3ade2eb29b8ca4f'), ('x-timestamp', '1407145477.08335'), ('x-trans-id', 'tx649f70cb8a4b4c479ceb5-0053df5608'), ('date', 'Mon, 04 Aug 2014 09:44:40 GMT'), ('content-type', 'application/json')]
DEBUG:swiftclient: REQ: curl -i https://zebra.zerovm.org/v1/AUTH_ABC123 -X POST -H "Content-Type: application/json" -H "X-Zerovm-Execute: 1.0" -H "X-Auth-Token: AUTH_tkABC123"
DEBUG:swiftclient: RESP STATUS: 200 OK
DEBUG:swiftclient: RESP HEADERS: [('x-nexe-retcode', '0'), ('x-nexe-system', 'hello'), ('x-nexe-cdr-line', '4.396, 4.390, 0.14 3.87 1025 75942868 2 19 0 0 0 0'), ('x-nexe-policy', 'Policy-0'), ('x-nexe-validation', '0'), ('content-length', '19'), ('x-nexe-etag', 'disabled'), ('etag', '035f4c7abe607d17359db1e3affd3f08'), ('x-trans-id', 'tx51a50fbe3dc0446c92ace-0053df5609'), ('x-nexe-status', 'ok'), ('date', 'Mon, 04 Aug 2014 09:44:45 GMT'), ('content-type', 'text/html')]
DEBUG:swiftclient: RESP BODY: hello from zerovm!

DEBUG:zpmlib.zpm: response status: 200
hello from zerovm!

app deployed to
  https://zebra.zerovm.org/v1/AUTH_ABC123/test4/index.html

@etoews
Copy link
Author

etoews commented Aug 4, 2014

LGTM! Nice work.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@larsbutler larsbutler

Labels
enhancement
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Better HTTP request/response logging
2 participants
@larsbutler @etoews