]> git.sesse.net Git - nageru/blobdiff - decklink_output.cpp
Release Nageru 1.7.2.
[nageru] / decklink_output.cpp
index d846b96a744436ac7a01eb598c13c46a84f54031..a3d220b37c98bb96834415af5a277c47424b83cb 100644 (file)
@@ -2,6 +2,9 @@
 #include <movit/util.h>
 #include <movit/resource_pool.h>  // Must be above the Xlib includes.
 #include <pthread.h>
+#include <unistd.h>
+
+#include <mutex>
 
 #include <epoxy/egl.h>
 
@@ -9,8 +12,8 @@
 #include "decklink_output.h"
 #include "decklink_util.h"
 #include "flags.h"
+#include "metrics.h"
 #include "print_latency.h"
-#include "resource_pool.h"
 #include "timebase.h"
 #include "v210_converter.h"
 
@@ -18,24 +21,78 @@ using namespace movit;
 using namespace std;
 using namespace std::chrono;
 
+namespace {
+
+// This class can be deleted during regular use, so make all the metrics static.
+once_flag decklink_metrics_inited;
+LatencyHistogram latency_histogram;
+atomic<int64_t> metric_decklink_output_width_pixels{-1};
+atomic<int64_t> metric_decklink_output_height_pixels{-1};
+atomic<int64_t> metric_decklink_output_frame_rate_den{-1};
+atomic<int64_t> metric_decklink_output_frame_rate_nom{-1};
+atomic<int64_t> metric_decklink_output_inflight_frames{0};
+atomic<int64_t> metric_decklink_output_color_mismatch_frames{0};
+
+atomic<int64_t> metric_decklink_output_scheduled_frames_dropped{0};
+atomic<int64_t> metric_decklink_output_scheduled_frames_late{0};
+atomic<int64_t> metric_decklink_output_scheduled_frames_normal{0};
+atomic<int64_t> metric_decklink_output_scheduled_frames_preroll{0};
+
+atomic<int64_t> metric_decklink_output_completed_frames_completed{0};
+atomic<int64_t> metric_decklink_output_completed_frames_dropped{0};
+atomic<int64_t> metric_decklink_output_completed_frames_flushed{0};
+atomic<int64_t> metric_decklink_output_completed_frames_late{0};
+atomic<int64_t> metric_decklink_output_completed_frames_unknown{0};
+
+atomic<int64_t> metric_decklink_output_scheduled_samples{0};
+
+Summary metric_decklink_output_margin_seconds;
+
+}  // namespace
+
 DeckLinkOutput::DeckLinkOutput(ResourcePool *resource_pool, QSurface *surface, unsigned width, unsigned height, unsigned card_index)
        : resource_pool(resource_pool), surface(surface), width(width), height(height), card_index(card_index)
 {
        chroma_subsampler.reset(new ChromaSubsampler(resource_pool));
-       latency_histogram.init("decklink_output");
+
+       call_once(decklink_metrics_inited, [](){
+               latency_histogram.init("decklink_output");
+               global_metrics.add("decklink_output_width_pixels", &metric_decklink_output_width_pixels, Metrics::TYPE_GAUGE);
+               global_metrics.add("decklink_output_height_pixels", &metric_decklink_output_height_pixels, Metrics::TYPE_GAUGE);
+               global_metrics.add("decklink_output_frame_rate_den", &metric_decklink_output_frame_rate_den, Metrics::TYPE_GAUGE);
+               global_metrics.add("decklink_output_frame_rate_nom", &metric_decklink_output_frame_rate_nom, Metrics::TYPE_GAUGE);
+               global_metrics.add("decklink_output_inflight_frames", &metric_decklink_output_inflight_frames, Metrics::TYPE_GAUGE);
+               global_metrics.add("decklink_output_color_mismatch_frames", &metric_decklink_output_color_mismatch_frames);
+
+               global_metrics.add("decklink_output_scheduled_frames", {{ "status", "dropped" }}, &metric_decklink_output_scheduled_frames_dropped);
+               global_metrics.add("decklink_output_scheduled_frames", {{ "status", "late" }}, &metric_decklink_output_scheduled_frames_late);
+               global_metrics.add("decklink_output_scheduled_frames", {{ "status", "normal" }}, &metric_decklink_output_scheduled_frames_normal);
+               global_metrics.add("decklink_output_scheduled_frames", {{ "status", "preroll" }}, &metric_decklink_output_scheduled_frames_preroll);
+
+               global_metrics.add("decklink_output_completed_frames", {{ "status", "completed" }}, &metric_decklink_output_completed_frames_completed);
+               global_metrics.add("decklink_output_completed_frames", {{ "status", "dropped" }}, &metric_decklink_output_completed_frames_dropped);
+               global_metrics.add("decklink_output_completed_frames", {{ "status", "flushed" }}, &metric_decklink_output_completed_frames_flushed);
+               global_metrics.add("decklink_output_completed_frames", {{ "status", "late" }}, &metric_decklink_output_completed_frames_late);
+               global_metrics.add("decklink_output_completed_frames", {{ "status", "unknown" }}, &metric_decklink_output_completed_frames_unknown);
+
+               global_metrics.add("decklink_output_scheduled_samples", &metric_decklink_output_scheduled_samples);
+               vector<double> quantiles{0.01, 0.1, 0.25, 0.5, 0.75, 0.9, 0.99};
+               metric_decklink_output_margin_seconds.init(quantiles, 60.0);
+               global_metrics.add("decklink_output_margin_seconds", &metric_decklink_output_margin_seconds);
+       });
 }
 
-void DeckLinkOutput::set_device(IDeckLink *decklink)
+bool DeckLinkOutput::set_device(IDeckLink *decklink)
 {
        if (decklink->QueryInterface(IID_IDeckLinkOutput, (void**)&output) != S_OK) {
-               fprintf(stderr, "Card %u has no outputs\n", card_index);
-               exit(1);
+               fprintf(stderr, "Warning: Card %u has no outputs\n", card_index);
+               return false;
        }
 
        IDeckLinkDisplayModeIterator *mode_it;
        if (output->GetDisplayModeIterator(&mode_it) != S_OK) {
-               fprintf(stderr, "Failed to enumerate output display modes for card %u\n", card_index);
-               exit(1);
+               fprintf(stderr, "Warning: Failed to enumerate output display modes for card %u\n", card_index);
+               return false;
        }
 
        video_modes.clear();
@@ -61,6 +118,7 @@ void DeckLinkOutput::set_device(IDeckLink *decklink)
        // if they exist. We're not very likely to need analog outputs, so we don't need a way
        // to change beyond that.
        video_connection = pick_default_video_connection(decklink, BMDDeckLinkVideoOutputConnections, card_index);
+       return true;
 }
 
 void DeckLinkOutput::start_output(uint32_t mode, int64_t base_pts)
@@ -124,6 +182,11 @@ void DeckLinkOutput::start_output(uint32_t mode, int64_t base_pts)
                exit(1);
        }
 
+       metric_decklink_output_width_pixels = width;
+       metric_decklink_output_height_pixels = height;
+       metric_decklink_output_frame_rate_nom = time_value;
+       metric_decklink_output_frame_rate_den = time_scale;
+
        frame_duration = time_value * TIMEBASE / time_scale;
 
        display_mode->Release();
@@ -195,17 +258,19 @@ void DeckLinkOutput::send_frame(GLuint y_tex, GLuint cbcr_tex, YCbCrLumaCoeffici
                        fprintf(stderr, "         Consider --output-ycbcr-coefficients=rec601 (or =auto).\n");
                }
                last_frame_had_mode_mismatch = true;
+               ++metric_decklink_output_color_mismatch_frames;
        } else if ((current_mode_flags & bmdDisplayModeColorspaceRec709) && output_ycbcr_coefficients == YCBCR_REC_601) {
                if (!last_frame_had_mode_mismatch) {
                        fprintf(stderr, "WARNING: Chosen output mode expects Rec. 709 Y'CbCr coefficients.\n");
                        fprintf(stderr, "         Consider --output-ycbcr-coefficients=rec709 (or =auto).\n");
                }
                last_frame_had_mode_mismatch = true;
+               ++metric_decklink_output_color_mismatch_frames;
        } else {
                last_frame_had_mode_mismatch = false;
        }
 
-       unique_ptr<Frame> frame = move(get_frame());
+       unique_ptr<Frame> frame = get_frame();
        if (global_flags.ten_bit_output) {
                chroma_subsampler->create_v210(y_tex, cbcr_tex, width, height, frame->uyvy_tex);
        } else {
@@ -273,6 +338,7 @@ void DeckLinkOutput::send_audio(int64_t pts, const std::vector<float> &samples)
                        fprintf(stderr, "ScheduleAudioSamples() returned short write (%u/%ld)\n", frames_written, samples.size() / 2);
                }
        }
+       metric_decklink_output_scheduled_samples += samples.size() / 2;
 }
 
 void DeckLinkOutput::wait_for_frame(int64_t pts, int *dropped_frames, int64_t *frame_duration, bool *is_preroll, steady_clock::time_point *frame_timestamp)
@@ -289,6 +355,7 @@ void DeckLinkOutput::wait_for_frame(int64_t pts, int *dropped_frames, int64_t *f
        // While prerolling, we send out frames as quickly as we can.
        if (target_time < base_pts) {
                *is_preroll = true;
+               ++metric_decklink_output_scheduled_frames_preroll;
                return;
        }
 
@@ -313,9 +380,13 @@ void DeckLinkOutput::wait_for_frame(int64_t pts, int *dropped_frames, int64_t *f
        *frame_timestamp = steady_clock::now() +
                nanoseconds((target_time - stream_frame_time) * 1000000000 / TIMEBASE);
 
+       metric_decklink_output_margin_seconds.count_event(
+               (target_time - stream_frame_time) / double(TIMEBASE));
+
        // If we're ahead of time, wait for the frame to (approximately) start.
        if (stream_frame_time < target_time) {
                should_quit.sleep_until(*frame_timestamp);
+               ++metric_decklink_output_scheduled_frames_normal;
                return;
        }
 
@@ -324,6 +395,7 @@ void DeckLinkOutput::wait_for_frame(int64_t pts, int *dropped_frames, int64_t *f
        if (stream_frame_time < target_time + max_overshoot) {
                fprintf(stderr, "Warning: Frame was %ld ms late (but not skipping it due to --output-slop-frames).\n",
                        lrint((stream_frame_time - target_time) * 1000.0 / TIMEBASE));
+               ++metric_decklink_output_scheduled_frames_late;
                return;
        }
 
@@ -333,6 +405,8 @@ void DeckLinkOutput::wait_for_frame(int64_t pts, int *dropped_frames, int64_t *f
        const int64_t ns_per_frame = this->frame_duration * 1000000000 / TIMEBASE;
        *frame_timestamp += nanoseconds(*dropped_frames * ns_per_frame);
        fprintf(stderr, "Dropped %d output frames; skipping.\n", *dropped_frames);
+       metric_decklink_output_scheduled_frames_dropped += *dropped_frames;
+       ++metric_decklink_output_scheduled_frames_normal;
 }
 
 uint32_t DeckLinkOutput::pick_video_mode(uint32_t mode) const
@@ -378,20 +452,25 @@ HRESULT DeckLinkOutput::ScheduledFrameCompleted(/* in */ IDeckLinkVideoFrame *co
        Frame *frame = static_cast<Frame *>(completedFrame);
        switch (result) {
        case bmdOutputFrameCompleted:
+               ++metric_decklink_output_completed_frames_completed;
                break;
        case bmdOutputFrameDisplayedLate:
                fprintf(stderr, "Output frame displayed late (pts=%ld)\n", frame->pts);
                fprintf(stderr, "Consider increasing --output-buffer-frames if this persists.\n");
+               ++metric_decklink_output_completed_frames_late;
                break;
        case bmdOutputFrameDropped:
                fprintf(stderr, "Output frame was dropped (pts=%ld)\n", frame->pts);
                fprintf(stderr, "Consider increasing --output-buffer-frames if this persists.\n");
+               ++metric_decklink_output_completed_frames_dropped;
                break;
        case bmdOutputFrameFlushed:
                fprintf(stderr, "Output frame was flushed (pts=%ld)\n", frame->pts);
+               ++metric_decklink_output_completed_frames_flushed;
                break;
        default:
                fprintf(stderr, "Output frame completed with unknown status %d\n", result);
+               ++metric_decklink_output_completed_frames_unknown;
                break;
        }
 
@@ -402,6 +481,7 @@ HRESULT DeckLinkOutput::ScheduledFrameCompleted(/* in */ IDeckLinkVideoFrame *co
                lock_guard<mutex> lock(frame_queue_mutex);
                frame_freelist.push(unique_ptr<Frame>(frame));
                --num_frames_in_flight;
+               --metric_decklink_output_inflight_frames;
        }
 
        return S_OK;
@@ -445,7 +525,7 @@ unique_ptr<DeckLinkOutput::Frame> DeckLinkOutput::get_frame()
        check_error();
        glBindBuffer(GL_PIXEL_PACK_BUFFER, frame->pbo);
        check_error();
-       glBufferStorage(GL_PIXEL_PACK_BUFFER, stride * height, NULL, GL_MAP_READ_BIT | GL_MAP_PERSISTENT_BIT);
+       glBufferStorage(GL_PIXEL_PACK_BUFFER, stride * height, nullptr, GL_MAP_READ_BIT | GL_MAP_PERSISTENT_BIT);
        check_error();
        frame->uyvy_ptr = (uint8_t *)glMapBufferRange(GL_PIXEL_PACK_BUFFER, 0, stride * height, GL_MAP_READ_BIT | GL_MAP_PERSISTENT_BIT);
        check_error();
@@ -471,9 +551,18 @@ void DeckLinkOutput::present_thread_func()
                        frame = move(pending_video_frames.front());
                        pending_video_frames.pop();
                        ++num_frames_in_flight;
+                       ++metric_decklink_output_inflight_frames;
                }
 
-               glClientWaitSync(frame->fence.get(), /*flags=*/0, GL_TIMEOUT_IGNORED);
+               for ( ;; ) {
+                       int err = glClientWaitSync(frame->fence.get(), /*flags=*/0, 0);
+                       if (err == GL_TIMEOUT_EXPIRED) {
+                               // NVIDIA likes to busy-wait; yield instead.
+                               this_thread::sleep_for(milliseconds(1));
+                       } else {
+                               break;
+                       }
+               }
                check_error();
                frame->fence.reset();
 
@@ -497,6 +586,7 @@ void DeckLinkOutput::present_thread_func()
                        lock_guard<mutex> lock(frame_queue_mutex);
                        frame_freelist.push(move(frame));
                        --num_frames_in_flight;
+                       --metric_decklink_output_inflight_frames;
                }
        }
 }