WSGI requests in ir-api are reported with no log level and inconsistent date string

[Sean Dague]

WSGI requests in ir-api are reported with no log level and a date string which is not consistent with the rest of the log:

Example:

http://logs.openstack.org/01/236901/1/check/gate-tempest-dsvm-ironic-agent_ssh/349672c/logs/screen-ir-api.txt.gz#_2015-10-19_11_30_22_584

2015-10-19 11:30:22.583 18979 DEBUG keystonemiddleware.auth_token [-] Authenticating user token process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:478
2015-10-19 11:30:22.584 18979 DEBUG keystonemiddleware.auth_token [-] Received request from user: user_id bc729ccac6e54a52a9778149f892f521, project_id c02d0be4e2724497ab1fc6a94b5804d9, roles admin process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:758
127.0.0.1 - - [19/Oct/2015 11:30:22] "GET /v1/nodes/detail?instance_uuid=425f3318-5236-4ead-baea-75df5905a965 HTTP/1.1" 200 9943
2015-10-19 11:30:22.639 18979 DEBUG keystonemiddleware.auth_token [-] Authenticating user token process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:478
2015-10-19 11:30:22.641 18979 DEBUG keystonemiddleware.auth_token [-] Received request from user: user_id 14a89e56dc42451b86efaf721cf9dd28, project_id c77a1bf6d7114ac796f007b220a3672c, roles admin,Member,_member_ process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:758
127.0.0.1 - - [19/Oct/2015 11:30:22] "GET /v1/nodes/79517f80-464c-4030-981a-790b457a8cb0 HTTP/1.1" 200 9776
2015-10-19 11:30:23.660 18979 DEBUG keystonemiddleware.auth_token [-] Authenticating user token process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:478
2015-10-19 11:30:23.662 18979 DEBUG keystonemiddleware.auth_token [-] Received request from user: user_id 14a89e56dc42451b86efaf721cf9dd28, project_id c77a1bf6d7114ac796f007b220a3672c, roles admin,Member,_member_ process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:758

[Sean Dague (sdague)]

The crux of this issue is that wsgiref.simple_server is used, which means default logging is very wonky.

http://stackoverflow.com/questions/31433682/control-wsgiref-simple-server-log - gives some hints on how to do this right.

[Dmitry Tantsur (divius)]

Note that switching to oslo.service might change the situation: https://bugs.launchpad.net/ironic/+bug/1484044

[Rahul U Nair (rahulunair)]

Unassigning my self my from the bug.

[Dmitry Tantsur (divius)]

The API logging has improved substantially with the switch to oslo.service, so I think we can call it done.