d1fa88a7f2e0913069bd6a3a41ae8f0ea31a7c6c
[cascardo/ovs.git] / lib / stream-ssl.c
1 /*
2  * Copyright (c) 2008, 2009, 2010, 2011, 2012, 2013, 2014 Nicira, Inc.
3  *
4  * Licensed under the Apache License, Version 2.0 (the "License");
5  * you may not use this file except in compliance with the License.
6  * You may obtain a copy of the License at:
7  *
8  *     http://www.apache.org/licenses/LICENSE-2.0
9  *
10  * Unless required by applicable law or agreed to in writing, software
11  * distributed under the License is distributed on an "AS IS" BASIS,
12  * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
13  * See the License for the specific language governing permissions and
14  * limitations under the License.
15  */
16
17 #include <config.h>
18 #include "stream-ssl.h"
19 #include "dhparams.h"
20 #include <ctype.h>
21 #include <errno.h>
22 #include <inttypes.h>
23 #include <string.h>
24 #include <sys/types.h>
25 #include <sys/socket.h>
26 #include <netinet/tcp.h>
27 #include <openssl/err.h>
28 #include <openssl/rand.h>
29 #include <openssl/ssl.h>
30 #include <openssl/x509v3.h>
31 #include <poll.h>
32 #include <fcntl.h>
33 #include <sys/stat.h>
34 #include <unistd.h>
35 #include "coverage.h"
36 #include "dynamic-string.h"
37 #include "entropy.h"
38 #include "ofpbuf.h"
39 #include "openflow/openflow.h"
40 #include "packets.h"
41 #include "poll-loop.h"
42 #include "shash.h"
43 #include "socket-util.h"
44 #include "util.h"
45 #include "stream-provider.h"
46 #include "stream.h"
47 #include "timeval.h"
48 #include "vlog.h"
49
50 #ifdef _WIN32
51 /* Ref: https://www.openssl.org/support/faq.html#PROG2
52  * Your application must link against the same version of the Win32 C-Runtime
53  * against which your openssl libraries were linked.  The default version for
54  * OpenSSL is /MD - "Multithreaded DLL". If we compile Open vSwitch with
55  * something other than /MD, instead of re-compiling OpenSSL
56  * toolkit, openssl/applink.c can be #included. Also, it is important
57  * to add CRYPTO_malloc_init prior first call to OpenSSL.
58  *
59  * XXX: The behavior of the following #include when Open vSwitch is
60  * compiled with /MD is not tested. */
61 #include <openssl/applink.c>
62 #define SHUT_RDWR SD_BOTH
63 #endif
64
65 VLOG_DEFINE_THIS_MODULE(stream_ssl);
66
67 /* Active SSL. */
68
69 enum ssl_state {
70     STATE_TCP_CONNECTING,
71     STATE_SSL_CONNECTING
72 };
73
74 enum session_type {
75     CLIENT,
76     SERVER
77 };
78
79 struct ssl_stream
80 {
81     struct stream stream;
82     enum ssl_state state;
83     enum session_type type;
84     int fd;
85     SSL *ssl;
86     struct ofpbuf *txbuf;
87     unsigned int session_nr;
88
89     /* rx_want and tx_want record the result of the last call to SSL_read()
90      * and SSL_write(), respectively:
91      *
92      *    - If the call reported that data needed to be read from the file
93      *      descriptor, the corresponding member is set to SSL_READING.
94      *
95      *    - If the call reported that data needed to be written to the file
96      *      descriptor, the corresponding member is set to SSL_WRITING.
97      *
98      *    - Otherwise, the member is set to SSL_NOTHING, indicating that the
99      *      call completed successfully (or with an error) and that there is no
100      *      need to block.
101      *
102      * These are needed because there is no way to ask OpenSSL what a data read
103      * or write would require without giving it a buffer to receive into or
104      * data to send, respectively.  (Note that the SSL_want() status is
105      * overwritten by each SSL_read() or SSL_write() call, so we can't rely on
106      * its value.)
107      *
108      * A single call to SSL_read() or SSL_write() can perform both reading
109      * and writing and thus invalidate not one of these values but actually
110      * both.  Consider this situation, for example:
111      *
112      *    - SSL_write() blocks on a read, so tx_want gets SSL_READING.
113      *
114      *    - SSL_read() laters succeeds reading from 'fd' and clears out the
115      *      whole receive buffer, so rx_want gets SSL_READING.
116      *
117      *    - Client calls stream_wait(STREAM_RECV) and stream_wait(STREAM_SEND)
118      *      and blocks.
119      *
120      *    - Now we're stuck blocking until the peer sends us data, even though
121      *      SSL_write() could now succeed, which could easily be a deadlock
122      *      condition.
123      *
124      * On the other hand, we can't reset both tx_want and rx_want on every call
125      * to SSL_read() or SSL_write(), because that would produce livelock,
126      * e.g. in this situation:
127      *
128      *    - SSL_write() blocks, so tx_want gets SSL_READING or SSL_WRITING.
129      *
130      *    - SSL_read() blocks, so rx_want gets SSL_READING or SSL_WRITING,
131      *      but tx_want gets reset to SSL_NOTHING.
132      *
133      *    - Client calls stream_wait(STREAM_RECV) and stream_wait(STREAM_SEND)
134      *      and blocks.
135      *
136      *    - Client wakes up immediately since SSL_NOTHING in tx_want indicates
137      *      that no blocking is necessary.
138      *
139      * The solution we adopt here is to set tx_want to SSL_NOTHING after
140      * calling SSL_read() only if the SSL state of the connection changed,
141      * which indicates that an SSL-level renegotiation made some progress, and
142      * similarly for rx_want and SSL_write().  This prevents both the
143      * deadlock and livelock situations above.
144      */
145     int rx_want, tx_want;
146
147     /* A few bytes of header data in case SSL negotiation fails. */
148     uint8_t head[2];
149     short int n_head;
150 };
151
152 /* SSL context created by ssl_init(). */
153 static SSL_CTX *ctx;
154
155 struct ssl_config_file {
156     bool read;                  /* Whether the file was successfully read. */
157     char *file_name;            /* Configured file name, if any. */
158     struct timespec mtime;      /* File mtime as of last time we read it. */
159 };
160
161 /* SSL configuration files. */
162 static struct ssl_config_file private_key;
163 static struct ssl_config_file certificate;
164 static struct ssl_config_file ca_cert;
165
166 /* Ordinarily, the SSL client and server verify each other's certificates using
167  * a CA certificate.  Setting this to false disables this behavior.  (This is a
168  * security risk.) */
169 static bool verify_peer_cert = true;
170
171 /* Ordinarily, we require a CA certificate for the peer to be locally
172  * available.  We can, however, bootstrap the CA certificate from the peer at
173  * the beginning of our first connection then use that certificate on all
174  * subsequent connections, saving it to a file for use in future runs also.  In
175  * this case, 'bootstrap_ca_cert' is true. */
176 static bool bootstrap_ca_cert;
177
178 /* Session number.  Used in debug logging messages to uniquely identify a
179  * session. */
180 static unsigned int next_session_nr;
181
182 /* Who knows what can trigger various SSL errors, so let's throttle them down
183  * quite a bit. */
184 static struct vlog_rate_limit rl = VLOG_RATE_LIMIT_INIT(10, 25);
185
186 static int ssl_init(void);
187 static int do_ssl_init(void);
188 static bool ssl_wants_io(int ssl_error);
189 static void ssl_close(struct stream *);
190 static void ssl_clear_txbuf(struct ssl_stream *);
191 static void interpret_queued_ssl_error(const char *function);
192 static int interpret_ssl_error(const char *function, int ret, int error,
193                                int *want);
194 static DH *tmp_dh_callback(SSL *ssl, int is_export OVS_UNUSED, int keylength);
195 static void log_ca_cert(const char *file_name, X509 *cert);
196 static void stream_ssl_set_ca_cert_file__(const char *file_name,
197                                           bool bootstrap, bool force);
198 static void ssl_protocol_cb(int write_p, int version, int content_type,
199                             const void *, size_t, SSL *, void *sslv_);
200 static bool update_ssl_config(struct ssl_config_file *, const char *file_name);
201 static int sock_errno(void);
202
203 static short int
204 want_to_poll_events(int want)
205 {
206     switch (want) {
207     case SSL_NOTHING:
208         OVS_NOT_REACHED();
209
210     case SSL_READING:
211         return POLLIN;
212
213     case SSL_WRITING:
214         return POLLOUT;
215
216     default:
217         OVS_NOT_REACHED();
218     }
219 }
220
221 static int
222 setsockopt_tcp_nodelay(int fd)
223 {
224     int on = 1;
225     int retval;
226
227     retval = setsockopt(fd, IPPROTO_TCP, TCP_NODELAY, &on, sizeof on);
228     if (retval) {
229         retval = sock_errno();
230         VLOG_ERR("setsockopt(TCP_NODELAY): %s", sock_strerror(retval));
231     }
232     return retval;
233 }
234
235 static int
236 new_ssl_stream(const char *name, int fd, enum session_type type,
237                enum ssl_state state, struct stream **streamp)
238 {
239     struct ssl_stream *sslv;
240     SSL *ssl = NULL;
241     int retval;
242
243     /* Check for all the needful configuration. */
244     retval = 0;
245     if (!private_key.read) {
246         VLOG_ERR("Private key must be configured to use SSL");
247         retval = ENOPROTOOPT;
248     }
249     if (!certificate.read) {
250         VLOG_ERR("Certificate must be configured to use SSL");
251         retval = ENOPROTOOPT;
252     }
253     if (!ca_cert.read && verify_peer_cert && !bootstrap_ca_cert) {
254         VLOG_ERR("CA certificate must be configured to use SSL");
255         retval = ENOPROTOOPT;
256     }
257     if (!retval && !SSL_CTX_check_private_key(ctx)) {
258         VLOG_ERR("Private key does not match certificate public key: %s",
259                  ERR_error_string(ERR_get_error(), NULL));
260         retval = ENOPROTOOPT;
261     }
262     if (retval) {
263         goto error;
264     }
265
266     /* Disable Nagle.
267      * On windows platforms, this can only be called upon TCP connected.
268      */
269     if (state == STATE_SSL_CONNECTING) {
270         retval = setsockopt_tcp_nodelay(fd);
271         if (retval) {
272             goto error;
273         }
274     }
275
276     /* Create and configure OpenSSL stream. */
277     ssl = SSL_new(ctx);
278     if (ssl == NULL) {
279         VLOG_ERR("SSL_new: %s", ERR_error_string(ERR_get_error(), NULL));
280         retval = ENOPROTOOPT;
281         goto error;
282     }
283     if (SSL_set_fd(ssl, fd) == 0) {
284         VLOG_ERR("SSL_set_fd: %s", ERR_error_string(ERR_get_error(), NULL));
285         retval = ENOPROTOOPT;
286         goto error;
287     }
288     if (!verify_peer_cert || (bootstrap_ca_cert && type == CLIENT)) {
289         SSL_set_verify(ssl, SSL_VERIFY_NONE, NULL);
290     }
291
292     /* Create and return the ssl_stream. */
293     sslv = xmalloc(sizeof *sslv);
294     stream_init(&sslv->stream, &ssl_stream_class, EAGAIN, name);
295     sslv->state = state;
296     sslv->type = type;
297     sslv->fd = fd;
298     sslv->ssl = ssl;
299     sslv->txbuf = NULL;
300     sslv->rx_want = sslv->tx_want = SSL_NOTHING;
301     sslv->session_nr = next_session_nr++;
302     sslv->n_head = 0;
303
304     if (VLOG_IS_DBG_ENABLED()) {
305         SSL_set_msg_callback(ssl, ssl_protocol_cb);
306         SSL_set_msg_callback_arg(ssl, sslv);
307     }
308
309     *streamp = &sslv->stream;
310     return 0;
311
312 error:
313     if (ssl) {
314         SSL_free(ssl);
315     }
316     closesocket(fd);
317     return retval;
318 }
319
320 static struct ssl_stream *
321 ssl_stream_cast(struct stream *stream)
322 {
323     stream_assert_class(stream, &ssl_stream_class);
324     return CONTAINER_OF(stream, struct ssl_stream, stream);
325 }
326
327 static int
328 ssl_open(const char *name, char *suffix, struct stream **streamp, uint8_t dscp)
329 {
330     int error, fd;
331
332     error = ssl_init();
333     if (error) {
334         return error;
335     }
336
337     error = inet_open_active(SOCK_STREAM, suffix, OFP_OLD_PORT, NULL, &fd,
338                              dscp);
339     if (fd >= 0) {
340         int state = error ? STATE_TCP_CONNECTING : STATE_SSL_CONNECTING;
341         return new_ssl_stream(name, fd, CLIENT, state, streamp);
342     } else {
343         VLOG_ERR("%s: connect: %s", name, ovs_strerror(error));
344         return error;
345     }
346 }
347
348 static int
349 do_ca_cert_bootstrap(struct stream *stream)
350 {
351     struct ssl_stream *sslv = ssl_stream_cast(stream);
352     STACK_OF(X509) *chain;
353     X509 *cert;
354     FILE *file;
355     int error;
356     int fd;
357
358     chain = SSL_get_peer_cert_chain(sslv->ssl);
359     if (!chain || !sk_X509_num(chain)) {
360         VLOG_ERR("could not bootstrap CA cert: no certificate presented by "
361                  "peer");
362         return EPROTO;
363     }
364     cert = sk_X509_value(chain, sk_X509_num(chain) - 1);
365
366     /* Check that 'cert' is self-signed.  Otherwise it is not a CA
367      * certificate and we should not attempt to use it as one. */
368     error = X509_check_issued(cert, cert);
369     if (error) {
370         VLOG_ERR("could not bootstrap CA cert: obtained certificate is "
371                  "not self-signed (%s)",
372                  X509_verify_cert_error_string(error));
373         if (sk_X509_num(chain) < 2) {
374             VLOG_ERR("only one certificate was received, so probably the peer "
375                      "is not configured to send its CA certificate");
376         }
377         return EPROTO;
378     }
379
380     fd = open(ca_cert.file_name, O_CREAT | O_EXCL | O_WRONLY, 0444);
381     if (fd < 0) {
382         if (errno == EEXIST) {
383             VLOG_INFO_RL(&rl, "reading CA cert %s created by another process",
384                          ca_cert.file_name);
385             stream_ssl_set_ca_cert_file__(ca_cert.file_name, true, true);
386             return EPROTO;
387         } else {
388             VLOG_ERR("could not bootstrap CA cert: creating %s failed: %s",
389                      ca_cert.file_name, ovs_strerror(errno));
390             return errno;
391         }
392     }
393
394     file = fdopen(fd, "w");
395     if (!file) {
396         error = errno;
397         VLOG_ERR("could not bootstrap CA cert: fdopen failed: %s",
398                  ovs_strerror(error));
399         unlink(ca_cert.file_name);
400         return error;
401     }
402
403     if (!PEM_write_X509(file, cert)) {
404         VLOG_ERR("could not bootstrap CA cert: PEM_write_X509 to %s failed: "
405                  "%s", ca_cert.file_name,
406                  ERR_error_string(ERR_get_error(), NULL));
407         fclose(file);
408         unlink(ca_cert.file_name);
409         return EIO;
410     }
411
412     if (fclose(file)) {
413         error = errno;
414         VLOG_ERR("could not bootstrap CA cert: writing %s failed: %s",
415                  ca_cert.file_name, ovs_strerror(error));
416         unlink(ca_cert.file_name);
417         return error;
418     }
419
420     VLOG_INFO("successfully bootstrapped CA cert to %s", ca_cert.file_name);
421     log_ca_cert(ca_cert.file_name, cert);
422     bootstrap_ca_cert = false;
423     ca_cert.read = true;
424
425     /* SSL_CTX_add_client_CA makes a copy of cert's relevant data. */
426     SSL_CTX_add_client_CA(ctx, cert);
427
428     /* SSL_CTX_use_certificate() takes ownership of the certificate passed in.
429      * 'cert' is owned by sslv->ssl, so we need to duplicate it. */
430     cert = X509_dup(cert);
431     if (!cert) {
432         out_of_memory();
433     }
434     SSL_CTX_set_cert_store(ctx, X509_STORE_new());
435     if (SSL_CTX_load_verify_locations(ctx, ca_cert.file_name, NULL) != 1) {
436         VLOG_ERR("SSL_CTX_load_verify_locations: %s",
437                  ERR_error_string(ERR_get_error(), NULL));
438         return EPROTO;
439     }
440     VLOG_INFO("killing successful connection to retry using CA cert");
441     return EPROTO;
442 }
443
444 static int
445 ssl_connect(struct stream *stream)
446 {
447     struct ssl_stream *sslv = ssl_stream_cast(stream);
448     int retval;
449
450     switch (sslv->state) {
451     case STATE_TCP_CONNECTING:
452         retval = check_connection_completion(sslv->fd);
453         if (retval) {
454             return retval;
455         }
456         sslv->state = STATE_SSL_CONNECTING;
457         retval = setsockopt_tcp_nodelay(sslv->fd);
458         if (retval) {
459             return retval;
460         }
461         /* Fall through. */
462
463     case STATE_SSL_CONNECTING:
464         /* Capture the first few bytes of received data so that we can guess
465          * what kind of funny data we've been sent if SSL negotiation fails. */
466         if (sslv->n_head <= 0) {
467             sslv->n_head = recv(sslv->fd, sslv->head, sizeof sslv->head,
468                                 MSG_PEEK);
469         }
470
471         retval = (sslv->type == CLIENT
472                    ? SSL_connect(sslv->ssl) : SSL_accept(sslv->ssl));
473         if (retval != 1) {
474             int error = SSL_get_error(sslv->ssl, retval);
475             if (retval < 0 && ssl_wants_io(error)) {
476                 return EAGAIN;
477             } else {
478                 int unused;
479
480                 interpret_ssl_error((sslv->type == CLIENT ? "SSL_connect"
481                                      : "SSL_accept"), retval, error, &unused);
482                 shutdown(sslv->fd, SHUT_RDWR);
483                 stream_report_content(sslv->head, sslv->n_head, STREAM_SSL,
484                                       THIS_MODULE, stream_get_name(stream));
485                 return EPROTO;
486             }
487         } else if (bootstrap_ca_cert) {
488             return do_ca_cert_bootstrap(stream);
489         } else if (verify_peer_cert
490                    && ((SSL_get_verify_mode(sslv->ssl)
491                        & (SSL_VERIFY_NONE | SSL_VERIFY_PEER))
492                        != SSL_VERIFY_PEER)) {
493             /* Two or more SSL connections completed at the same time while we
494              * were in bootstrap mode.  Only one of these can finish the
495              * bootstrap successfully.  The other one(s) must be rejected
496              * because they were not verified against the bootstrapped CA
497              * certificate.  (Alternatively we could verify them against the CA
498              * certificate, but that's more trouble than it's worth.  These
499              * connections will succeed the next time they retry, assuming that
500              * they have a certificate against the correct CA.) */
501             VLOG_INFO("rejecting SSL connection during bootstrap race window");
502             return EPROTO;
503         } else {
504             return 0;
505         }
506     }
507
508     OVS_NOT_REACHED();
509 }
510
511 static void
512 ssl_close(struct stream *stream)
513 {
514     struct ssl_stream *sslv = ssl_stream_cast(stream);
515     ssl_clear_txbuf(sslv);
516
517     /* Attempt clean shutdown of the SSL connection.  This will work most of
518      * the time, as long as the kernel send buffer has some free space and the
519      * SSL connection isn't renegotiating, etc.  That has to be good enough,
520      * since we don't have any way to continue the close operation in the
521      * background. */
522     SSL_shutdown(sslv->ssl);
523
524     /* SSL_shutdown() might have signaled an error, in which case we need to
525      * flush it out of the OpenSSL error queue or the next OpenSSL operation
526      * will falsely signal an error. */
527     ERR_clear_error();
528
529     SSL_free(sslv->ssl);
530     closesocket(sslv->fd);
531     free(sslv);
532 }
533
534 static void
535 interpret_queued_ssl_error(const char *function)
536 {
537     int queued_error = ERR_get_error();
538     if (queued_error != 0) {
539         VLOG_WARN_RL(&rl, "%s: %s",
540                      function, ERR_error_string(queued_error, NULL));
541     } else {
542         VLOG_ERR_RL(&rl, "%s: SSL_ERROR_SSL without queued error", function);
543     }
544 }
545
546 static int
547 interpret_ssl_error(const char *function, int ret, int error,
548                     int *want)
549 {
550     *want = SSL_NOTHING;
551
552     switch (error) {
553     case SSL_ERROR_NONE:
554         VLOG_ERR_RL(&rl, "%s: unexpected SSL_ERROR_NONE", function);
555         break;
556
557     case SSL_ERROR_ZERO_RETURN:
558         VLOG_ERR_RL(&rl, "%s: unexpected SSL_ERROR_ZERO_RETURN", function);
559         break;
560
561     case SSL_ERROR_WANT_READ:
562         *want = SSL_READING;
563         return EAGAIN;
564
565     case SSL_ERROR_WANT_WRITE:
566         *want = SSL_WRITING;
567         return EAGAIN;
568
569     case SSL_ERROR_WANT_CONNECT:
570         VLOG_ERR_RL(&rl, "%s: unexpected SSL_ERROR_WANT_CONNECT", function);
571         break;
572
573     case SSL_ERROR_WANT_ACCEPT:
574         VLOG_ERR_RL(&rl, "%s: unexpected SSL_ERROR_WANT_ACCEPT", function);
575         break;
576
577     case SSL_ERROR_WANT_X509_LOOKUP:
578         VLOG_ERR_RL(&rl, "%s: unexpected SSL_ERROR_WANT_X509_LOOKUP",
579                     function);
580         break;
581
582     case SSL_ERROR_SYSCALL: {
583         int queued_error = ERR_get_error();
584         if (queued_error == 0) {
585             if (ret < 0) {
586                 int status = errno;
587                 VLOG_WARN_RL(&rl, "%s: system error (%s)",
588                              function, ovs_strerror(status));
589                 return status;
590             } else {
591                 VLOG_WARN_RL(&rl, "%s: unexpected SSL connection close",
592                              function);
593                 return EPROTO;
594             }
595         } else {
596             VLOG_WARN_RL(&rl, "%s: %s",
597                          function, ERR_error_string(queued_error, NULL));
598             break;
599         }
600     }
601
602     case SSL_ERROR_SSL:
603         interpret_queued_ssl_error(function);
604         break;
605
606     default:
607         VLOG_ERR_RL(&rl, "%s: bad SSL error code %d", function, error);
608         break;
609     }
610     return EIO;
611 }
612
613 static ssize_t
614 ssl_recv(struct stream *stream, void *buffer, size_t n)
615 {
616     struct ssl_stream *sslv = ssl_stream_cast(stream);
617     int old_state;
618     ssize_t ret;
619
620     /* Behavior of zero-byte SSL_read is poorly defined. */
621     ovs_assert(n > 0);
622
623     old_state = SSL_get_state(sslv->ssl);
624     ret = SSL_read(sslv->ssl, buffer, n);
625     if (old_state != SSL_get_state(sslv->ssl)) {
626         sslv->tx_want = SSL_NOTHING;
627     }
628     sslv->rx_want = SSL_NOTHING;
629
630     if (ret > 0) {
631         return ret;
632     } else {
633         int error = SSL_get_error(sslv->ssl, ret);
634         if (error == SSL_ERROR_ZERO_RETURN) {
635             return 0;
636         } else {
637             return -interpret_ssl_error("SSL_read", ret, error,
638                                         &sslv->rx_want);
639         }
640     }
641 }
642
643 static void
644 ssl_clear_txbuf(struct ssl_stream *sslv)
645 {
646     ofpbuf_delete(sslv->txbuf);
647     sslv->txbuf = NULL;
648 }
649
650 static int
651 ssl_do_tx(struct stream *stream)
652 {
653     struct ssl_stream *sslv = ssl_stream_cast(stream);
654
655     for (;;) {
656         int old_state = SSL_get_state(sslv->ssl);
657         int ret = SSL_write(sslv->ssl,
658                             ofpbuf_data(sslv->txbuf), ofpbuf_size(sslv->txbuf));
659         if (old_state != SSL_get_state(sslv->ssl)) {
660             sslv->rx_want = SSL_NOTHING;
661         }
662         sslv->tx_want = SSL_NOTHING;
663         if (ret > 0) {
664             ofpbuf_pull(sslv->txbuf, ret);
665             if (ofpbuf_size(sslv->txbuf) == 0) {
666                 return 0;
667             }
668         } else {
669             int ssl_error = SSL_get_error(sslv->ssl, ret);
670             if (ssl_error == SSL_ERROR_ZERO_RETURN) {
671                 VLOG_WARN_RL(&rl, "SSL_write: connection closed");
672                 return EPIPE;
673             } else {
674                 return interpret_ssl_error("SSL_write", ret, ssl_error,
675                                            &sslv->tx_want);
676             }
677         }
678     }
679 }
680
681 static ssize_t
682 ssl_send(struct stream *stream, const void *buffer, size_t n)
683 {
684     struct ssl_stream *sslv = ssl_stream_cast(stream);
685
686     if (sslv->txbuf) {
687         return -EAGAIN;
688     } else {
689         int error;
690
691         sslv->txbuf = ofpbuf_clone_data(buffer, n);
692         error = ssl_do_tx(stream);
693         switch (error) {
694         case 0:
695             ssl_clear_txbuf(sslv);
696             return n;
697         case EAGAIN:
698             return n;
699         default:
700             sslv->txbuf = NULL;
701             return -error;
702         }
703     }
704 }
705
706 static void
707 ssl_run(struct stream *stream)
708 {
709     struct ssl_stream *sslv = ssl_stream_cast(stream);
710
711     if (sslv->txbuf && ssl_do_tx(stream) != EAGAIN) {
712         ssl_clear_txbuf(sslv);
713     }
714 }
715
716 static void
717 ssl_run_wait(struct stream *stream)
718 {
719     struct ssl_stream *sslv = ssl_stream_cast(stream);
720
721     if (sslv->tx_want != SSL_NOTHING) {
722         poll_fd_wait(sslv->fd, want_to_poll_events(sslv->tx_want));
723     }
724 }
725
726 static void
727 ssl_wait(struct stream *stream, enum stream_wait_type wait)
728 {
729     struct ssl_stream *sslv = ssl_stream_cast(stream);
730
731     switch (wait) {
732     case STREAM_CONNECT:
733         if (stream_connect(stream) != EAGAIN) {
734             poll_immediate_wake();
735         } else {
736             switch (sslv->state) {
737             case STATE_TCP_CONNECTING:
738                 poll_fd_wait(sslv->fd, POLLOUT);
739                 break;
740
741             case STATE_SSL_CONNECTING:
742                 /* ssl_connect() called SSL_accept() or SSL_connect(), which
743                  * set up the status that we test here. */
744                 poll_fd_wait(sslv->fd,
745                                want_to_poll_events(SSL_want(sslv->ssl)));
746                 break;
747
748             default:
749                 OVS_NOT_REACHED();
750             }
751         }
752         break;
753
754     case STREAM_RECV:
755         if (sslv->rx_want != SSL_NOTHING) {
756             poll_fd_wait(sslv->fd, want_to_poll_events(sslv->rx_want));
757         } else {
758             poll_immediate_wake();
759         }
760         break;
761
762     case STREAM_SEND:
763         if (!sslv->txbuf) {
764             /* We have room in our tx queue. */
765             poll_immediate_wake();
766         } else {
767             /* stream_run_wait() will do the right thing; don't bother with
768              * redundancy. */
769         }
770         break;
771
772     default:
773         OVS_NOT_REACHED();
774     }
775 }
776
777 const struct stream_class ssl_stream_class = {
778     "ssl",                      /* name */
779     true,                       /* needs_probes */
780     ssl_open,                   /* open */
781     ssl_close,                  /* close */
782     ssl_connect,                /* connect */
783     ssl_recv,                   /* recv */
784     ssl_send,                   /* send */
785     ssl_run,                    /* run */
786     ssl_run_wait,               /* run_wait */
787     ssl_wait,                   /* wait */
788 };
789 \f
790 /* Passive SSL. */
791
792 struct pssl_pstream
793 {
794     struct pstream pstream;
795     int fd;
796 };
797
798 const struct pstream_class pssl_pstream_class;
799
800 static struct pssl_pstream *
801 pssl_pstream_cast(struct pstream *pstream)
802 {
803     pstream_assert_class(pstream, &pssl_pstream_class);
804     return CONTAINER_OF(pstream, struct pssl_pstream, pstream);
805 }
806
807 static int
808 pssl_open(const char *name OVS_UNUSED, char *suffix, struct pstream **pstreamp,
809           uint8_t dscp)
810 {
811     char bound_name[SS_NTOP_BUFSIZE + 16];
812     char addrbuf[SS_NTOP_BUFSIZE];
813     struct sockaddr_storage ss;
814     struct pssl_pstream *pssl;
815     uint16_t port;
816     int retval;
817     int fd;
818
819     retval = ssl_init();
820     if (retval) {
821         return retval;
822     }
823
824     fd = inet_open_passive(SOCK_STREAM, suffix, OFP_OLD_PORT, &ss, dscp, true);
825     if (fd < 0) {
826         return -fd;
827     }
828
829     port = ss_get_port(&ss);
830     snprintf(bound_name, sizeof bound_name, "ptcp:%"PRIu16":%s",
831              port, ss_format_address(&ss, addrbuf, sizeof addrbuf));
832
833     pssl = xmalloc(sizeof *pssl);
834     pstream_init(&pssl->pstream, &pssl_pstream_class, bound_name);
835     pstream_set_bound_port(&pssl->pstream, htons(port));
836     pssl->fd = fd;
837     *pstreamp = &pssl->pstream;
838     return 0;
839 }
840
841 static void
842 pssl_close(struct pstream *pstream)
843 {
844     struct pssl_pstream *pssl = pssl_pstream_cast(pstream);
845     closesocket(pssl->fd);
846     free(pssl);
847 }
848
849 static int
850 pssl_accept(struct pstream *pstream, struct stream **new_streamp)
851 {
852     struct pssl_pstream *pssl = pssl_pstream_cast(pstream);
853     char name[SS_NTOP_BUFSIZE + 16];
854     char addrbuf[SS_NTOP_BUFSIZE];
855     struct sockaddr_storage ss;
856     socklen_t ss_len = sizeof ss;
857     int new_fd;
858     int error;
859
860     new_fd = accept(pssl->fd, (struct sockaddr *) &ss, &ss_len);
861     if (new_fd < 0) {
862         error = sock_errno();
863 #ifdef _WIN32
864         if (error == WSAEWOULDBLOCK) {
865             error = EAGAIN;
866         }
867 #endif
868         if (error != EAGAIN) {
869             VLOG_DBG_RL(&rl, "accept: %s", sock_strerror(error));
870         }
871         return error;
872     }
873
874     error = set_nonblocking(new_fd);
875     if (error) {
876         closesocket(new_fd);
877         return error;
878     }
879
880     snprintf(name, sizeof name, "tcp:%s:%"PRIu16,
881              ss_format_address(&ss, addrbuf, sizeof addrbuf),
882              ss_get_port(&ss));
883     return new_ssl_stream(name, new_fd, SERVER, STATE_SSL_CONNECTING,
884                           new_streamp);
885 }
886
887 static void
888 pssl_wait(struct pstream *pstream)
889 {
890     struct pssl_pstream *pssl = pssl_pstream_cast(pstream);
891     poll_fd_wait(pssl->fd, POLLIN);
892 }
893
894 static int
895 pssl_set_dscp(struct pstream *pstream, uint8_t dscp)
896 {
897     struct pssl_pstream *pssl = pssl_pstream_cast(pstream);
898     return set_dscp(pssl->fd, dscp);
899 }
900
901 const struct pstream_class pssl_pstream_class = {
902     "pssl",
903     true,
904     pssl_open,
905     pssl_close,
906     pssl_accept,
907     pssl_wait,
908     pssl_set_dscp,
909 };
910 \f
911 /*
912  * Returns true if OpenSSL error is WANT_READ or WANT_WRITE, indicating that
913  * OpenSSL is requesting that we call it back when the socket is ready for read
914  * or writing, respectively.
915  */
916 static bool
917 ssl_wants_io(int ssl_error)
918 {
919     return (ssl_error == SSL_ERROR_WANT_WRITE
920             || ssl_error == SSL_ERROR_WANT_READ);
921 }
922
923 static int
924 ssl_init(void)
925 {
926     static int init_status = -1;
927     if (init_status < 0) {
928         init_status = do_ssl_init();
929         ovs_assert(init_status >= 0);
930     }
931     return init_status;
932 }
933
934 static int
935 do_ssl_init(void)
936 {
937     SSL_METHOD *method;
938
939 #ifdef _WIN32
940     /* The following call is needed if we "#include <openssl/applink.c>". */
941     CRYPTO_malloc_init();
942 #endif
943     SSL_library_init();
944     SSL_load_error_strings();
945
946     if (!RAND_status()) {
947         /* We occasionally see OpenSSL fail to seed its random number generator
948          * in heavily loaded hypervisors.  I suspect the following scenario:
949          *
950          * 1. OpenSSL calls read() to get 32 bytes from /dev/urandom.
951          * 2. The kernel generates 10 bytes of randomness and copies it out.
952          * 3. A signal arrives (perhaps SIGALRM).
953          * 4. The kernel interrupts the system call to service the signal.
954          * 5. Userspace gets 10 bytes of entropy.
955          * 6. OpenSSL doesn't read again to get the final 22 bytes.  Therefore
956          *    OpenSSL doesn't have enough entropy to consider itself
957          *    initialized.
958          *
959          * The only part I'm not entirely sure about is #6, because the OpenSSL
960          * code is so hard to read. */
961         uint8_t seed[32];
962         int retval;
963
964         VLOG_WARN("OpenSSL random seeding failed, reseeding ourselves");
965
966         retval = get_entropy(seed, sizeof seed);
967         if (retval) {
968             VLOG_ERR("failed to obtain entropy (%s)",
969                      ovs_retval_to_string(retval));
970             return retval > 0 ? retval : ENOPROTOOPT;
971         }
972
973         RAND_seed(seed, sizeof seed);
974     }
975
976     /* OpenSSL has a bunch of "connection methods": SSLv2_method(),
977      * SSLv3_method(), TLSv1_method(), SSLv23_method(), ...  Most of these
978      * support exactly one version of SSL, e.g. TLSv1_method() supports TLSv1
979      * only, not any earlier *or later* version.  The only exception is
980      * SSLv23_method(), which in fact supports *any* version of SSL and TLS.
981      * We don't want SSLv2 or SSLv3 support, so we turn it off below with
982      * SSL_CTX_set_options().
983      *
984      * The cast is needed to avoid a warning with newer versions of OpenSSL in
985      * which SSLv23_method() returns a "const" pointer. */
986     method = CONST_CAST(SSL_METHOD *, SSLv23_method());
987     if (method == NULL) {
988         VLOG_ERR("TLSv1_method: %s", ERR_error_string(ERR_get_error(), NULL));
989         return ENOPROTOOPT;
990     }
991
992     ctx = SSL_CTX_new(method);
993     if (ctx == NULL) {
994         VLOG_ERR("SSL_CTX_new: %s", ERR_error_string(ERR_get_error(), NULL));
995         return ENOPROTOOPT;
996     }
997     SSL_CTX_set_options(ctx, SSL_OP_NO_SSLv2 | SSL_OP_NO_SSLv3);
998     SSL_CTX_set_tmp_dh_callback(ctx, tmp_dh_callback);
999     SSL_CTX_set_mode(ctx, SSL_MODE_ENABLE_PARTIAL_WRITE);
1000     SSL_CTX_set_mode(ctx, SSL_MODE_ACCEPT_MOVING_WRITE_BUFFER);
1001     SSL_CTX_set_verify(ctx, SSL_VERIFY_PEER | SSL_VERIFY_FAIL_IF_NO_PEER_CERT,
1002                        NULL);
1003
1004     return 0;
1005 }
1006
1007 static DH *
1008 tmp_dh_callback(SSL *ssl OVS_UNUSED, int is_export OVS_UNUSED, int keylength)
1009 {
1010     struct dh {
1011         int keylength;
1012         DH *dh;
1013         DH *(*constructor)(void);
1014     };
1015
1016     static struct dh dh_table[] = {
1017         {1024, NULL, get_dh1024},
1018         {2048, NULL, get_dh2048},
1019         {4096, NULL, get_dh4096},
1020     };
1021
1022     struct dh *dh;
1023
1024     for (dh = dh_table; dh < &dh_table[ARRAY_SIZE(dh_table)]; dh++) {
1025         if (dh->keylength == keylength) {
1026             if (!dh->dh) {
1027                 dh->dh = dh->constructor();
1028                 if (!dh->dh) {
1029                     out_of_memory();
1030                 }
1031             }
1032             return dh->dh;
1033         }
1034     }
1035     VLOG_ERR_RL(&rl, "no Diffie-Hellman parameters for key length %d",
1036                 keylength);
1037     return NULL;
1038 }
1039
1040 /* Returns true if SSL is at least partially configured. */
1041 bool
1042 stream_ssl_is_configured(void)
1043 {
1044     return private_key.file_name || certificate.file_name || ca_cert.file_name;
1045 }
1046
1047 static bool
1048 update_ssl_config(struct ssl_config_file *config, const char *file_name)
1049 {
1050     struct timespec mtime;
1051     int error;
1052
1053     if (ssl_init() || !file_name) {
1054         return false;
1055     }
1056
1057     /* If the file name hasn't changed and neither has the file contents, stop
1058      * here. */
1059     error = get_mtime(file_name, &mtime);
1060     if (error && error != ENOENT) {
1061         VLOG_ERR_RL(&rl, "%s: stat failed (%s)",
1062                     file_name, ovs_strerror(error));
1063     }
1064     if (config->file_name
1065         && !strcmp(config->file_name, file_name)
1066         && mtime.tv_sec == config->mtime.tv_sec
1067         && mtime.tv_nsec == config->mtime.tv_nsec) {
1068         return false;
1069     }
1070
1071     /* Update 'config'. */
1072     config->mtime = mtime;
1073     if (file_name != config->file_name) {
1074         free(config->file_name);
1075         config->file_name = xstrdup(file_name);
1076     }
1077     return true;
1078 }
1079
1080 static void
1081 stream_ssl_set_private_key_file__(const char *file_name)
1082 {
1083     if (SSL_CTX_use_PrivateKey_file(ctx, file_name, SSL_FILETYPE_PEM) == 1) {
1084         private_key.read = true;
1085     } else {
1086         VLOG_ERR("SSL_use_PrivateKey_file: %s",
1087                  ERR_error_string(ERR_get_error(), NULL));
1088     }
1089 }
1090
1091 void
1092 stream_ssl_set_private_key_file(const char *file_name)
1093 {
1094     if (update_ssl_config(&private_key, file_name)) {
1095         stream_ssl_set_private_key_file__(file_name);
1096     }
1097 }
1098
1099 static void
1100 stream_ssl_set_certificate_file__(const char *file_name)
1101 {
1102     if (SSL_CTX_use_certificate_chain_file(ctx, file_name) == 1) {
1103         certificate.read = true;
1104     } else {
1105         VLOG_ERR("SSL_use_certificate_file: %s",
1106                  ERR_error_string(ERR_get_error(), NULL));
1107     }
1108 }
1109
1110 void
1111 stream_ssl_set_certificate_file(const char *file_name)
1112 {
1113     if (update_ssl_config(&certificate, file_name)) {
1114         stream_ssl_set_certificate_file__(file_name);
1115     }
1116 }
1117
1118 /* Sets the private key and certificate files in one operation.  Use this
1119  * interface, instead of calling stream_ssl_set_private_key_file() and
1120  * stream_ssl_set_certificate_file() individually, in the main loop of a
1121  * long-running program whose key and certificate might change at runtime.
1122  *
1123  * This is important because of OpenSSL's behavior.  If an OpenSSL context
1124  * already has a certificate, and stream_ssl_set_private_key_file() is called
1125  * to install a new private key, OpenSSL will report an error because the new
1126  * private key does not match the old certificate.  The other order, of setting
1127  * a new certificate, then setting a new private key, does work.
1128  *
1129  * If this were the only problem, calling stream_ssl_set_certificate_file()
1130  * before stream_ssl_set_private_key_file() would fix it.  But, if the private
1131  * key is changed before the certificate (e.g. someone "scp"s or "mv"s the new
1132  * private key in place before the certificate), then OpenSSL would reject that
1133  * change, and then the change of certificate would succeed, but there would be
1134  * no associated private key (because it had only changed once and therefore
1135  * there was no point in re-reading it).
1136  *
1137  * This function avoids both problems by, whenever either the certificate or
1138  * the private key file changes, re-reading both of them, in the correct order.
1139  */
1140 void
1141 stream_ssl_set_key_and_cert(const char *private_key_file,
1142                             const char *certificate_file)
1143 {
1144     if (update_ssl_config(&private_key, private_key_file)
1145         || update_ssl_config(&certificate, certificate_file)) {
1146         stream_ssl_set_certificate_file__(certificate_file);
1147         stream_ssl_set_private_key_file__(private_key_file);
1148     }
1149 }
1150
1151 /* Reads the X509 certificate or certificates in file 'file_name'.  On success,
1152  * stores the address of the first element in an array of pointers to
1153  * certificates in '*certs' and the number of certificates in the array in
1154  * '*n_certs', and returns 0.  On failure, stores a null pointer in '*certs', 0
1155  * in '*n_certs', and returns a positive errno value.
1156  *
1157  * The caller is responsible for freeing '*certs'. */
1158 static int
1159 read_cert_file(const char *file_name, X509 ***certs, size_t *n_certs)
1160 {
1161     FILE *file;
1162     size_t allocated_certs = 0;
1163
1164     *certs = NULL;
1165     *n_certs = 0;
1166
1167     file = fopen(file_name, "r");
1168     if (!file) {
1169         VLOG_ERR("failed to open %s for reading: %s",
1170                  file_name, ovs_strerror(errno));
1171         return errno;
1172     }
1173
1174     for (;;) {
1175         X509 *certificate;
1176         int c;
1177
1178         /* Read certificate from file. */
1179         certificate = PEM_read_X509(file, NULL, NULL, NULL);
1180         if (!certificate) {
1181             size_t i;
1182
1183             VLOG_ERR("PEM_read_X509 failed reading %s: %s",
1184                      file_name, ERR_error_string(ERR_get_error(), NULL));
1185             for (i = 0; i < *n_certs; i++) {
1186                 X509_free((*certs)[i]);
1187             }
1188             free(*certs);
1189             *certs = NULL;
1190             *n_certs = 0;
1191             fclose(file);
1192             return EIO;
1193         }
1194
1195         /* Add certificate to array. */
1196         if (*n_certs >= allocated_certs) {
1197             *certs = x2nrealloc(*certs, &allocated_certs, sizeof **certs);
1198         }
1199         (*certs)[(*n_certs)++] = certificate;
1200
1201         /* Are there additional certificates in the file? */
1202         do {
1203             c = getc(file);
1204         } while (isspace(c));
1205         if (c == EOF) {
1206             break;
1207         }
1208         ungetc(c, file);
1209     }
1210     fclose(file);
1211     return 0;
1212 }
1213
1214
1215 /* Sets 'file_name' as the name of a file containing one or more X509
1216  * certificates to send to the peer.  Typical use in OpenFlow is to send the CA
1217  * certificate to the peer, which enables a switch to pick up the controller's
1218  * CA certificate on its first connection. */
1219 void
1220 stream_ssl_set_peer_ca_cert_file(const char *file_name)
1221 {
1222     X509 **certs;
1223     size_t n_certs;
1224     size_t i;
1225
1226     if (ssl_init()) {
1227         return;
1228     }
1229
1230     if (!read_cert_file(file_name, &certs, &n_certs)) {
1231         for (i = 0; i < n_certs; i++) {
1232             if (SSL_CTX_add_extra_chain_cert(ctx, certs[i]) != 1) {
1233                 VLOG_ERR("SSL_CTX_add_extra_chain_cert: %s",
1234                          ERR_error_string(ERR_get_error(), NULL));
1235             }
1236         }
1237         free(certs);
1238     }
1239 }
1240
1241 /* Logs fingerprint of CA certificate 'cert' obtained from 'file_name'. */
1242 static void
1243 log_ca_cert(const char *file_name, X509 *cert)
1244 {
1245     unsigned char digest[EVP_MAX_MD_SIZE];
1246     unsigned int n_bytes;
1247     struct ds fp;
1248     char *subject;
1249
1250     ds_init(&fp);
1251     if (!X509_digest(cert, EVP_sha1(), digest, &n_bytes)) {
1252         ds_put_cstr(&fp, "<out of memory>");
1253     } else {
1254         unsigned int i;
1255         for (i = 0; i < n_bytes; i++) {
1256             if (i) {
1257                 ds_put_char(&fp, ':');
1258             }
1259             ds_put_format(&fp, "%02x", digest[i]);
1260         }
1261     }
1262     subject = X509_NAME_oneline(X509_get_subject_name(cert), NULL, 0);
1263     VLOG_INFO("Trusting CA cert from %s (%s) (fingerprint %s)", file_name,
1264               subject ? subject : "<out of memory>", ds_cstr(&fp));
1265     OPENSSL_free(subject);
1266     ds_destroy(&fp);
1267 }
1268
1269 static void
1270 stream_ssl_set_ca_cert_file__(const char *file_name,
1271                               bool bootstrap, bool force)
1272 {
1273     X509 **certs;
1274     size_t n_certs;
1275     struct stat s;
1276
1277     if (!update_ssl_config(&ca_cert, file_name) && !force) {
1278         return;
1279     }
1280
1281     if (!strcmp(file_name, "none")) {
1282         verify_peer_cert = false;
1283         VLOG_WARN("Peer certificate validation disabled "
1284                   "(this is a security risk)");
1285     } else if (bootstrap && stat(file_name, &s) && errno == ENOENT) {
1286         bootstrap_ca_cert = true;
1287     } else if (!read_cert_file(file_name, &certs, &n_certs)) {
1288         size_t i;
1289
1290         /* Set up list of CAs that the server will accept from the client. */
1291         for (i = 0; i < n_certs; i++) {
1292             /* SSL_CTX_add_client_CA makes a copy of the relevant data. */
1293             if (SSL_CTX_add_client_CA(ctx, certs[i]) != 1) {
1294                 VLOG_ERR("failed to add client certificate %"PRIuSIZE" from %s: %s",
1295                          i, file_name,
1296                          ERR_error_string(ERR_get_error(), NULL));
1297             } else {
1298                 log_ca_cert(file_name, certs[i]);
1299             }
1300             X509_free(certs[i]);
1301         }
1302         free(certs);
1303
1304         /* Set up CAs for OpenSSL to trust in verifying the peer's
1305          * certificate. */
1306         SSL_CTX_set_cert_store(ctx, X509_STORE_new());
1307         if (SSL_CTX_load_verify_locations(ctx, file_name, NULL) != 1) {
1308             VLOG_ERR("SSL_CTX_load_verify_locations: %s",
1309                      ERR_error_string(ERR_get_error(), NULL));
1310             return;
1311         }
1312
1313         bootstrap_ca_cert = false;
1314     }
1315     ca_cert.read = true;
1316 }
1317
1318 /* Sets 'file_name' as the name of the file from which to read the CA
1319  * certificate used to verify the peer within SSL connections.  If 'bootstrap'
1320  * is false, the file must exist.  If 'bootstrap' is false, then the file is
1321  * read if it is exists; if it does not, then it will be created from the CA
1322  * certificate received from the peer on the first SSL connection. */
1323 void
1324 stream_ssl_set_ca_cert_file(const char *file_name, bool bootstrap)
1325 {
1326     stream_ssl_set_ca_cert_file__(file_name, bootstrap, false);
1327 }
1328 \f
1329 /* SSL protocol logging. */
1330
1331 static const char *
1332 ssl_alert_level_to_string(uint8_t type)
1333 {
1334     switch (type) {
1335     case 1: return "warning";
1336     case 2: return "fatal";
1337     default: return "<unknown>";
1338     }
1339 }
1340
1341 static const char *
1342 ssl_alert_description_to_string(uint8_t type)
1343 {
1344     switch (type) {
1345     case 0: return "close_notify";
1346     case 10: return "unexpected_message";
1347     case 20: return "bad_record_mac";
1348     case 21: return "decryption_failed";
1349     case 22: return "record_overflow";
1350     case 30: return "decompression_failure";
1351     case 40: return "handshake_failure";
1352     case 42: return "bad_certificate";
1353     case 43: return "unsupported_certificate";
1354     case 44: return "certificate_revoked";
1355     case 45: return "certificate_expired";
1356     case 46: return "certificate_unknown";
1357     case 47: return "illegal_parameter";
1358     case 48: return "unknown_ca";
1359     case 49: return "access_denied";
1360     case 50: return "decode_error";
1361     case 51: return "decrypt_error";
1362     case 60: return "export_restriction";
1363     case 70: return "protocol_version";
1364     case 71: return "insufficient_security";
1365     case 80: return "internal_error";
1366     case 90: return "user_canceled";
1367     case 100: return "no_renegotiation";
1368     default: return "<unknown>";
1369     }
1370 }
1371
1372 static const char *
1373 ssl_handshake_type_to_string(uint8_t type)
1374 {
1375     switch (type) {
1376     case 0: return "hello_request";
1377     case 1: return "client_hello";
1378     case 2: return "server_hello";
1379     case 11: return "certificate";
1380     case 12: return "server_key_exchange";
1381     case 13: return "certificate_request";
1382     case 14: return "server_hello_done";
1383     case 15: return "certificate_verify";
1384     case 16: return "client_key_exchange";
1385     case 20: return "finished";
1386     default: return "<unknown>";
1387     }
1388 }
1389
1390 static void
1391 ssl_protocol_cb(int write_p, int version OVS_UNUSED, int content_type,
1392                 const void *buf_, size_t len, SSL *ssl OVS_UNUSED, void *sslv_)
1393 {
1394     const struct ssl_stream *sslv = sslv_;
1395     const uint8_t *buf = buf_;
1396     struct ds details;
1397
1398     if (!VLOG_IS_DBG_ENABLED()) {
1399         return;
1400     }
1401
1402     ds_init(&details);
1403     if (content_type == 20) {
1404         ds_put_cstr(&details, "change_cipher_spec");
1405     } else if (content_type == 21) {
1406         ds_put_format(&details, "alert: %s, %s",
1407                       ssl_alert_level_to_string(buf[0]),
1408                       ssl_alert_description_to_string(buf[1]));
1409     } else if (content_type == 22) {
1410         ds_put_format(&details, "handshake: %s",
1411                       ssl_handshake_type_to_string(buf[0]));
1412     } else {
1413         ds_put_format(&details, "type %d", content_type);
1414     }
1415
1416     VLOG_DBG("%s%u%s%s %s (%"PRIuSIZE" bytes)",
1417              sslv->type == CLIENT ? "client" : "server",
1418              sslv->session_nr, write_p ? "-->" : "<--",
1419              stream_get_name(&sslv->stream), ds_cstr(&details), len);
1420
1421     ds_destroy(&details);
1422 }