[Spice-devel] [spice-server v3 6/8] Use spice_debug rather than spice_info in library code

Christophe Fergeau cfergeau at redhat.com
Mon Feb 6 17:15:54 UTC 2017


Using spice_info() gets in the way of tests using
g_test_expect_message() as all the messages emitted using
a non-debug log level must be listed as expected, otherwise we get a
critical about an expected message not having been logged.
---
 server/cursor-channel.c     |  4 +--
 server/dcc.c                |  6 ++--
 server/display-channel.c    | 24 ++++++-------
 server/image-encoders.c     | 18 +++++-----
 server/red-channel-client.c |  4 +--
 server/red-worker.c         | 34 +++++++++---------
 server/reds-stream.c        | 40 ++++++++++-----------
 server/reds.c               | 86 ++++++++++++++++++++++-----------------------
 server/sound.c              |  2 +-
 9 files changed, 109 insertions(+), 109 deletions(-)

diff --git a/server/cursor-channel.c b/server/cursor-channel.c
index b3cbb96..c1254b7 100644
--- a/server/cursor-channel.c
+++ b/server/cursor-channel.c
@@ -294,7 +294,7 @@ static void cursor_channel_send_item(RedChannelClient *rcc, RedPipeItem *pipe_it
 CursorChannel* cursor_channel_new(RedsState *server, QXLInstance *qxl,
                                   const SpiceCoreInterfaceInternal *core)
 {
-    spice_info("create cursor channel");
+    spice_debug("create cursor channel");
     return g_object_new(TYPE_CURSOR_CHANNEL,
                         "spice-server", server,
                         "core-interface", core,
@@ -413,7 +413,7 @@ void cursor_channel_connect(CursorChannel *cursor, RedClient *client, RedsStream
 
     spice_return_if_fail(cursor != NULL);
 
-    spice_info("add cursor channel client");
+    spice_debug("add cursor channel client");
     ccc = cursor_channel_client_new(cursor, client, stream,
                                     migrate,
                                     common_caps, num_common_caps,
diff --git a/server/dcc.c b/server/dcc.c
index afe37b1..3c8e24d 100644
--- a/server/dcc.c
+++ b/server/dcc.c
@@ -514,7 +514,7 @@ DisplayChannelClient *dcc_new(DisplayChannel *display,
                          "jpeg-state", jpeg_state,
                          "zlib-glz-state", zlib_glz_state,
                          NULL);
-    spice_info("New display (client %p) dcc %p stream %p", client, dcc, stream);
+    spice_debug("New display (client %p) dcc %p stream %p", client, dcc, stream);
     common_graphics_channel_set_during_target_migrate(COMMON_GRAPHICS_CHANNEL(display), mig_target);
     dcc->priv->id = common_graphics_channel_get_qxl(COMMON_GRAPHICS_CHANNEL(display))->id;
 
@@ -550,7 +550,7 @@ static int display_channel_client_wait_for_init(DisplayChannelClient *dcc)
         if (dcc->priv->pixmap_cache && dcc->priv->encoders.glz_dict) {
             dcc->priv->pixmap_cache_generation = dcc->priv->pixmap_cache->generation;
             /* TODO: move common.id? if it's used for a per client structure.. */
-            spice_info("creating encoder with id == %d", dcc->priv->id);
+            spice_debug("creating encoder with id == %d", dcc->priv->id);
             if (!image_encoders_glz_create(&dcc->priv->encoders, dcc->priv->id)) {
                 spice_critical("create global lz failed");
             }
@@ -1052,7 +1052,7 @@ static int dcc_handle_stream_report(DisplayChannelClient *dcc,
 
     agent = &dcc->priv->stream_agents[report->stream_id];
     if (!agent->video_encoder) {
-        spice_info("stream_report: no encoder for stream id %u. "
+        spice_debug("stream_report: no encoder for stream id %u. "
                    "The stream has probably been destroyed",
                    report->stream_id);
         return TRUE;
diff --git a/server/display-channel.c b/server/display-channel.c
index 7d3c6e4..bb1efe4 100644
--- a/server/display-channel.c
+++ b/server/display-channel.c
@@ -114,7 +114,7 @@ void display_channel_compress_stats_print(DisplayChannel *display_channel)
 
     g_object_get(display_channel, "id", &id, NULL);
 
-    spice_info("==> Compression stats for display %u", id);
+    spice_debug("==> Compression stats for display %u", id);
     image_encoder_shared_stat_print(&display_channel->priv->encoder_shared_data);
 #endif
 }
@@ -192,13 +192,13 @@ void display_channel_set_stream_video(DisplayChannel *display, int stream_video)
 
     switch (stream_video) {
     case SPICE_STREAM_VIDEO_ALL:
-        spice_info("sv all");
+        spice_debug("sv all");
         break;
     case SPICE_STREAM_VIDEO_FILTER:
-        spice_info("sv filter");
+        spice_debug("sv filter");
         break;
     case SPICE_STREAM_VIDEO_OFF:
-        spice_info("sv off");
+        spice_debug("sv off");
         break;
     default:
         spice_warn_if_reached();
@@ -239,7 +239,7 @@ static void stop_streams(DisplayChannel *display)
         if (!stream->current) {
             stream_stop(display, stream);
         } else {
-            spice_info("attached stream");
+            spice_debug("attached stream");
         }
     }
 
@@ -883,17 +883,17 @@ static bool drawable_can_stream(DisplayChannel *display, Drawable *drawable)
 static void display_channel_print_stats(DisplayChannel *display)
 {
     stat_time_t total = display->priv->add_stat.total;
-    spice_info("add with shadow count %u",
+    spice_debug("add with shadow count %u",
                display->priv->add_with_shadow_count);
     display->priv->add_with_shadow_count = 0;
-    spice_info("add[%u] %f exclude[%u] %f __exclude[%u] %f",
+    spice_debug("add[%u] %f exclude[%u] %f __exclude[%u] %f",
                display->priv->add_stat.count,
                stat_cpu_time_to_sec(total),
                display->priv->exclude_stat.count,
                stat_cpu_time_to_sec(display->priv->exclude_stat.total),
                display->priv->__exclude_stat.count,
                stat_cpu_time_to_sec(display->priv->__exclude_stat.total));
-    spice_info("add %f%% exclude %f%% exclude2 %f%% __exclude %f%%",
+    spice_debug("add %f%% exclude %f%% exclude2 %f%% __exclude %f%%",
                (double)(total - display->priv->exclude_stat.total) / total * 100,
                (double)(display->priv->exclude_stat.total) / total * 100,
                (double)(display->priv->exclude_stat.total -
@@ -1931,7 +1931,7 @@ static void on_disconnect(RedChannelClient *rcc)
     DisplayChannel *display;
     DisplayChannelClient *dcc;
 
-    spice_info(NULL);
+    spice_debug(NULL);
     spice_return_if_fail(rcc != NULL);
 
     dcc = DISPLAY_CHANNEL_CLIENT(rcc);
@@ -1988,7 +1988,7 @@ DisplayChannel* display_channel_new(RedsState *reds,
     DisplayChannel *display;
 
     /* FIXME: migrate is not used...? */
-    spice_info("create display channel");
+    spice_debug("create display channel");
     display = g_object_new(TYPE_DISPLAY_CHANNEL,
                            "spice-server", reds,
                            "core-interface", core,
@@ -2130,8 +2130,8 @@ void display_channel_update_compression(DisplayChannel *display, DisplayChannelC
     } else {
         display->priv->enable_zlib_glz_wrap = (dcc_get_zlib_glz_state(dcc) == SPICE_WAN_COMPRESSION_ALWAYS);
     }
-    spice_info("jpeg %s", display->priv->enable_jpeg ? "enabled" : "disabled");
-    spice_info("zlib-over-glz %s", display->priv->enable_zlib_glz_wrap ? "enabled" : "disabled");
+    spice_debug("jpeg %s", display->priv->enable_jpeg ? "enabled" : "disabled");
+    spice_debug("zlib-over-glz %s", display->priv->enable_zlib_glz_wrap ? "enabled" : "disabled");
 }
 
 void display_channel_gl_scanout(DisplayChannel *display)
diff --git a/server/image-encoders.c b/server/image-encoders.c
index 0d57260..52742ae 100644
--- a/server/image-encoders.c
+++ b/server/image-encoders.c
@@ -723,7 +723,7 @@ static GlzSharedDictionary *create_glz_dictionary(ImageEncoders *enc,
                                                   RedClient *client,
                                                   uint8_t id, int window_size)
 {
-    spice_info("Lz Window %d Size=%d", id, window_size);
+    spice_debug("Lz Window %d Size=%d", id, window_size);
 
     GlzEncDictContext *glz_dict =
         glz_enc_dictionary_create(window_size, MAX_LZ_ENCODERS, &enc->glz_data.usr);
@@ -838,7 +838,7 @@ int image_encoders_compress_quic(ImageEncoders *enc, SpiceImage *dest,
     stat_start_time_init(&start_time, &enc->shared_data->quic_stat);
 
 #ifdef COMPRESS_DEBUG
-    spice_info("QUIC compress");
+    spice_debug("QUIC compress");
 #endif
 
     switch (src->format) {
@@ -927,7 +927,7 @@ int image_encoders_compress_lz(ImageEncoders *enc,
     stat_start_time_init(&start_time, &enc->shared_data->lz_stat);
 
 #ifdef COMPRESS_DEBUG
-    spice_info("LZ LOCAL compress");
+    spice_debug("LZ LOCAL compress");
 #endif
 
     encoder_data_init(&lz_data->data);
@@ -998,7 +998,7 @@ int image_encoders_compress_jpeg(ImageEncoders *enc, SpiceImage *dest,
     stat_start_time_init(&start_time, &enc->shared_data->jpeg_alpha_stat);
 
 #ifdef COMPRESS_DEBUG
-    spice_info("JPEG compress");
+    spice_debug("JPEG compress");
 #endif
 
     switch (src->format) {
@@ -1115,7 +1115,7 @@ int image_encoders_compress_lz4(ImageEncoders *enc, SpiceImage *dest,
     stat_start_time_init(&start_time, &enc->shared_data->lz4_stat);
 
 #ifdef COMPRESS_DEBUG
-    spice_info("LZ4 compress");
+    spice_debug("LZ4 compress");
 #endif
 
     encoder_data_init(&lz4_data->data);
@@ -1227,7 +1227,7 @@ int image_encoders_compress_glz(ImageEncoders *enc,
     int zlib_size;
 
 #ifdef COMPRESS_DEBUG
-    spice_info("LZ global compress fmt=%d", src->format);
+    spice_debug("LZ global compress fmt=%d", src->format);
 #endif
 
     if ((src->x * src->y) >= glz_enc_dictionary_get_size(enc->glz_dict->dict)) {
@@ -1337,7 +1337,7 @@ void image_encoder_shared_stat_reset(ImageEncoderSharedData *shared_data)
 #ifdef COMPRESS_STAT
 static void stat_print_one(const char *name, const stat_info_t *stat)
 {
-    spice_info(STAT_FMT, name, stat->count,
+    spice_debug(STAT_FMT, name, stat->count,
                stat_byte_to_mega(stat->orig_size),
                stat_byte_to_mega(stat->comp_size),
                stat_cpu_time_to_sec(stat->total));
@@ -1377,7 +1377,7 @@ void image_encoder_shared_stat_print(const ImageEncoderSharedData *shared_data)
                           shared_data->zlib_glz_stat.comp_size;
     }
 
-    spice_info("Method   \t  count  \torig_size(MB)\tenc_size(MB)\tenc_time(s)");
+    spice_debug("Method   \t  count  \torig_size(MB)\tenc_size(MB)\tenc_time(s)");
     stat_print_one("OFF      ", &shared_data->off_stat);
     stat_print_one("QUIC     ", &shared_data->quic_stat);
     stat_print_one("GLZ      ", &shared_data->glz_stat);
@@ -1386,7 +1386,7 @@ void image_encoder_shared_stat_print(const ImageEncoderSharedData *shared_data)
     stat_print_one("JPEG     ", &shared_data->jpeg_stat);
     stat_print_one("JPEG-RGBA", &shared_data->jpeg_alpha_stat);
     stat_print_one("LZ4      ", &shared_data->lz4_stat);
-    spice_info("-------------------------------------------------------------------");
+    spice_debug("-------------------------------------------------------------------");
     stat_print_one("Total    ", &total);
 #endif
 }
diff --git a/server/red-channel-client.c b/server/red-channel-client.c
index 0002951..342f137 100644
--- a/server/red-channel-client.c
+++ b/server/red-channel-client.c
@@ -1692,7 +1692,7 @@ int red_channel_client_wait_pipe_item_sent(RedChannelClient *rcc,
     uint64_t end_time;
     gboolean item_in_pipe;
 
-    spice_info(NULL);
+    spice_debug(NULL);
 
     if (timeout != -1) {
         end_time = spice_get_monotonic_time_ns() + timeout;
@@ -1747,7 +1747,7 @@ int red_channel_client_wait_outgoing_item(RedChannelClient *rcc,
     } else {
         end_time = UINT64_MAX;
     }
-    spice_info("blocked");
+    spice_debug("blocked");
 
     do {
         usleep(CHANNEL_BLOCKED_SLEEP_DURATION);
diff --git a/server/red-worker.c b/server/red-worker.c
index 475acc4..ecba2ae 100644
--- a/server/red-worker.c
+++ b/server/red-worker.c
@@ -615,7 +615,7 @@ static void handle_dev_stop(void *opaque, void *payload)
 {
     RedWorker *worker = opaque;
 
-    spice_info("stop");
+    spice_debug("stop");
     spice_assert(worker->running);
 
     worker->running = FALSE;
@@ -734,7 +734,7 @@ static void handle_dev_display_connect(void *opaque, void *payload)
     DisplayChannel *display = worker->display_channel;
     DisplayChannelClient *dcc;
 
-    spice_info("connect new client");
+    spice_debug("connect new client");
     spice_return_if_fail(display);
 
     dcc = dcc_new(display, msg->client, msg->stream, msg->migration,
@@ -757,7 +757,7 @@ static void handle_dev_display_disconnect(void *opaque, void *payload)
     RedChannelClient *rcc = msg->rcc;
     RedWorker *worker = opaque;
 
-    spice_info("disconnect display client");
+    spice_debug("disconnect display client");
     spice_assert(rcc);
 
     guest_set_client_capabilities(worker);
@@ -771,7 +771,7 @@ static void handle_dev_display_migrate(void *opaque, void *payload)
     RedWorker *worker = opaque;
 
     RedChannelClient *rcc = msg->rcc;
-    spice_info("migrate display client");
+    spice_debug("migrate display client");
     spice_assert(rcc);
     red_migrate_display(worker->display_channel, rcc);
 }
@@ -831,7 +831,7 @@ static void handle_dev_cursor_connect(void *opaque, void *payload)
     RedWorkerMessageCursorConnect *msg = payload;
     RedWorker *worker = opaque;
 
-    spice_info("cursor connect");
+    spice_debug("cursor connect");
     cursor_channel_connect(worker->cursor_channel,
                            msg->client, msg->stream, msg->migration,
                            msg->common_caps, msg->num_common_caps,
@@ -845,7 +845,7 @@ static void handle_dev_cursor_disconnect(void *opaque, void *payload)
     RedWorkerMessageCursorDisconnect *msg = payload;
     RedChannelClient *rcc = msg->rcc;
 
-    spice_info("disconnect cursor client");
+    spice_debug("disconnect cursor client");
     spice_return_if_fail(rcc);
     red_channel_client_disconnect(rcc);
 }
@@ -855,7 +855,7 @@ static void handle_dev_cursor_migrate(void *opaque, void *payload)
     RedWorkerMessageCursorMigrate *msg = payload;
     RedChannelClient *rcc = msg->rcc;
 
-    spice_info("migrate cursor client");
+    spice_debug("migrate cursor client");
     cursor_channel_client_migrate(rcc);
 }
 
@@ -867,27 +867,27 @@ static void handle_dev_set_compression(void *opaque, void *payload)
 
     switch (image_compression) {
     case SPICE_IMAGE_COMPRESSION_AUTO_LZ:
-        spice_info("ic auto_lz");
+        spice_debug("ic auto_lz");
         break;
     case SPICE_IMAGE_COMPRESSION_AUTO_GLZ:
-        spice_info("ic auto_glz");
+        spice_debug("ic auto_glz");
         break;
     case SPICE_IMAGE_COMPRESSION_QUIC:
-        spice_info("ic quic");
+        spice_debug("ic quic");
         break;
 #ifdef USE_LZ4
     case SPICE_IMAGE_COMPRESSION_LZ4:
-        spice_info("ic lz4");
+        spice_debug("ic lz4");
         break;
 #endif
     case SPICE_IMAGE_COMPRESSION_LZ:
-        spice_info("ic lz");
+        spice_debug("ic lz");
         break;
     case SPICE_IMAGE_COMPRESSION_GLZ:
-        spice_info("ic glz");
+        spice_debug("ic glz");
         break;
     case SPICE_IMAGE_COMPRESSION_OFF:
-        spice_info("ic off");
+        spice_debug("ic off");
         break;
     default:
         spice_warning("ic invalid");
@@ -921,7 +921,7 @@ static void handle_dev_set_mouse_mode(void *opaque, void *payload)
     RedWorkerMessageSetMouseMode *msg = payload;
     RedWorker *worker = opaque;
 
-    spice_info("mouse mode %u", msg->mode);
+    spice_debug("mouse mode %u", msg->mode);
     cursor_channel_set_mouse_mode(worker->cursor_channel, msg->mode);
 }
 
@@ -1012,7 +1012,7 @@ static void handle_dev_loadvm_commands(void *opaque, void *payload)
     uint32_t count = msg->count;
     QXLCommandExt *ext = msg->ext;
 
-    spice_info("loadvm_commands");
+    spice_debug("loadvm_commands");
     for (i = 0 ; i < count ; ++i) {
         if (!loadvm_command(worker, &ext[i])) {
             /* XXX allow failure in loadvm? */
@@ -1394,7 +1394,7 @@ static void *red_worker_main(void *arg)
 {
     RedWorker *worker = arg;
 
-    spice_info("begin");
+    spice_debug("begin");
     SPICE_VERIFY(MAX_PIPE_SIZE > WIDE_CLIENT_ACK_WINDOW &&
            MAX_PIPE_SIZE > NARROW_CLIENT_ACK_WINDOW); //ensure wakeup by ack message
 
diff --git a/server/reds-stream.c b/server/reds-stream.c
index d0dadb9..471989e 100644
--- a/server/reds-stream.c
+++ b/server/reds-stream.c
@@ -342,7 +342,7 @@ void reds_stream_free(RedsStream *s)
     }
 
     reds_stream_remove_watch(s);
-    spice_info("close socket fd %d", s->socket);
+    spice_debug("close socket fd %d", s->socket);
     close(s->socket);
 
     free(s);
@@ -699,7 +699,7 @@ static int auth_sasl_check_ssf(RedsSASL *sasl, int *runSSF)
     }
 
     ssf = *(const int *)val;
-    spice_info("negotiated an SSF of %d", ssf);
+    spice_debug("negotiated an SSF of %d", ssf);
     if (ssf < 56) {
         return 0; /* 56 is good for Kerberos */
     }
@@ -742,7 +742,7 @@ RedsSaslError reds_sasl_handle_auth_step(RedsStream *stream, AsyncReadDone read_
         datalen--; /* Don't count NULL byte when passing to _start() */
     }
 
-    spice_info("Step using SASL Data %p (%d bytes)",
+    spice_debug("Step using SASL Data %p (%d bytes)",
                clientdata, datalen);
     err = sasl_server_step(sasl->conn,
                            clientdata,
@@ -762,7 +762,7 @@ RedsSaslError reds_sasl_handle_auth_step(RedsStream *stream, AsyncReadDone read_
         return REDS_SASL_ERROR_INVALID_DATA;
     }
 
-    spice_info("SASL return data %d bytes, %p", serveroutlen, serverout);
+    spice_debug("SASL return data %d bytes, %p", serveroutlen, serverout);
 
     if (serveroutlen) {
         serveroutlen += 1;
@@ -776,7 +776,7 @@ RedsSaslError reds_sasl_handle_auth_step(RedsStream *stream, AsyncReadDone read_
     reds_stream_write_u8(stream, err == SASL_CONTINUE ? 0 : 1);
 
     if (err == SASL_CONTINUE) {
-        spice_info("%s", "Authentication must continue (step)");
+        spice_debug("%s", "Authentication must continue (step)");
         /* Wait for step length */
         reds_stream_async_read(stream, (uint8_t *)&sasl->len, sizeof(uint32_t),
                                read_cb, opaque);
@@ -789,7 +789,7 @@ RedsSaslError reds_sasl_handle_auth_step(RedsStream *stream, AsyncReadDone read_
             goto authreject;
         }
 
-        spice_info("Authentication successful");
+        spice_debug("Authentication successful");
         reds_stream_write_u32(stream, SPICE_LINK_ERR_OK); /* Accept auth */
 
         /*
@@ -813,7 +813,7 @@ RedsSaslError reds_sasl_handle_auth_steplen(RedsStream *stream, AsyncReadDone re
 {
     RedsSASL *sasl = &stream->priv->sasl;
 
-    spice_info("Got steplen %d", sasl->len);
+    spice_debug("Got steplen %d", sasl->len);
     if (sasl->len > SASL_DATA_MAX_LEN) {
         spice_warning("Too much SASL data %d", sasl->len);
         return REDS_SASL_ERROR_INVALID_DATA;
@@ -865,7 +865,7 @@ RedsSaslError reds_sasl_handle_auth_start(RedsStream *stream, AsyncReadDone read
         datalen--; /* Don't count NULL byte when passing to _start() */
     }
 
-    spice_info("Start SASL auth with mechanism %s. Data %p (%d bytes)",
+    spice_debug("Start SASL auth with mechanism %s. Data %p (%d bytes)",
                sasl->mechlist, clientdata, datalen);
     err = sasl_server_start(sasl->conn,
                             sasl->mechlist,
@@ -886,7 +886,7 @@ RedsSaslError reds_sasl_handle_auth_start(RedsStream *stream, AsyncReadDone read
         return REDS_SASL_ERROR_INVALID_DATA;
     }
 
-    spice_info("SASL return data %d bytes, %p", serveroutlen, serverout);
+    spice_debug("SASL return data %d bytes, %p", serveroutlen, serverout);
 
     if (serveroutlen) {
         serveroutlen += 1;
@@ -900,7 +900,7 @@ RedsSaslError reds_sasl_handle_auth_start(RedsStream *stream, AsyncReadDone read
     reds_stream_write_u8(stream, err == SASL_CONTINUE ? 0 : 1);
 
     if (err == SASL_CONTINUE) {
-        spice_info("%s", "Authentication must continue (start)");
+        spice_debug("%s", "Authentication must continue (start)");
         /* Wait for step length */
         reds_stream_async_read(stream, (uint8_t *)&sasl->len, sizeof(uint32_t),
                                read_cb, opaque);
@@ -913,7 +913,7 @@ RedsSaslError reds_sasl_handle_auth_start(RedsStream *stream, AsyncReadDone read
             goto authreject;
         }
 
-        spice_info("Authentication successful");
+        spice_debug("Authentication successful");
         reds_stream_write_u32(stream, SPICE_LINK_ERR_OK); /* Accept auth */
 
         /*
@@ -936,7 +936,7 @@ RedsSaslError reds_sasl_handle_auth_startlen(RedsStream *stream, AsyncReadDone r
 {
     RedsSASL *sasl = &stream->priv->sasl;
 
-    spice_info("Got client start len %d", sasl->len);
+    spice_debug("Got client start len %d", sasl->len);
     if (sasl->len > SASL_DATA_MAX_LEN) {
         spice_warning("Too much SASL data %d", sasl->len);
         return REDS_SASL_ERROR_INVALID_DATA;
@@ -958,22 +958,22 @@ bool reds_sasl_handle_auth_mechname(RedsStream *stream, AsyncReadDone read_cb, v
     RedsSASL *sasl = &stream->priv->sasl;
 
     sasl->mechname[sasl->len] = '\0';
-    spice_info("Got client mechname '%s' check against '%s'",
+    spice_debug("Got client mechname '%s' check against '%s'",
                sasl->mechname, sasl->mechlist);
 
     if (strncmp(sasl->mechlist, sasl->mechname, sasl->len) == 0) {
         if (sasl->mechlist[sasl->len] != '\0' &&
             sasl->mechlist[sasl->len] != ',') {
-            spice_info("One %d", sasl->mechlist[sasl->len]);
+            spice_debug("One %d", sasl->mechlist[sasl->len]);
             return FALSE;
         }
     } else {
         char *offset = strstr(sasl->mechlist, sasl->mechname);
-        spice_info("Two %p", offset);
+        spice_debug("Two %p", offset);
         if (!offset) {
             return FALSE;
         }
-        spice_info("Two '%s'", offset);
+        spice_debug("Two '%s'", offset);
         if (offset[-1] != ',' ||
             (offset[sasl->len] != '\0'&&
              offset[sasl->len] != ',')) {
@@ -984,7 +984,7 @@ bool reds_sasl_handle_auth_mechname(RedsStream *stream, AsyncReadDone read_cb, v
     free(sasl->mechlist);
     sasl->mechlist = spice_strdup(sasl->mechname);
 
-    spice_info("Validated mechname '%s'", sasl->mechname);
+    spice_debug("Validated mechname '%s'", sasl->mechname);
 
     reds_stream_async_read(stream, (uint8_t *)&sasl->len, sizeof(uint32_t),
                            read_cb, opaque);
@@ -1003,7 +1003,7 @@ bool reds_sasl_handle_auth_mechlen(RedsStream *stream, AsyncReadDone read_cb, vo
 
     sasl->mechname = spice_malloc(sasl->len + 1);
 
-    spice_info("Wait for client mechname");
+    spice_debug("Wait for client mechname");
     reds_stream_async_read(stream, (uint8_t *)sasl->mechname, sasl->len,
                            read_cb, opaque);
 
@@ -1101,7 +1101,7 @@ bool reds_sasl_start_auth(RedsStream *stream, AsyncReadDone read_cb, void *opaqu
         goto error_dispose;
     }
 
-    spice_info("Available mechanisms for client: '%s'", mechlist);
+    spice_debug("Available mechanisms for client: '%s'", mechlist);
 
     sasl->mechlist = spice_strdup(mechlist);
 
@@ -1112,7 +1112,7 @@ bool reds_sasl_start_auth(RedsStream *stream, AsyncReadDone read_cb, void *opaqu
         goto error;
     }
 
-    spice_info("Wait for client mechname length");
+    spice_debug("Wait for client mechname length");
     reds_stream_async_read(stream, (uint8_t *)&sasl->len, sizeof(uint32_t),
                            read_cb, opaque);
 
diff --git a/server/reds.c b/server/reds.c
index ff0f0e2..55a5494 100644
--- a/server/reds.c
+++ b/server/reds.c
@@ -482,7 +482,7 @@ void reds_client_disconnect(RedsState *reds, RedClient *client)
 
     if (reds->config->exit_on_disconnect)
     {
-        spice_info("Exiting server because of client disconnect.\n");
+        spice_debug("Exiting server because of client disconnect.\n");
         exit(0);
     }
 
@@ -491,7 +491,7 @@ void reds_client_disconnect(RedsState *reds, RedClient *client)
         return;
     }
 
-    spice_info(NULL);
+    spice_debug(NULL);
     /* disconnecting is set to prevent recursion because of the following:
      * main_channel_client_on_disconnect->
      *  reds_client_disconnect->red_client_destroy->main_channel...
@@ -567,7 +567,7 @@ static void reds_disconnect(RedsState *reds)
     GListIter iter;
     RedClient *client;
 
-    spice_info(NULL);
+    spice_debug(NULL);
     GLIST_FOREACH(reds->clients, iter, RedClient, client) {
         reds_client_disconnect(reds, client);
     }
@@ -1149,7 +1149,7 @@ void reds_on_main_mouse_mode_request(RedsState *reds, void *message, size_t size
         if (reds->is_client_mouse_allowed) {
             reds_set_mouse_mode(reds, SPICE_MOUSE_MODE_CLIENT);
         } else {
-            spice_info("client mouse is disabled");
+            spice_debug("client mouse is disabled");
         }
         break;
     case SPICE_MOUSE_MODE_SERVER:
@@ -1563,7 +1563,7 @@ static bool reds_send_link_error(RedLinkInfo *link, uint32_t error)
 
 static void reds_info_new_channel(RedLinkInfo *link, int connection_id)
 {
-    spice_info("channel %d:%d, connected successfully, over %s link",
+    spice_debug("channel %d:%d, connected successfully, over %s link",
                link->link_mess->channel_type,
                link->link_mess->channel_id,
                reds_stream_is_ssl(link->stream) ? "Secure" : "Non Secure");
@@ -1588,7 +1588,7 @@ static void reds_mig_target_client_add(RedsState *reds, RedClient *client)
     RedsMigTargetClient *mig_client;
 
     g_return_if_fail(reds);
-    spice_info(NULL);
+    spice_debug(NULL);
     mig_client = spice_new0(RedsMigTargetClient, 1);
     mig_client->client = client;
     mig_client->reds = reds;
@@ -1678,7 +1678,7 @@ static void reds_handle_main_link(RedsState *reds, RedLinkInfo *link)
     MainChannelClient *mcc;
     int mig_target = FALSE;
 
-    spice_info(NULL);
+    spice_debug(NULL);
     spice_assert(reds->main_channel);
 
     link_mess = link->link_mess;
@@ -1716,7 +1716,7 @@ static void reds_handle_main_link(RedsState *reds, RedLinkInfo *link)
                             link_mess->num_common_caps,
                             link_mess->num_common_caps ? caps : NULL, link_mess->num_channel_caps,
                             link_mess->num_channel_caps ? caps + link_mess->num_common_caps : NULL);
-    spice_info("NEW Client %p mcc %p connect-id %d", client, mcc, connection_id);
+    spice_debug("NEW Client %p mcc %p connect-id %d", client, mcc, connection_id);
     free(link_mess);
     red_client_set_main(client, mcc);
 
@@ -1804,10 +1804,10 @@ static int reds_link_mig_target_channels(RedsState *reds, RedClient *client)
     RedsMigTargetClient *mig_client;
     GList *item;
 
-    spice_info("%p", client);
+    spice_debug("%p", client);
     mig_client = reds_mig_target_client_find(reds, client);
     if (!mig_client) {
-        spice_info("Error: mig target client was not found");
+        spice_debug("Error: mig target client was not found");
         return FALSE;
     }
 
@@ -1854,7 +1854,7 @@ void reds_on_client_seamless_migrate_complete(RedsState *reds, RedClient *client
 {
     spice_debug(NULL);
     if (!reds_find_client(reds, client)) {
-        spice_info("client no longer exists");
+        spice_debug("client no longer exists");
         return;
     }
     main_channel_client_migrate_dst_complete(red_client_get_main(client));
@@ -1864,7 +1864,7 @@ void reds_on_client_semi_seamless_migrate_complete(RedsState *reds, RedClient *c
 {
     MainChannelClient *mcc;
 
-    spice_info("%p", client);
+    spice_debug("%p", client);
     mcc = red_client_get_main(client);
 
     // TODO: not doing net test. consider doing it on client_migrate_info
@@ -2140,7 +2140,7 @@ static void reds_handle_auth_mechanism(void *opaque)
     RedLinkInfo *link = (RedLinkInfo *)opaque;
     RedsState *reds = link->reds;
 
-    spice_info("Auth method: %d", link->auth_mechanism.auth_mechanism);
+    spice_debug("Auth method: %d", link->auth_mechanism.auth_mechanism);
 
     link->auth_mechanism.auth_mechanism = GUINT32_FROM_LE(link->auth_mechanism.auth_mechanism);
     if (link->auth_mechanism.auth_mechanism == SPICE_COMMON_CAP_AUTH_SPICE
@@ -2149,7 +2149,7 @@ static void reds_handle_auth_mechanism(void *opaque)
         reds_get_spice_ticket(link);
 #if HAVE_SASL
     } else if (link->auth_mechanism.auth_mechanism == SPICE_COMMON_CAP_AUTH_SASL) {
-        spice_info("Starting SASL");
+        spice_debug("Starting SASL");
         reds_start_auth_sasl(link);
 #endif
     } else {
@@ -2572,9 +2572,9 @@ static int reds_init_socket(const char *addr, int portnr, int family)
                              uaddr,INET6_ADDRSTRLEN, uport,32,
                              NI_NUMERICHOST | NI_NUMERICSERV);
             if (rc == 0) {
-                spice_info("bound to %s:%s", uaddr, uport);
+                spice_debug("bound to %s:%s", uaddr, uport);
             } else {
-                spice_info("cannot resolve address spice-server is bound to");
+                spice_debug("cannot resolve address spice-server is bound to");
             }
             freeaddrinfo(res);
             goto listen;
@@ -2795,7 +2795,7 @@ static int reds_init_ssl(RedsState *reds)
     /* Load our keys and certificates*/
     return_code = SSL_CTX_use_certificate_chain_file(reds->ctx, reds->config->ssl_parameters.certs_file);
     if (return_code == 1) {
-        spice_info("Loaded certificates from %s", reds->config->ssl_parameters.certs_file);
+        spice_debug("Loaded certificates from %s", reds->config->ssl_parameters.certs_file);
     } else {
         spice_warning("Could not load certificates from %s", reds->config->ssl_parameters.certs_file);
         return -1;
@@ -2807,7 +2807,7 @@ static int reds_init_ssl(RedsState *reds)
     return_code = SSL_CTX_use_PrivateKey_file(reds->ctx, reds->config->ssl_parameters.private_key_file,
                                               SSL_FILETYPE_PEM);
     if (return_code == 1) {
-        spice_info("Using private key from %s", reds->config->ssl_parameters.private_key_file);
+        spice_debug("Using private key from %s", reds->config->ssl_parameters.private_key_file);
     } else {
         spice_warning("Could not use private key file");
         return -1;
@@ -2816,7 +2816,7 @@ static int reds_init_ssl(RedsState *reds)
     /* Load the CAs we trust*/
     return_code = SSL_CTX_load_verify_locations(reds->ctx, reds->config->ssl_parameters.ca_certificate_file, 0);
     if (return_code == 1) {
-        spice_info("Loaded CA certificates from %s", reds->config->ssl_parameters.ca_certificate_file);
+        spice_debug("Loaded CA certificates from %s", reds->config->ssl_parameters.ca_certificate_file);
     } else {
         spice_warning("Could not use CA file %s", reds->config->ssl_parameters.ca_certificate_file);
         return -1;
@@ -2912,7 +2912,7 @@ static void reds_mig_release(RedsState *reds)
 
 static void reds_mig_started(RedsState *reds)
 {
-    spice_info(NULL);
+    spice_debug(NULL);
     spice_assert(reds->config->mig_spice);
 
     reds->mig_inprogress = TRUE;
@@ -2963,7 +2963,7 @@ static void reds_migrate_channels_seamless(RedsState *reds)
 
 static void reds_mig_finished(RedsState *reds, int completed)
 {
-    spice_info(NULL);
+    spice_debug(NULL);
 
     reds->mig_inprogress = TRUE;
 
@@ -2994,7 +2994,7 @@ static void reds_mig_switch(RedsState *reds)
 static void migrate_timeout(void *opaque)
 {
     RedsState *reds = opaque;
-    spice_info(NULL);
+    spice_debug(NULL);
     spice_assert(reds->mig_wait_connect || reds->mig_wait_disconnect);
     if (reds->mig_wait_connect) {
         /* we will fall back to the switch host scheme when migration completes */
@@ -3141,7 +3141,7 @@ static int spice_server_char_device_add_interface(SpiceServer *reds,
             SPICE_CONTAINEROF(sin, SpiceCharDeviceInstance, base);
     RedCharDevice *dev_state = NULL;
 
-    spice_info("CHAR_DEVICE %s", char_device->subtype);
+    spice_debug("CHAR_DEVICE %s", char_device->subtype);
     if (strcmp(char_device->subtype, SUBTYPE_VDAGENT) == 0) {
         if (reds->vdagent) {
             spice_warning("vdagent already attached");
@@ -3194,7 +3194,7 @@ static void spice_server_char_device_remove_interface(RedsState *reds, SpiceBase
     SpiceCharDeviceInstance* char_device =
             SPICE_CONTAINEROF(sin, SpiceCharDeviceInstance, base);
 
-    spice_info("remove CHAR_DEVICE %s", char_device->subtype);
+    spice_debug("remove CHAR_DEVICE %s", char_device->subtype);
     if (strcmp(char_device->subtype, SUBTYPE_VDAGENT) == 0) {
         if (reds->vdagent) {
             reds_agent_remove(reds);
@@ -3222,7 +3222,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
     const SpiceBaseInterface *interface = sin->sif;
 
     if (strcmp(interface->type, SPICE_INTERFACE_KEYBOARD) == 0) {
-        spice_info("SPICE_INTERFACE_KEYBOARD");
+        spice_debug("SPICE_INTERFACE_KEYBOARD");
         if (interface->major_version != SPICE_INTERFACE_KEYBOARD_MAJOR ||
             interface->minor_version > SPICE_INTERFACE_KEYBOARD_MINOR) {
             spice_warning("unsupported keyboard interface");
@@ -3232,7 +3232,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
             return -1;
         }
     } else if (strcmp(interface->type, SPICE_INTERFACE_MOUSE) == 0) {
-        spice_info("SPICE_INTERFACE_MOUSE");
+        spice_debug("SPICE_INTERFACE_MOUSE");
         if (interface->major_version != SPICE_INTERFACE_MOUSE_MAJOR ||
             interface->minor_version > SPICE_INTERFACE_MOUSE_MINOR) {
             spice_warning("unsupported mouse interface");
@@ -3244,7 +3244,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
     } else if (strcmp(interface->type, SPICE_INTERFACE_QXL) == 0) {
         QXLInstance *qxl;
 
-        spice_info("SPICE_INTERFACE_QXL");
+        spice_debug("SPICE_INTERFACE_QXL");
         if (interface->major_version != SPICE_INTERFACE_QXL_MAJOR ||
             interface->minor_version > SPICE_INTERFACE_QXL_MINOR) {
             spice_warning("unsupported qxl interface");
@@ -3264,7 +3264,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
         red_qxl_set_compression_level(qxl, calc_compression_level(reds));
     } else if (strcmp(interface->type, SPICE_INTERFACE_TABLET) == 0) {
         SpiceTabletInstance *tablet = SPICE_CONTAINEROF(sin, SpiceTabletInstance, base);
-        spice_info("SPICE_INTERFACE_TABLET");
+        spice_debug("SPICE_INTERFACE_TABLET");
         if (interface->major_version != SPICE_INTERFACE_TABLET_MAJOR ||
             interface->minor_version > SPICE_INTERFACE_TABLET_MINOR) {
             spice_warning("unsupported tablet interface");
@@ -3279,7 +3279,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
         }
 
     } else if (strcmp(interface->type, SPICE_INTERFACE_PLAYBACK) == 0) {
-        spice_info("SPICE_INTERFACE_PLAYBACK");
+        spice_debug("SPICE_INTERFACE_PLAYBACK");
         if (interface->major_version != SPICE_INTERFACE_PLAYBACK_MAJOR ||
             interface->minor_version > SPICE_INTERFACE_PLAYBACK_MINOR) {
             spice_warning("unsupported playback interface");
@@ -3288,7 +3288,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
         snd_attach_playback(reds, SPICE_CONTAINEROF(sin, SpicePlaybackInstance, base));
 
     } else if (strcmp(interface->type, SPICE_INTERFACE_RECORD) == 0) {
-        spice_info("SPICE_INTERFACE_RECORD");
+        spice_debug("SPICE_INTERFACE_RECORD");
         if (interface->major_version != SPICE_INTERFACE_RECORD_MAJOR ||
             interface->minor_version > SPICE_INTERFACE_RECORD_MINOR) {
             spice_warning("unsupported record interface");
@@ -3305,7 +3305,7 @@ SPICE_GNUC_VISIBLE int spice_server_add_interface(SpiceServer *reds,
         spice_server_char_device_add_interface(reds, sin);
 
     } else if (strcmp(interface->type, SPICE_INTERFACE_MIGRATION) == 0) {
-        spice_info("SPICE_INTERFACE_MIGRATION");
+        spice_debug("SPICE_INTERFACE_MIGRATION");
         if (reds->migration_interface) {
             spice_warning("already have migration");
             return -1;
@@ -3335,14 +3335,14 @@ SPICE_GNUC_VISIBLE int spice_server_remove_interface(SpiceBaseInstance *sin)
         SpiceTabletInstance *tablet = SPICE_CONTAINEROF(sin, SpiceTabletInstance, base);
         g_return_val_if_fail(tablet->st != NULL, -1);
         reds = spice_tablet_state_get_server(tablet->st);
-        spice_info("remove SPICE_INTERFACE_TABLET");
+        spice_debug("remove SPICE_INTERFACE_TABLET");
         inputs_channel_detach_tablet(reds->inputs_channel, tablet);
         reds_update_mouse_mode(reds);
     } else if (strcmp(interface->type, SPICE_INTERFACE_PLAYBACK) == 0) {
-        spice_info("remove SPICE_INTERFACE_PLAYBACK");
+        spice_debug("remove SPICE_INTERFACE_PLAYBACK");
         snd_detach_playback(SPICE_CONTAINEROF(sin, SpicePlaybackInstance, base));
     } else if (strcmp(interface->type, SPICE_INTERFACE_RECORD) == 0) {
-        spice_info("remove SPICE_INTERFACE_RECORD");
+        spice_debug("remove SPICE_INTERFACE_RECORD");
         snd_detach_record(SPICE_CONTAINEROF(sin, SpiceRecordInstance, base));
     } else if (strcmp(interface->type, SPICE_INTERFACE_CHAR_DEVICE) == 0) {
         SpiceCharDeviceInstance *char_device = SPICE_CONTAINEROF(sin, SpiceCharDeviceInstance, base);
@@ -3367,7 +3367,7 @@ SPICE_GNUC_VISIBLE int spice_server_remove_interface(SpiceBaseInstance *sin)
 
 static int do_spice_init(RedsState *reds, SpiceCoreInterface *core_interface)
 {
-    spice_info("starting %s", VERSION);
+    spice_debug("starting %s", VERSION);
 
 #if !GLIB_CHECK_VERSION(2,36,0)
     g_type_init();
@@ -4047,11 +4047,11 @@ SPICE_GNUC_VISIBLE int spice_server_migrate_connect(SpiceServer *reds, const cha
     SpiceMigrateInterface *sif;
     int try_seamless;
 
-    spice_info(NULL);
+    spice_debug(NULL);
     spice_assert(reds->migration_interface);
 
     if (reds->expect_migrate) {
-        spice_info("consecutive calls without migration. Canceling previous call");
+        spice_debug("consecutive calls without migration. Canceling previous call");
         main_channel_migrate_src_complete(reds->main_channel, FALSE);
     }
 
@@ -4082,7 +4082,7 @@ SPICE_GNUC_VISIBLE int spice_server_migrate_connect(SpiceServer *reds, const cha
     } else {
         if (reds->clients == NULL) {
             reds_mig_release(reds);
-            spice_info("no client connected");
+            spice_debug("no client connected");
         }
         sif->migrate_connect_complete(reds->migration_interface);
     }
@@ -4094,7 +4094,7 @@ SPICE_GNUC_VISIBLE int spice_server_migrate_info(SpiceServer *reds, const char*
                                           int port, int secure_port,
                                           const char* cert_subject)
 {
-    spice_info(NULL);
+    spice_debug(NULL);
     spice_assert(!reds->migration_interface);
 
     if (!reds_set_migration_dest_info(reds, dest, port, secure_port, cert_subject)) {
@@ -4105,7 +4105,7 @@ SPICE_GNUC_VISIBLE int spice_server_migrate_info(SpiceServer *reds, const char*
 
 SPICE_GNUC_VISIBLE int spice_server_migrate_start(SpiceServer *reds)
 {
-    spice_info(NULL);
+    spice_debug(NULL);
     if (!reds->config->mig_spice) {
         return -1;
     }
@@ -4117,7 +4117,7 @@ SPICE_GNUC_VISIBLE int spice_server_migrate_end(SpiceServer *reds, int completed
     SpiceMigrateInterface *sif;
     int ret = 0;
 
-    spice_info(NULL);
+    spice_debug(NULL);
 
     spice_assert(reds->migration_interface);
 
@@ -4131,7 +4131,7 @@ SPICE_GNUC_VISIBLE int spice_server_migrate_end(SpiceServer *reds, int completed
 
     reds->expect_migrate = FALSE;
     if (!reds_main_channel_connected(reds)) {
-        spice_info("no peer connected");
+        spice_debug("no peer connected");
         goto complete;
     }
     reds_mig_finished(reds, completed);
@@ -4146,7 +4146,7 @@ complete:
 /* interface for switch-host migration */
 SPICE_GNUC_VISIBLE int spice_server_migrate_switch(SpiceServer *reds)
 {
-    spice_info(NULL);
+    spice_debug(NULL);
     if (reds->clients == NULL) {
        return 0;
     }
diff --git a/server/sound.c b/server/sound.c
index 7c36174..aa20f92 100644
--- a/server/sound.c
+++ b/server/sound.c
@@ -957,7 +957,7 @@ SPICE_GNUC_VISIBLE void spice_server_playback_put_samples(SpicePlaybackInstance
     playback_client = frame->client;
     if (!playback_client || sin->st->channel.connection != SND_CHANNEL_CLIENT(playback_client)) {
         /* lost last reference, client has been destroyed previously */
-        spice_info("audio samples belong to a disconnected client");
+        spice_debug("audio samples belong to a disconnected client");
         return;
     }
     spice_assert(SND_CHANNEL_CLIENT(playback_client)->active);
-- 
2.9.3



More information about the Spice-devel mailing list