cache and clean the VOMS AC only once per connection

As a consequence, the VOMS AC is available during the logging operation at the end of the request and can be seen in the access log, e.g. with the following change in the configuration file:

http {
log_format storm '$time_iso8601 [$request_id] $remote_addr '
                    '- $remote_user "$request" <$upstream_response_time> '
                    '$ssl_protocol/$ssl_cipher '
                    '"$ssl_client_s_dn" '
                    '[$voms_fqans] '
                    '$status $body_bytes_sent "$http_referer" '
                    '"$http_user_agent" "$http_x_forwarded_for"';
    access_log ... storm;
    ...

In the access log one can then see, for example:

2018-06-27T13:56:19+00:00 [4cc92445bf56587e6d589fb7c697841b] 127.0.0.1 - - "GET / HTTP/1.1" <-> TLSv1.2/ECDHE-RSA-AES256-GCM-SHA384 "CN=1744748182,CN=test0,O=IGI,C=IT" [/test/exp1,/test/exp2,/test/exp3/Role=PIPPO] 200 330 "-" "curl/7.29.0" "-"

There are no tests for this change. A test can be done using, for example:

curl --cert t/certs/3.pem --capath t/trust-anchors --cacert t/certs/3.cert.pem https://nginx-voms.example:8443 https://nginx-voms.example:8443

Note that two identical URLs are requested and curl sends them within the same connection (i.e. the connection is kept alive). To verify that the VOMS AC is cached and cleaned only once per connection one can check the error log, for example:

2018/06/27 13:56:19 [debug] 9207#9207: *1 generic_getter
2018/06/27 13:56:19 [debug] 9207#9207: *1 get_voms_ac
2018/06/27 13:56:19 [debug] 9207#9207: *1 get_voms_ac_from_cache
2018/06/27 13:56:19 [debug] 9207#9207: *1 retrieve_voms_ac_from_proxy
2018/06/27 13:56:19 [debug] 9207#9207: *1 cache_voms_ac
2018/06/27 13:56:19 [debug] 9207#9207: *1 generic_getter
2018/06/27 13:56:19 [debug] 9207#9207: *1 get_voms_ac
2018/06/27 13:56:19 [debug] 9207#9207: *1 get_voms_ac_from_cache
2018/06/27 13:56:19 [debug] 9207#9207: *1 generic_getter
2018/06/27 13:56:19 [debug] 9207#9207: *1 get_voms_ac
2018/06/27 13:56:19 [debug] 9207#9207: *1 get_voms_ac_from_cache
...
2018/06/27 13:56:57 [info] 9207#9207: *2 client 127.0.0.1 closed keepalive connection
2018/06/27 13:56:57 [debug] 9207#9207: *2 clean_voms_ac

valgrind.suppress

+{
+   <insert_a_suppression_name_here>
+   Memcheck:Leak
+   match-leak-kinds: definite
+   fun:malloc
+   fun:CRYPTO_malloc
+   fun:SSL_SESSION_new
+   fun:ssl_get_new_session
+   fun:ssl23_connect
+   fun:ngx_ssl_handshake
+   fun:ngx_http_upstream_ssl_init_connection
+   fun:ngx_http_upstream_send_request_handler
+   fun:ngx_http_upstream_handler
+   fun:ngx_epoll_process_events
+   fun:ngx_process_events_and_timers
+   fun:ngx_single_process_cycle
+   fun:main
+}
+{
+   <insert_a_suppression_name_here>
+   Memcheck:Leak
+   match-leak-kinds: definite
+   fun:malloc
+   fun:ngx_alloc
+   fun:ngx_set_environment
+   fun:ngx_single_process_cycle
+   fun:main
+}
+{
+   <insert_a_suppression_name_here>
+   Memcheck:Free
+   fun:free
+   fun:__libc_freeres
+   fun:_vgnU_freeres
+   fun:__run_exit_handlers
+   fun:exit
+   fun:ngx_master_process_exit
+   fun:ngx_single_process_cycle
+   fun:main
+}
+{
+   <insert_a_suppression_name_here>
+   Memcheck:Param
+   epoll_ctl(event)
+   fun:epoll_ctl
+   fun:ngx_epoll_test_rdhup
+   fun:ngx_epoll_init
+   fun:ngx_event_process_init
+   fun:ngx_single_process_cycle
+   fun:main
+}
+{
+   <insert_a_suppression_name_here>
+   Memcheck:Param
+   epoll_ctl(event)
+   fun:epoll_ctl
+   fun:ngx_epoll_test_rdhup
+   fun:ngx_epoll_init
+   fun:ngx_event_process_init
+   fun:ngx_single_process_cycle
+   fun:main
+}