X-Git-Url: https://main.carlh.net/gitweb/?a=blobdiff_plain;f=src%2Flib%2Fwriter.cc;h=b058c2801160d301142e00509159aa7a243b57a1;hb=71d8cf20889a3c419c9a3e485f461236e5317423;hp=cff0b5be23925ea09592b03dbcc27410f6e64e9c;hpb=aabf54736d7401437af7f066c51fee91be64e809;p=dcpomatic.git diff --git a/src/lib/writer.cc b/src/lib/writer.cc index cff0b5be2..b058c2801 100644 --- a/src/lib/writer.cc +++ b/src/lib/writer.cc @@ -1,5 +1,5 @@ /* - Copyright (C) 2012 Carl Hetherington + Copyright (C) 2012-2014 Carl Hetherington This program is free software; you can redistribute it and/or modify it under the terms of the GNU General Public License as published by @@ -19,38 +19,54 @@ #include #include -#include +#include +#include #include -#include #include +#include #include #include "writer.h" #include "compose.hpp" #include "film.h" -#include "format.h" +#include "ratio.h" #include "log.h" #include "dcp_video_frame.h" +#include "dcp_content_type.h" +#include "player.h" +#include "audio_mapping.h" #include "config.h" +#include "job.h" +#include "cross.h" #include "i18n.h" +#define LOG_GENERAL(...) _film->log()->log (String::compose (__VA_ARGS__), Log::GENERAL); +#define LOG_TIMING(...) _film->log()->microsecond_log (String::compose (__VA_ARGS__), Log::TIMING); +#define LOG_WARNING_NC(...) _film->log()->log (__VA_ARGS__, Log::WARNING); + +/* OS X strikes again */ +#undef set_key + using std::make_pair; using std::pair; using std::string; -using std::ifstream; using std::list; using std::cout; +using std::stringstream; using boost::shared_ptr; +using boost::weak_ptr; -int const Writer::_maximum_frames_in_memory = 8; +int const Writer::_maximum_frames_in_memory = Config::instance()->num_local_encoding_threads() + 4; -Writer::Writer (shared_ptr f) +Writer::Writer (shared_ptr f, weak_ptr j) : _film (f) + , _job (j) , _first_nonexistant_frame (0) , _thread (0) , _finish (false) , _queued_full_in_memory (0) , _last_written_frame (-1) + , _last_written_eyes (EYES_RIGHT) , _full_written (0) , _fake_written (0) , _repeat_written (0) @@ -58,81 +74,159 @@ Writer::Writer (shared_ptr f) { /* Remove any old DCP */ boost::filesystem::remove_all (_film->dir (_film->dcp_name ())); - + + shared_ptr job = _job.lock (); + assert (job); + + job->sub (_("Checking existing image data")); check_existing_picture_mxf (); - + /* Create our picture asset in a subdirectory, named according to those film's parameters which affect the video output. We will hard-link it into the DCP later. */ - - _picture_asset.reset ( - new libdcp::MonoPictureAsset ( - _film->internal_video_mxf_dir (), - _film->internal_video_mxf_filename (), - _film->dcp_frame_rate (), - _film->format()->dcp_size () - ) - ); - _picture_asset_writer = _picture_asset->start_write (_first_nonexistant_frame > 0); + if (_film->three_d ()) { + _picture_asset.reset (new libdcp::StereoPictureAsset (_film->internal_video_mxf_dir (), _film->internal_video_mxf_filename ())); + } else { + _picture_asset.reset (new libdcp::MonoPictureAsset (_film->internal_video_mxf_dir (), _film->internal_video_mxf_filename ())); + } + + _picture_asset->set_edit_rate (_film->video_frame_rate ()); + _picture_asset->set_size (_film->frame_size ()); + _picture_asset->set_interop (_film->interop ()); - AudioMapping m (_film); + if (_film->encrypted ()) { + _picture_asset->set_key (_film->key ()); + } - if (m.dcp_channels() > 0) { - _sound_asset.reset ( - new libdcp::SoundAsset ( - _film->dir (_film->dcp_name()), - _film->dcp_audio_mxf_filename (), - _film->dcp_frame_rate (), - m.dcp_channels (), - dcp_audio_sample_rate (_film->audio_stream()->sample_rate()) - ) - ); + _picture_asset_writer = _picture_asset->start_write (_first_nonexistant_frame > 0); + if (_film->audio_channels ()) { + _sound_asset.reset (new libdcp::SoundAsset (_film->directory (), _film->audio_mxf_filename ())); + _sound_asset->set_edit_rate (_film->video_frame_rate ()); + _sound_asset->set_channels (_film->audio_channels ()); + _sound_asset->set_sampling_rate (_film->audio_frame_rate ()); + _sound_asset->set_interop (_film->interop ()); + + if (_film->encrypted ()) { + _sound_asset->set_key (_film->key ()); + } + + /* Write the sound asset into the film directory so that we leave the creation + of the DCP directory until the last minute. + */ _sound_asset_writer = _sound_asset->start_write (); } _thread = new boost::thread (boost::bind (&Writer::thread, this)); + + job->sub (_("Encoding image data")); +} + +Writer::~Writer () +{ + terminate_thread (false); } void -Writer::write (shared_ptr encoded, int frame) +Writer::write (shared_ptr encoded, int frame, Eyes eyes) { boost::mutex::scoped_lock lock (_mutex); + while (_queued_full_in_memory > _maximum_frames_in_memory) { + _full_condition.wait (lock); + } + QueueItem qi; qi.type = QueueItem::FULL; qi.encoded = encoded; qi.frame = frame; - _queue.push_back (qi); - ++_queued_full_in_memory; - _condition.notify_all (); + if (_film->three_d() && eyes == EYES_BOTH) { + /* 2D material in a 3D DCP; fake the 3D */ + qi.eyes = EYES_LEFT; + _queue.push_back (qi); + ++_queued_full_in_memory; + qi.eyes = EYES_RIGHT; + _queue.push_back (qi); + ++_queued_full_in_memory; + } else { + qi.eyes = eyes; + _queue.push_back (qi); + ++_queued_full_in_memory; + } + + _empty_condition.notify_all (); } void -Writer::fake_write (int frame) +Writer::fake_write (int frame, Eyes eyes) { boost::mutex::scoped_lock lock (_mutex); - ifstream ifi (_film->info_path (frame).c_str()); + while (_queued_full_in_memory > _maximum_frames_in_memory) { + _full_condition.wait (lock); + } + + FILE* ifi = fopen_boost (_film->info_path (frame, eyes), "r"); libdcp::FrameInfo info (ifi); + fclose (ifi); QueueItem qi; qi.type = QueueItem::FAKE; qi.size = info.size; qi.frame = frame; - _queue.push_back (qi); + if (_film->three_d() && eyes == EYES_BOTH) { + qi.eyes = EYES_LEFT; + _queue.push_back (qi); + qi.eyes = EYES_RIGHT; + _queue.push_back (qi); + } else { + qi.eyes = eyes; + _queue.push_back (qi); + } - _condition.notify_all (); + _empty_condition.notify_all (); } /** This method is not thread safe */ void Writer::write (shared_ptr audio) { - _sound_asset_writer->write (audio->data(), audio->frames()); + if (_sound_asset) { + _sound_asset_writer->write (audio->data(), audio->frames()); + } +} + +/** This must be called from Writer::thread() with an appropriate lock held */ +bool +Writer::have_sequenced_image_at_queue_head () +{ + if (_queue.empty ()) { + return false; + } + + _queue.sort (); + + /* The queue should contain only EYES_LEFT/EYES_RIGHT pairs or EYES_BOTH */ + + if (_queue.front().eyes == EYES_BOTH) { + /* 2D */ + return _queue.front().frame == (_last_written_frame + 1); + } + + /* 3D */ + + if (_last_written_eyes == EYES_LEFT && _queue.front().frame == _last_written_frame && _queue.front().eyes == EYES_RIGHT) { + return true; + } + + if (_last_written_eyes == EYES_RIGHT && _queue.front().frame == (_last_written_frame + 1) && _queue.front().eyes == EYES_LEFT) { + return true; + } + + return false; } void @@ -145,26 +239,21 @@ try while (1) { - _queue.sort (); - - if (_finish || - _queued_full_in_memory > _maximum_frames_in_memory || - (!_queue.empty() && _queue.front().frame == (_last_written_frame + 1))) { - + if (_finish || _queued_full_in_memory > _maximum_frames_in_memory || have_sequenced_image_at_queue_head ()) { break; } - - TIMING (N_("writer sleeps with a queue of %1"), _queue.size()); - _condition.wait (lock); - TIMING (N_("writer wakes with a queue of %1"), _queue.size()); + + LOG_TIMING (N_("writer sleeps with a queue of %1"), _queue.size()); + _empty_condition.wait (lock); + LOG_TIMING (N_("writer wakes with a queue of %1"), _queue.size()); } if (_finish && _queue.empty()) { return; } - /* Write any frames that we can write; i.e. those that are in sequence */ - while (!_queue.empty() && _queue.front().frame == (_last_written_frame + 1)) { + /* Write any frames that we can write; i.e. those that are in sequence. */ + while (have_sequenced_image_at_queue_head ()) { QueueItem qi = _queue.front (); _queue.pop_front (); if (qi.type == QueueItem::FULL && qi.encoded) { @@ -175,34 +264,53 @@ try switch (qi.type) { case QueueItem::FULL: { - _film->log()->log (String::compose (N_("Writer FULL-writes %1 to MXF"), qi.frame)); + LOG_GENERAL (N_("Writer FULL-writes %1 to MXF"), qi.frame); if (!qi.encoded) { - qi.encoded.reset (new EncodedData (_film->j2c_path (qi.frame, false))); + qi.encoded.reset (new EncodedData (_film->j2c_path (qi.frame, qi.eyes, false))); } - libdcp::FrameInfo const fin = _picture_asset_writer->write (qi.encoded->data(), qi.encoded->size()); - qi.encoded->write_info (_film, qi.frame, fin); - _last_written = qi.encoded; + + libdcp::FrameInfo fin = _picture_asset_writer->write (qi.encoded->data(), qi.encoded->size()); + qi.encoded->write_info (_film, qi.frame, qi.eyes, fin); + _last_written[qi.eyes] = qi.encoded; ++_full_written; break; } case QueueItem::FAKE: - _film->log()->log (String::compose (N_("Writer FAKE-writes %1 to MXF"), qi.frame)); + LOG_GENERAL (N_("Writer FAKE-writes %1 to MXF"), qi.frame); _picture_asset_writer->fake_write (qi.size); - _last_written.reset (); + _last_written[qi.eyes].reset (); ++_fake_written; break; case QueueItem::REPEAT: { - _film->log()->log (String::compose (N_("Writer REPEAT-writes %1 to MXF"), qi.frame)); - libdcp::FrameInfo const fin = _picture_asset_writer->write (_last_written->data(), _last_written->size()); - _last_written->write_info (_film, qi.frame, fin); + LOG_GENERAL (N_("Writer REPEAT-writes %1 to MXF"), qi.frame); + libdcp::FrameInfo fin = _picture_asset_writer->write ( + _last_written[qi.eyes]->data(), + _last_written[qi.eyes]->size() + ); + + _last_written[qi.eyes]->write_info (_film, qi.frame, qi.eyes, fin); ++_repeat_written; break; } } lock.lock (); - ++_last_written_frame; + _last_written_frame = qi.frame; + _last_written_eyes = qi.eyes; + + if (_film->length()) { + shared_ptr job = _job.lock (); + assert (job); + int total = _film->time_to_video_frames (_film->length ()); + if (_film->three_d ()) { + /* _full_written and so on are incremented for each eye, so we need to double the total + frames to get the correct progress. + */ + total *= 2; + } + job->set_progress (float (_full_written + _fake_written + _repeat_written) / total); + } } while (_queued_full_in_memory > _maximum_frames_in_memory) { @@ -210,7 +318,8 @@ try Write some FULL frames to disk. */ - /* Find one */ + /* Find one from the back of the queue */ + _queue.sort (); list::reverse_iterator i = _queue.rbegin (); while (i != _queue.rend() && (i->type != QueueItem::FULL || !i->encoded)) { ++i; @@ -222,12 +331,20 @@ try ++_pushed_to_disk; lock.unlock (); - _film->log()->log (String::compose (N_("Writer full (awaiting %1); pushes %2 to disk"), _last_written_frame + 1, qi.frame)); - qi.encoded->write (_film, qi.frame); + + LOG_GENERAL ( + "Writer full (awaiting %1 [last eye was %2]); pushes %3 to disk", + _last_written_frame + 1, + _last_written_eyes, qi.frame + ); + + qi.encoded->write (_film, qi.frame, qi.eyes); lock.lock (); qi.encoded.reset (); --_queued_full_in_memory; } + + _full_condition.notify_all (); } } catch (...) @@ -236,70 +353,83 @@ catch (...) } void -Writer::finish () +Writer::terminate_thread (bool can_throw) { - if (!_thread) { + boost::mutex::scoped_lock lock (_mutex); + if (_thread == 0) { return; } - boost::mutex::scoped_lock lock (_mutex); _finish = true; - _condition.notify_all (); + _empty_condition.notify_all (); + _full_condition.notify_all (); lock.unlock (); - _thread->join (); - if (thrown ()) { + _thread->join (); + if (can_throw) { rethrow (); } delete _thread; _thread = 0; +} - _picture_asset_writer->finalize (); +void +Writer::finish () +{ + if (!_thread) { + return; + } + + terminate_thread (true); + _picture_asset_writer->finalize (); if (_sound_asset_writer) { _sound_asset_writer->finalize (); } - - int const frames = _last_written_frame + 1; - int duration = 0; - if (_film->trim_type() == Film::CPL) { - duration = frames - _film->trim_start() - _film->trim_end(); - _picture_asset->set_entry_point (_film->trim_start ()); - } else { - duration = frames; - } - _picture_asset->set_duration (duration); + int const frames = _last_written_frame + 1; - /* Hard-link the video MXF into the DCP */ + _picture_asset->set_duration (frames); - boost::filesystem::path from; - from /= _film->internal_video_mxf_dir(); - from /= _film->internal_video_mxf_filename(); + /* Hard-link the video MXF into the DCP */ + boost::filesystem::path video_from; + video_from /= _film->internal_video_mxf_dir(); + video_from /= _film->internal_video_mxf_filename(); - boost::filesystem::path to; - to /= _film->dir (_film->dcp_name()); - to /= _film->dcp_video_mxf_filename (); + boost::filesystem::path video_to; + video_to /= _film->dir (_film->dcp_name()); + video_to /= _film->video_mxf_filename (); boost::system::error_code ec; - boost::filesystem::create_hard_link (from, to, ec); + boost::filesystem::create_hard_link (video_from, video_to, ec); if (ec) { /* hard link failed; copy instead */ - boost::filesystem::copy_file (from, to); - _film->log()->log ("Hard-link failed; fell back to copying"); + boost::filesystem::copy_file (video_from, video_to); + LOG_WARNING_NC ("Hard-link failed; fell back to copying"); } /* And update the asset */ _picture_asset->set_directory (_film->dir (_film->dcp_name ())); - _picture_asset->set_file_name (_film->dcp_video_mxf_filename ()); + _picture_asset->set_file_name (_film->video_mxf_filename ()); + + /* Move the audio MXF into the DCP */ if (_sound_asset) { - if (_film->trim_type() == Film::CPL) { - _sound_asset->set_entry_point (_film->trim_start ()); + boost::filesystem::path audio_to; + audio_to /= _film->dir (_film->dcp_name ()); + audio_to /= _film->audio_mxf_filename (); + + boost::filesystem::rename (_film->file (_film->audio_mxf_filename ()), audio_to, ec); + if (ec) { + throw FileError ( + String::compose (_("could not move audio MXF into the DCP (%1)"), ec.value ()), _film->file (_film->audio_mxf_filename ()) + ); } - _sound_asset->set_duration (duration); + + _sound_asset->set_directory (_film->dir (_film->dcp_name ())); + _sound_asset->set_duration (frames); } libdcp::DCP dcp (_film->dir (_film->dcp_name())); @@ -310,7 +440,7 @@ Writer::finish () _film->dcp_name(), _film->dcp_content_type()->libdcp_kind (), frames, - _film->dcp_frame_rate () + _film->video_frame_rate () ) ); @@ -323,28 +453,86 @@ Writer::finish () ) )); + shared_ptr job = _job.lock (); + assert (job); + + job->sub (_("Computing image digest")); + _picture_asset->compute_digest (boost::bind (&Job::set_progress, job.get(), _1, false)); + + if (_sound_asset) { + job->sub (_("Computing audio digest")); + _sound_asset->compute_digest (boost::bind (&Job::set_progress, job.get(), _1, false)); + } + libdcp::XMLMetadata meta = Config::instance()->dcp_metadata (); meta.set_issue_date_now (); - dcp.write_xml (meta); + dcp.write_xml (_film->interop (), meta, _film->is_signed() ? make_signer () : shared_ptr ()); - _film->log()->log (String::compose (N_("Wrote %1 FULL, %2 FAKE, %3 REPEAT; %4 pushed to disk"), _full_written, _fake_written, _repeat_written, _pushed_to_disk)); + LOG_GENERAL ( + N_("Wrote %1 FULL, %2 FAKE, %3 REPEAT; %4 pushed to disk"), _full_written, _fake_written, _repeat_written, _pushed_to_disk + ); } /** Tell the writer that frame `f' should be a repeat of the frame before it */ void -Writer::repeat (int f) +Writer::repeat (int f, Eyes e) { boost::mutex::scoped_lock lock (_mutex); + while (_queued_full_in_memory > _maximum_frames_in_memory) { + _full_condition.wait (lock); + } + QueueItem qi; qi.type = QueueItem::REPEAT; qi.frame = f; - - _queue.push_back (qi); + if (_film->three_d() && e == EYES_BOTH) { + qi.eyes = EYES_LEFT; + _queue.push_back (qi); + qi.eyes = EYES_RIGHT; + _queue.push_back (qi); + } else { + qi.eyes = e; + _queue.push_back (qi); + } - _condition.notify_all (); + _empty_condition.notify_all (); } +bool +Writer::check_existing_picture_mxf_frame (FILE* mxf, int f, Eyes eyes) +{ + /* Read the frame info as written */ + FILE* ifi = fopen_boost (_film->info_path (f, eyes), "r"); + if (!ifi) { + LOG_GENERAL ("Existing frame %1 has no info file", f); + return false; + } + + libdcp::FrameInfo info (ifi); + fclose (ifi); + if (info.size == 0) { + LOG_GENERAL ("Existing frame %1 has no info file", f); + return false; + } + + /* Read the data from the MXF and hash it */ + dcpomatic_fseek (mxf, info.offset, SEEK_SET); + EncodedData data (info.size); + size_t const read = fread (data.data(), 1, data.size(), mxf); + if (read != static_cast (data.size ())) { + LOG_GENERAL ("Existing frame %1 is incomplete", f); + return false; + } + + string const existing_hash = md5_digest (data.data(), data.size()); + if (existing_hash != info.hash) { + LOG_GENERAL ("Existing frame %1 failed hash check", f); + return false; + } + + return true; +} void Writer::check_existing_picture_mxf () @@ -353,30 +541,40 @@ Writer::check_existing_picture_mxf () boost::filesystem::path p; p /= _film->internal_video_mxf_dir (); p /= _film->internal_video_mxf_filename (); - FILE* mxf = fopen (p.string().c_str(), "rb"); + FILE* mxf = fopen_boost (p, "rb"); if (!mxf) { - _film->log()->log (String::compose ("Could not open existing MXF at %1 (errno=%2)", p.string(), errno)); + LOG_GENERAL ("Could not open existing MXF at %1 (errno=%2)", p.string(), errno); return; } + int N = 0; + for (boost::filesystem::directory_iterator i (_film->info_dir ()); i != boost::filesystem::directory_iterator (); ++i) { + ++N; + } + while (1) { - /* Read the frame info as written */ - ifstream ifi (_film->info_path (_first_nonexistant_frame).c_str()); - libdcp::FrameInfo info (ifi); + shared_ptr job = _job.lock (); + assert (job); - /* Read the data from the MXF and hash it */ - fseek (mxf, info.offset, SEEK_SET); - EncodedData data (info.size); - fread (data.data(), 1, data.size(), mxf); - string const existing_hash = md5_digest (data.data(), data.size()); - - if (existing_hash != info.hash) { - _film->log()->log (String::compose (N_("Existing frame %1 failed hash check"), _first_nonexistant_frame)); - break; + if (N > 0) { + job->set_progress (float (_first_nonexistant_frame) / N); } - _film->log()->log (String::compose (N_("Have existing frame %1"), _first_nonexistant_frame)); + if (_film->three_d ()) { + if (!check_existing_picture_mxf_frame (mxf, _first_nonexistant_frame, EYES_LEFT)) { + break; + } + if (!check_existing_picture_mxf_frame (mxf, _first_nonexistant_frame, EYES_RIGHT)) { + break; + } + } else { + if (!check_existing_picture_mxf_frame (mxf, _first_nonexistant_frame, EYES_BOTH)) { + break; + } + } + + LOG_GENERAL ("Have existing frame %1", _first_nonexistant_frame); ++_first_nonexistant_frame; } @@ -398,11 +596,15 @@ Writer::can_fake_write (int frame) const bool operator< (QueueItem const & a, QueueItem const & b) { - return a.frame < b.frame; + if (a.frame != b.frame) { + return a.frame < b.frame; + } + + return static_cast (a.eyes) < static_cast (b.eyes); } bool operator== (QueueItem const & a, QueueItem const & b) { - return a.frame == b.frame; + return a.frame == b.frame && a.eyes == b.eyes; }