BOOST_FOREACH.
[dcpomatic.git] / src / tools / dcpomatic_disk_writer.cc
index 5e76341ebe48559e7849893b790f1f53587ba059..6c4221d8a7b337c199b4eafb31f8c5d50488ce61 100644 (file)
 
 */
 
-#include "lib/disk_writer_messages.h"
 #include "lib/compose.hpp"
-#include "lib/exceptions.h"
 #include "lib/cross.h"
+#include "lib/dcpomatic_log.h"
 #include "lib/digester.h"
+#include "lib/disk_writer_messages.h"
+#include "lib/exceptions.h"
+#include "lib/ext.h"
 #include "lib/file_log.h"
-#include "lib/dcpomatic_log.h"
 #include "lib/nanomsg.h"
-extern "C" {
-#include <lwext4/ext4_mbr.h>
-#include <lwext4/ext4_fs.h>
-#include <lwext4/ext4_mkfs.h>
-#include <lwext4/ext4_errno.h>
-#include <lwext4/ext4_debug.h>
-#include <lwext4/ext4.h>
-}
+#include "lib/version.h"
+#include "lib/warnings.h"
 
 #ifdef DCPOMATIC_POSIX
 #include <sys/ioctl.h>
@@ -42,18 +37,16 @@ extern "C" {
 #endif
 
 #ifdef DCPOMATIC_OSX
+#include "lib/stdout_log.h"
 #undef nil
 extern "C" {
 #include <lwext4/file_dev.h>
 }
+#include <xpc/xpc.h>
 #endif
 
 #ifdef DCPOMATIC_LINUX
-#include <linux/fs.h>
 #include <polkit/polkit.h>
-extern "C" {
-#include <lwext4/file_dev.h>
-}
 #include <poll.h>
 #endif
 
@@ -63,7 +56,10 @@ extern "C" {
 }
 #endif
 
+DCPOMATIC_DISABLE_WARNINGS
 #include <glibmm.h>
+DCPOMATIC_ENABLE_WARNINGS
+
 #include <unistd.h>
 #include <sys/types.h>
 #include <boost/filesystem.hpp>
@@ -75,300 +71,24 @@ using std::min;
 using std::string;
 using std::runtime_error;
 using std::exception;
+using std::vector;
 using boost::optional;
 
-#ifdef DCPOMATIC_LINUX
-static PolkitAuthority* polkit_authority = 0;
-#endif
-static uint64_t const block_size = 4096;
-static Nanomsg* nanomsg = 0;
 
 #define SHORT_TIMEOUT 100
 #define LONG_TIMEOUT 2000
 
-static
-void
-count (boost::filesystem::path dir, uint64_t& total_bytes)
-{
-       using namespace boost::filesystem;
-       for (directory_iterator i = directory_iterator(dir); i != directory_iterator(); ++i) {
-               if (is_directory(*i)) {
-                       count (*i, total_bytes);
-               } else {
-                       total_bytes += file_size (*i);
-               }
-       }
-}
-
-static
-string
-write (boost::filesystem::path from, boost::filesystem::path to, uint64_t& total_remaining, uint64_t total)
-{
-       ext4_file out;
-       int r = ext4_fopen(&out, to.generic_string().c_str(), "wb");
-       if (r != EOK) {
-               throw CopyError (String::compose("Failed to open file %1", to.generic_string()), r);
-       }
-
-       FILE* in = fopen_boost (from, "rb");
-       if (!in) {
-               ext4_fclose (&out);
-               throw CopyError (String::compose("Failed to open file %1", from.string()), 0);
-       }
-
-       uint8_t* buffer = new uint8_t[block_size];
-       Digester digester;
-
-       uint64_t remaining = file_size (from);
-       while (remaining > 0) {
-               uint64_t const this_time = min(remaining, block_size);
-               size_t read = fread (buffer, 1, this_time, in);
-               if (read != this_time) {
-                       fclose (in);
-                       ext4_fclose (&out);
-                       delete[] buffer;
-                       throw CopyError (String::compose("Short read; expected %1 but read %2", this_time, read), 0);
-               }
-
-               digester.add (buffer, this_time);
-
-               size_t written;
-               r = ext4_fwrite (&out, buffer, this_time, &written);
-               if (r != EOK) {
-                       fclose (in);
-                       ext4_fclose (&out);
-                       delete[] buffer;
-                       throw CopyError ("Write failed", r);
-               }
-               if (written != this_time) {
-                       fclose (in);
-                       ext4_fclose (&out);
-                       delete[] buffer;
-                       throw CopyError (String::compose("Short write; expected %1 but wrote %2", this_time, written), 0);
-               }
-               remaining -= this_time;
-               total_remaining -= this_time;
-               nanomsg->send(String::compose(DISK_WRITER_PROGRESS "\n%1\n", (1 - float(total_remaining) / total)), SHORT_TIMEOUT);
-       }
-
-       fclose (in);
-       ext4_fclose (&out);
-       delete[] buffer;
-
-       return digester.get ();
-}
-
-static
-string
-read (boost::filesystem::path from, boost::filesystem::path to, uint64_t& total_remaining, uint64_t total)
-{
-       ext4_file in;
-       LOG_DISK("Opening %1 for read", to.generic_string());
-       int r = ext4_fopen(&in, to.generic_string().c_str(), "rb");
-       if (r != EOK) {
-               throw VerifyError (String::compose("Failed to open file %1", to.generic_string()), r);
-       }
-       LOG_DISK("Opened %1 for read", to.generic_string());
-
-       uint8_t* buffer = new uint8_t[block_size];
-       Digester digester;
-
-       uint64_t remaining = file_size (from);
-       while (remaining > 0) {
-               uint64_t const this_time = min(remaining, block_size);
-               size_t read;
-               r = ext4_fread (&in, buffer, this_time, &read);
-               if (read != this_time) {
-                       ext4_fclose (&in);
-                       delete[] buffer;
-                       throw VerifyError (String::compose("Short read; expected %1 but read %2", this_time, read), 0);
-               }
-
-               digester.add (buffer, this_time);
-               remaining -= this_time;
-               total_remaining -= this_time;
-               nanomsg->send(String::compose(DISK_WRITER_PROGRESS "\n%1\n", (1 - float(total_remaining) / total)), SHORT_TIMEOUT);
-       }
-
-       ext4_fclose (&in);
-       delete[] buffer;
-
-       return digester.get ();
-}
-
-
-/** @param from File to copy from.
- *  @param to Directory to copy to.
- */
-static
-void
-copy (boost::filesystem::path from, boost::filesystem::path to, uint64_t& total_remaining, uint64_t total)
-{
-       LOG_DISK ("Copy %1 -> %2", from.string(), to.generic_string());
-
-       using namespace boost::filesystem;
-
-       path const cr = to / from.filename();
-
-       if (is_directory(from)) {
-               int r = ext4_dir_mk (cr.generic_string().c_str());
-               if (r != EOK) {
-                       throw CopyError (String::compose("Failed to create directory %1", cr.generic_string()), r);
-               }
-
-               for (directory_iterator i = directory_iterator(from); i != directory_iterator(); ++i) {
-                       copy (i->path(), cr, total_remaining, total);
-               }
-       } else {
-               string const write_digest = write (from, cr, total_remaining, total);
-               LOG_DISK ("Wrote %1 %2 with %3", from.string(), cr.generic_string(), write_digest);
-               string const read_digest = read (from, cr, total_remaining, total);
-               LOG_DISK ("Read %1 %2 with %3", from.string(), cr.generic_string(), write_digest);
-               if (write_digest != read_digest) {
-                       throw VerifyError ("Hash of written data is incorrect", 0);
-               }
-       }
-}
-
-
-static
-void
-write (boost::filesystem::path dcp_path, string device)
-try
-{
-//     ext4_dmask_set (DEBUG_ALL);
-
-       /* We rely on static initialization for these */
-       static struct ext4_fs fs;
-       static struct ext4_mkfs_info info;
-       info.block_size = 1024;
-       info.inode_size = 128;
-       info.journal = false;
-
-#ifdef WIN32
-       file_windows_name_set(device.c_str());
-       struct ext4_blockdev* bd = file_windows_dev_get();
-#else
-       file_dev_name_set (device.c_str());
-       struct ext4_blockdev* bd = file_dev_get ();
-#endif
-
-       if (!bd) {
-               throw CopyError ("Failed to open drive", 0);
-       }
-       LOG_DISK_NC ("Opened drive");
-
-       struct ext4_mbr_parts parts;
-       parts.division[0] = 100;
-       parts.division[1] = 0;
-       parts.division[2] = 0;
-       parts.division[3] = 0;
-
-#ifdef DCPOMATIC_LINUX
-       PrivilegeEscalator e;
-#endif
-
-       /* XXX: not sure if disk_id matters */
-       int r = ext4_mbr_write (bd, &parts, 0);
-
-       if (r) {
-               throw CopyError ("Failed to write MBR", r);
-       }
-       LOG_DISK_NC ("Wrote MBR");
-
-#ifdef DCPOMATIC_WINDOWS
-       struct ext4_mbr_bdevs bdevs;
-       r = ext4_mbr_scan (bd, &bdevs);
-       if (r != EOK) {
-               throw CopyError ("Failed to read MBR", r);
-       }
-
-       file_windows_partition_set (bdevs.partitions[0].part_offset, bdevs.partitions[0].part_size);
-#endif
 
 #ifdef DCPOMATIC_LINUX
-       /* Re-read the partition table */
-       int fd = open(device.c_str(), O_RDONLY);
-       ioctl(fd, BLKRRPART, NULL);
-       close(fd);
-#endif
-
-#ifdef DCPOMATIC_LINUX
-       string partition = device;
-       /* XXX: don't know if this logic is sensible */
-       if (partition.size() > 0 && isdigit(partition[partition.length() - 1])) {
-               partition += "p1";
-       } else {
-               partition += "1";
-       }
-       file_dev_name_set (partition.c_str());
-       bd = file_dev_get ();
-#endif
-
-#ifdef DCPOMATIC_OSX
-       string partition = device + "s1";
-       file_dev_name_set (partition.c_str());
-       bd = file_dev_get ();
+static PolkitAuthority* polkit_authority = 0;
 #endif
-
-       if (!bd) {
-               throw CopyError ("Failed to open partition", 0);
-       }
-       LOG_DISK_NC ("Opened partition");
-
-       nanomsg->send(DISK_WRITER_FORMATTING "\n", SHORT_TIMEOUT);
-
-       r = ext4_mkfs(&fs, bd, &info, F_SET_EXT4);
-       if (r != EOK) {
-               throw CopyError ("Failed to make filesystem", r);
-       }
-       LOG_DISK_NC ("Made filesystem");
-
-       r = ext4_device_register(bd, "ext4_fs");
-       if (r != EOK) {
-               throw CopyError ("Failed to register device", r);
-       }
-       LOG_DISK_NC ("Registered device");
-
-       r = ext4_mount("ext4_fs", "/mp/", false);
-       if (r != EOK) {
-               throw CopyError ("Failed to mount device", r);
-       }
-       LOG_DISK_NC ("Mounted device");
-
-       uint64_t total_bytes = 0;
-       count (dcp_path, total_bytes);
-
-       /* XXX: this is a hack.  We are going to "treat" every byte twice; write it, and then verify it.  Double the
-        * bytes totals so that progress works itself out (assuming write is the same speed as read).
-        */
-       total_bytes *= 2;
-       copy (dcp_path, "/mp", total_bytes, total_bytes);
-
-       r = ext4_umount("/mp/");
-       if (r != EOK) {
-               throw CopyError ("Failed to unmount device", r);
-       }
-
-       ext4_device_unregister("ext4_fs");
-       if (!nanomsg->send(DISK_WRITER_OK "\n", LONG_TIMEOUT)) {
-               throw CommunicationFailedError ();
-       }
-} catch (CopyError& e) {
-       LOG_DISK("CopyError (from write): %1 %2", e.message(), e.number().get_value_or(0));
-       nanomsg->send(String::compose(DISK_WRITER_ERROR "\n%1\n%2\n", e.message(), e.number().get_value_or(0)), LONG_TIMEOUT);
-} catch (VerifyError& e) {
-       LOG_DISK("VerifyError (from write): %1 %2", e.message(), e.number());
-       nanomsg->send(String::compose(DISK_WRITER_ERROR "\n%1\n%2\n", e.message(), e.number()), LONG_TIMEOUT);
-} catch (exception& e) {
-       LOG_DISK("Exception (from write): %1", e.what());
-       nanomsg->send(String::compose(DISK_WRITER_ERROR "\n%1\n0\n", e.what()), LONG_TIMEOUT);
-}
+static Nanomsg* nanomsg = 0;
 
 struct Parameters
 {
        boost::filesystem::path dcp_path;
        std::string device;
+       std::string posix_partition;
 };
 
 #ifdef DCPOMATIC_LINUX
@@ -379,7 +99,7 @@ polkit_callback (GObject *, GAsyncResult* res, gpointer data)
        Parameters* parameters = reinterpret_cast<Parameters*> (data);
        PolkitAuthorizationResult* result = polkit_authority_check_authorization_finish (polkit_authority, res, 0);
        if (result && polkit_authorization_result_get_is_authorized(result)) {
-               write (parameters->dcp_path, parameters->device);
+               dcpomatic::write (parameters->dcp_path, parameters->device, parameters->posix_partition, nanomsg);
        }
        delete parameters;
        if (result) {
@@ -388,6 +108,7 @@ polkit_callback (GObject *, GAsyncResult* res, gpointer data)
 }
 #endif
 
+
 bool
 idle ()
 try
@@ -399,27 +120,30 @@ try
                return true;
        }
 
+       LOG_DISK("Writer receives command: %1", *s);
+
        if (*s == DISK_WRITER_QUIT) {
                exit (EXIT_SUCCESS);
+       } else if (*s == DISK_WRITER_PING) {
+               nanomsg->send(DISK_WRITER_PONG "\n", LONG_TIMEOUT);
        } else if (*s == DISK_WRITER_UNMOUNT) {
                /* XXX: should do Linux polkit stuff here */
-               optional<string> device = nanomsg->receive (LONG_TIMEOUT);
-               if (!device) {
+               optional<string> xml_head = nanomsg->receive (LONG_TIMEOUT);
+               optional<string> xml_body = nanomsg->receive (LONG_TIMEOUT);
+               if (!xml_head || !xml_body) {
+                       LOG_DISK_NC("Failed to receive unmount request");
                        throw CommunicationFailedError ();
                }
-               if (unmount_drive(*device)) {
-                       if (!nanomsg->send (DISK_WRITER_OK "\n", LONG_TIMEOUT)) {
-                               throw CommunicationFailedError();
-                       }
-               } else {
-                       if (!nanomsg->send (DISK_WRITER_ERROR "\n", LONG_TIMEOUT)) {
-                               throw CommunicationFailedError();
-                       }
+               bool const success = Drive(*xml_head + *xml_body).unmount();
+               if (!nanomsg->send (success ? (DISK_WRITER_OK "\n") : (DISK_WRITER_ERROR "\n"), LONG_TIMEOUT)) {
+                       LOG_DISK_NC("CommunicationFailedError in unmount_finished");
+                       throw CommunicationFailedError ();
                }
-       } else {
-               optional<string> dcp_path = nanomsg->receive(LONG_TIMEOUT);
-               optional<string> device = nanomsg->receive(LONG_TIMEOUT);
+       } else if (*s == DISK_WRITER_WRITE) {
+               optional<string> dcp_path = nanomsg->receive (LONG_TIMEOUT);
+               optional<string> device = nanomsg->receive (LONG_TIMEOUT);
                if (!dcp_path || !device) {
+                       LOG_DISK_NC("Failed to receive write request");
                        throw CommunicationFailedError();
                }
 
@@ -428,7 +152,7 @@ try
 #ifdef DCPOMATIC_OSX
                if (!starts_with(*device, "/dev/disk")) {
                        LOG_DISK ("Will not write to %1", *device);
-                       nanomsg->try_send(DISK_WRITER_ERROR "\nRefusing to write to this drive\n1\n", LONG_TIMEOUT);
+                       nanomsg->send(DISK_WRITER_ERROR "\nRefusing to write to this drive\n1\n", LONG_TIMEOUT);
                        return true;
                }
 #endif
@@ -442,15 +166,15 @@ try
 #ifdef DCPOMATIC_WINDOWS
                if (!starts_with(*device, "\\\\.\\PHYSICALDRIVE")) {
                        LOG_DISK ("Will not write to %1", *device);
-                       nanomsg->try_send(DISK_WRITER_ERROR "\nRefusing to write to this drive\n1\n", LONG_TIMEOUT);
+                       nanomsg->send(DISK_WRITER_ERROR "\nRefusing to write to this drive\n1\n", LONG_TIMEOUT);
                        return true;
                }
 #endif
 
                bool on_drive_list = false;
                bool mounted = false;
-               for (auto const& i: get_drives()) {
-                       if (i.internal_name() == *device) {
+               for (auto const& i: Drive::get()) {
+                       if (i.device() == *device) {
                                on_drive_list = true;
                                mounted = i.mounted();
                        }
@@ -471,15 +195,26 @@ try
 
 #ifdef DCPOMATIC_LINUX
                polkit_authority = polkit_authority_get_sync (0, 0);
-               PolkitSubject* subject = polkit_unix_process_new (getppid());
+               PolkitSubject* subject = polkit_unix_process_new_for_owner (getppid(), 0, -1);
                Parameters* parameters = new Parameters;
                parameters->dcp_path = *dcp_path;
                parameters->device = *device;
+               parameters->posix_partition = *device;
+               /* XXX: don't know if this logic is sensible */
+               if (parameters->posix_partition.size() > 0 && isdigit(parameters->posix_partition[parameters->posix_partition.length() - 1])) {
+                       parameters->posix_partition += "p1";
+               } else {
+                       parameters->posix_partition += "1";
+               }
                polkit_authority_check_authorization (
                                polkit_authority, subject, "com.dcpomatic.write-drive", 0, POLKIT_CHECK_AUTHORIZATION_FLAGS_ALLOW_USER_INTERACTION, 0, polkit_callback, parameters
                                );
 #else
-               write (*dcp_path, *device);
+               string posix_partition = "";
+#ifdef DCPOMATIC_OSX
+               posix_partition = *device + "s1";
+#endif
+               dcpomatic::write (*dcp_path, *device, posix_partition, nanomsg);
 #endif
        }
 
@@ -492,11 +227,27 @@ try
 int
 main ()
 {
+#ifdef DCPOMATIC_OSX
+       /* On macOS this is running as root, so config_path() will be somewhere in root's
+        * home.  Instead, just write to stdout as the macOS process control stuff will
+        * redirect this to a file in /var/log
+        */
+       dcpomatic_log.reset(new StdoutLog(LogEntry::TYPE_DISK));
+       LOG_DISK("dcpomatic_disk_writer %1 started", dcpomatic_git_commit);
+#else
        /* XXX: this is a hack, but I expect we'll need logs and I'm not sure if there's
         * a better place to put them.
         */
        dcpomatic_log.reset(new FileLog(config_path() / "disk_writer.log", LogEntry::TYPE_DISK));
        LOG_DISK_NC("dcpomatic_disk_writer started");
+#endif
+
+#ifdef DCPOMATIC_OSX
+       /* I *think* this consumes the notifyd event that we used to start the process, so we only
+        * get started once per notification.
+        */
+        xpc_set_event_stream_handler("com.apple.notifyd.matching", DISPATCH_TARGET_QUEUE_DEFAULT, ^(xpc_object_t) {});
+#endif
 
        try {
                nanomsg = new Nanomsg (false);