spec/dummy/log/test.log in ddr-models-1.13.0 vs spec/dummy/log/test.log in ddr-models-1.13.1

- old
+ new

@@ -325727,5 +325727,2529 @@  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (3.3ms) DELETE FROM "events"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (3.6ms) DELETE FROM "users"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (3.2ms) DELETE FROM "events"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.2ms) DELETE FROM "users"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (3.0ms) DELETE FROM "events"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.1ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.7ms) DELETE FROM "users"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.1ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (1.6ms) DELETE FROM "events"; +  (0.1ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.1ms) DELETE FROM sqlite_sequence where name = 'events'; +  (1.5ms) DELETE FROM "users"; +  (0.1ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.1ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (6.0ms) DELETE FROM "events"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.1ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.3ms) DELETE FROM "users"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (6.5ms) DELETE FROM "events"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.4ms) DELETE FROM "users"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (2.6ms) DELETE FROM "events"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.6ms) DELETE FROM "users"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (6.6ms) DELETE FROM "events"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.3ms) DELETE FROM "users"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (6.5ms) DELETE FROM "events"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.3ms) DELETE FROM "users"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (6.5ms) DELETE FROM "events"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (2.5ms) DELETE FROM "users"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +Using the default predicate_mappings.yml that comes with active-fedora. If you want to override this, pass the path to predicate_mappings.yml to ActiveFedora - ie. ActiveFedora.init(:predicate_mappings_config_path => '/path/to/predicate_mappings.yml') - or set Rails.root and put predicate_mappings.yml into #{Rails.root}/config. +  (171.3ms) DELETE FROM "events"; +  (0.3ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.2ms) DELETE FROM sqlite_sequence where name = 'events'; +  (233.6ms) DELETE FROM "users"; +  (0.2ms) SELECT name FROM sqlite_master WHERE type='table' AND name='sqlite_sequence'; +  (0.1ms) DELETE FROM sqlite_sequence where name = 'users'; +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading fedora config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/fedora.yml +Using the default fedora.yml that comes with active-fedora. If you want to override this, pass the path to fedora.yml to ActiveFedora - ie. ActiveFedora.init(:fedora_config_path => '/path/to/fedora.yml') - or set Rails.root and put fedora.yml into #{Rails.root}/config. +ActiveFedora: loading solr config from /Users/dc/.rvm/gems/ruby-2.1.2@ddr-models/gems/active-fedora-7.1.2/config/solr.yml +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3928" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3928' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3928' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:55.793568"], ["event_date_time", "2015-04-13 16:13:55.792495"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3928"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:13:55.793568"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.5ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:55.952497"], ["event_date_time", "2015-04-13 16:13:55.951899"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:13:55.952497"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:56.900398"], ["event_date_time", "2015-04-13 16:13:56.899319"], ["exception", "null"], ["outcome", "success"], ["software", "ddr-models 1.13.0.post"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:13:56.900398"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:56.913321"], ["event_date_time", "2015-04-13 16:13:56.912844"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:13:56.913321"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.2ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.3ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person13@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.4ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.094923"], ["email", "person13@example.com"], ["encrypted_password", "$2a$04$OLTWS.Wq.kCLPza8tuIwcOIOHugc5orsbj9W4sfptTJLFiW6304i2"], ["updated_at", "2015-04-13 16:13:58.094923"], ["username", "person13@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person14@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.128931"], ["email", "person14@example.com"], ["encrypted_password", "$2a$04$fwfqCU1yBmy.iV2okxOab.hfzZG7AXo/6I9AptaLhV5OguFsOI88O"], ["updated_at", "2015-04-13 16:13:58.128931"], ["username", "person14@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person15@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.166596"], ["email", "person15@example.com"], ["encrypted_password", "$2a$04$qlOQwGdlHha.CveizScaQem2rZcVspKmibaNs7oUmauyPl8Cb1PC2"], ["updated_at", "2015-04-13 16:13:58.166596"], ["username", "person15@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person16@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.180234"], ["email", "person16@example.com"], ["encrypted_password", "$2a$04$gHiWoKivr.EuxwoqE7nQn.8iCmB/HRGRVdlP7pzHGictC8PEdB.3i"], ["updated_at", "2015-04-13 16:13:58.180234"], ["username", "person16@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person17@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.194262"], ["email", "person17@example.com"], ["encrypted_password", "$2a$04$nmP1HChgNnIfZoCOTA8ruu6.68uR2Frr2Ti9or7mZIheN9wMQe.ZK"], ["updated_at", "2015-04-13 16:13:58.194262"], ["username", "person17@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person18@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.207957"], ["email", "person18@example.com"], ["encrypted_password", "$2a$04$eaZshF4MPpjYSjzHrZOdwu6nQYTH/Sq1i6noo2FgdEA8NQn7bcYCS"], ["updated_at", "2015-04-13 16:13:58.207957"], ["username", "person18@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person19@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.221654"], ["email", "person19@example.com"], ["encrypted_password", "$2a$04$kEM.w3V5wmsH4rI6I0kIAux8xGHFEKk/8NlB6kdAFKfQZMBk0ZvTi"], ["updated_at", "2015-04-13 16:13:58.221654"], ["username", "person19@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person20@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.235042"], ["email", "person20@example.com"], ["encrypted_password", "$2a$04$fFtq53oKF8ntZHeElG0J2.X5YLO7DaHtipI8e3XCam5E9qZ4knuvm"], ["updated_at", "2015-04-13 16:13:58.235042"], ["username", "person20@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person21@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.245870"], ["email", "person21@example.com"], ["encrypted_password", "$2a$04$oBXrpU9P5uOzKEKbtAddvOTW02bFiExtwVtrfU920.eNu0jIrwypC"], ["updated_at", "2015-04-13 16:13:58.245870"], ["username", "person21@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person22@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.255685"], ["email", "person22@example.com"], ["encrypted_password", "$2a$04$/v7Hr8XZnGjD9bBw4I5waeOVPKgrRtC.1sJRZi4ZQ1.uf6ymeop7u"], ["updated_at", "2015-04-13 16:13:58.255685"], ["username", "person22@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person23@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.269128"], ["email", "person23@example.com"], ["encrypted_password", "$2a$04$hVg5RoktnGxs/.lAslFny.T7mur.j.9dMIFXt4fFL5BS28iyTVDuy"], ["updated_at", "2015-04-13 16:13:58.269128"], ["username", "person23@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person24@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.283344"], ["email", "person24@example.com"], ["encrypted_password", "$2a$04$I4hz6.CVAxaruYShbF5I4OvRWEksmugNvVFCiCBRwQVpB2EoJNH1u"], ["updated_at", "2015-04-13 16:13:58.283344"], ["username", "person24@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person25@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.296544"], ["email", "person25@example.com"], ["encrypted_password", "$2a$04$pDWudYNMBA6rd4/DYlJ5AusRbcjU1TJrc8fI2l0Vl2FhZMl7QtGGS"], ["updated_at", "2015-04-13 16:13:58.296544"], ["username", "person25@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person26@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.310193"], ["email", "person26@example.com"], ["encrypted_password", "$2a$04$qcKiuHV7DHvWQ.vwusvk4eplPKBy3LDSrp4qBFl6XwAxjh0vn.AXG"], ["updated_at", "2015-04-13 16:13:58.310193"], ["username", "person26@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking read permissions for user: person26@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: ["person26@example.com"] +[CANCAN] read_users: ["person26@example.com"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person27@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.325687"], ["email", "person27@example.com"], ["encrypted_password", "$2a$04$HNRq60ynlfZKfuk8lzzEQ.38ac3W/iQE5IjUdPvaxiKNXU8O7V2zq"], ["updated_at", "2015-04-13 16:13:58.325687"], ["username", "person27@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking read permissions for user: person27@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: [] +[CANCAN] read_users: ["person27@example.com"] +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person28@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.340189"], ["email", "person28@example.com"], ["encrypted_password", "$2a$04$EhhzDntYMKVk2cHNm9KT2ulU/A4iTDfHpfn7pvFlG6V.XDYaFXWbm"], ["updated_at", "2015-04-13 16:13:58.340189"], ["username", "person28@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking read permissions for user: person28@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: [] +[CANCAN] read_users: [] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person29@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.356899"], ["email", "person29@example.com"], ["encrypted_password", "$2a$04$EOvoNa3QNcxRU3IlpUT42eII/4YPHk5Qh0pwAz4x4vX.EjPaSrEdm"], ["updated_at", "2015-04-13 16:13:58.356899"], ["username", "person29@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking edit permissions for user: person29@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] edit_users: ["person29@example.com"] +[CANCAN] decision: true +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person30@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.370657"], ["email", "person30@example.com"], ["encrypted_password", "$2a$04$iO5aJQyLYwnNd6ZAOKnMseKwkw7EUYQDznHg.akB8AaeDhkfTMZJq"], ["updated_at", "2015-04-13 16:13:58.370657"], ["username", "person30@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking edit permissions for user: person30@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] edit_users: [] +[CANCAN] decision: false +[CANCAN] Checking read permissions for user: person30@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: [] +[CANCAN] read_users: ["person30@example.com"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person31@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.388169"], ["email", "person31@example.com"], ["encrypted_password", "$2a$04$WvhQvGQl2CsNpsZUahDA3OAaR8xtavsIwqpDm1bawKRTJ.TielnCW"], ["updated_at", "2015-04-13 16:13:58.388169"], ["username", "person31@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking edit permissions for user: person31@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] edit_users: [] +[CANCAN] decision: false +[CANCAN] Checking read permissions for user: person31@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: [] +[CANCAN] read_users: [] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person32@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.407820"], ["email", "person32@example.com"], ["encrypted_password", "$2a$04$Ww8xelZ3wIB1PoFOBgJ5tuRpTW9aLahwRS5c3we0Ye56Czfy6Feua"], ["updated_at", "2015-04-13 16:13:58.407820"], ["username", "person32@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking edit permissions for user: person32@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] edit_users: ["person32@example.com"] +[CANCAN] decision: true +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person33@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.421401"], ["email", "person33@example.com"], ["encrypted_password", "$2a$04$IKxSuKvfEJn.OAbfnJrJWeSTwcnNIrhMtZVAAPZLejxTn.QxxK4Be"], ["updated_at", "2015-04-13 16:13:58.421401"], ["username", "person33@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking edit permissions for user: person33@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] edit_users: [] +[CANCAN] decision: false +[CANCAN] Checking read permissions for user: person33@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: [] +[CANCAN] read_users: ["person33@example.com"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person34@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.437981"], ["email", "person34@example.com"], ["encrypted_password", "$2a$04$V.bkVtkbTPmVRXwkfN4lSe0l6GbjvyV/oLYjfTk2cY6Gz195zgpue"], ["updated_at", "2015-04-13 16:13:58.437981"], ["username", "person34@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] Checking edit permissions for user: person34@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] edit_users: [] +[CANCAN] decision: false +[CANCAN] Checking read permissions for user: person34@example.com with groups: ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +[CANCAN] edit_groups: [] +[CANCAN] read_groups: [] +[CANCAN] edit_users: [] +[CANCAN] read_users: [] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person35@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.455775"], ["email", "person35@example.com"], ["encrypted_password", "$2a$04$FrNzwu67tyZMmTRDIvYiS.i8QX3IwdCy78Rf.KsJI2EBQYK66oFRe"], ["updated_at", "2015-04-13 16:13:58.455775"], ["username", "person35@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person36@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.471833"], ["email", "person36@example.com"], ["encrypted_password", "$2a$04$E8JhcTzR1Up2wTwp9Decl.wbMaAm3wE7r1RlIiDBzAhyLnbLakiq2"], ["updated_at", "2015-04-13 16:13:58.471833"], ["username", "person36@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person37@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.486563"], ["email", "person37@example.com"], ["encrypted_password", "$2a$04$QZFDrx.whBatH/r3Cadz9u.Z1wBGEwOFGNSIt1KKUjbC26kDVIsNu"], ["updated_at", "2015-04-13 16:13:58.486563"], ["username", "person37@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person38@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.497809"], ["email", "person38@example.com"], ["encrypted_password", "$2a$04$IL5mNXA6i0opcCVdsYUkresEUZoS.K1HY0peBz5Af1Hn875x/ldxC"], ["updated_at", "2015-04-13 16:13:58.497809"], ["username", "person38@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.8ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person39@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.508358"], ["email", "person39@example.com"], ["encrypted_password", "$2a$04$WVLYeRUP.ckkjp3Gw5wK7eMsW4eUPv6p8VfJVFxqcPFVW0xTi3uM2"], ["updated_at", "2015-04-13 16:13:58.508358"], ["username", "person39@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person40@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.519103"], ["email", "person40@example.com"], ["encrypted_password", "$2a$04$JOfmznF6zIpAvc5el80T.eFE7ZHMZdSmE4GdWBG3xipbLh7X6t3qi"], ["updated_at", "2015-04-13 16:13:58.519103"], ["username", "person40@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person41@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.529900"], ["email", "person41@example.com"], ["encrypted_password", "$2a$04$geVTuBmxjt9mMxp0DS.fDeVJKUhxuDKgIbuVatwUArzKpC3mpqpTu"], ["updated_at", "2015-04-13 16:13:58.529900"], ["username", "person41@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person42@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.540365"], ["email", "person42@example.com"], ["encrypted_password", "$2a$04$wu6y.05zsxwRqx9bFS2jLuAazy1V1FadCeNjG2CSkzsRzc3M1aF/O"], ["updated_at", "2015-04-13 16:13:58.540365"], ["username", "person42@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person43@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.550619"], ["email", "person43@example.com"], ["encrypted_password", "$2a$04$qM7kRiXlkGw/aIcN3qTud.Ix.xsEgZBznFUedPmkV.60Me1el3n4i"], ["updated_at", "2015-04-13 16:13:58.550619"], ["username", "person43@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person44@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.560048"], ["email", "person44@example.com"], ["encrypted_password", "$2a$04$0menXA.gJ8TV0Xru67xMnO3ETAkC42gBkKfbfQyU7wVTB5.q/ZKj2"], ["updated_at", "2015-04-13 16:13:58.560048"], ["username", "person44@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(collection_creators)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person45@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.2ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.568593"], ["email", "person45@example.com"], ["encrypted_password", "$2a$04$yWQaj3gsUFS.pbUWOTkwvuZb2XsY4ajSUhocZFQlz68AbM1pCgDQy"], ["updated_at", "2015-04-13 16:13:58.568593"], ["username", "person45@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person46@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.579941"], ["email", "person46@example.com"], ["encrypted_password", "$2a$04$dhZa23JjUc7ar7ISbVdA1u99cOLFQwoWxns84U5bc4Ref127c.d2e"], ["updated_at", "2015-04-13 16:13:58.579941"], ["username", "person46@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person47@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.590278"], ["email", "person47@example.com"], ["encrypted_password", "$2a$04$8VQtcAaZc.W.R8KHEzMTY.tJIfCxql1CGSCHkp8L7DwvTXxVzYVTi"], ["updated_at", "2015-04-13 16:13:58.590278"], ["username", "person47@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person48@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.604353"], ["email", "person48@example.com"], ["encrypted_password", "$2a$04$Zacs624h8wRAjzu9upTTKeEUav4oYbgRAL.Vtoen2dOGQkjGdv07i"], ["updated_at", "2015-04-13 16:13:58.604353"], ["username", "person48@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.1ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person49@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:58.619928"], ["email", "person49@example.com"], ["encrypted_password", "$2a$04$7r/W8Lnc/c8J2QRRrYt5B./sI3zgKjWc4O9vVpqFUKQRM.BS01kZq"], ["updated_at", "2015-04-13 16:13:58.619928"], ["username", "person49@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Usergroups are ["public", #<Ddr::Auth::Group(public)>, #<Ddr::Auth::Group(registered)>, "registered"] +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:13:59.048949"], ["event_date_time", "2015-04-13 16:13:59.048450"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["type", "Ddr::Events::ValidationEvent"], ["updated_at", "2015-04-13 16:13:59.048949"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::UpdateEvent') AND "events"."pid" = 'changeme:3932' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:02.688772"], ["event_date_time", "2015-04-13 16:14:02.687898"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3932"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate multires_image derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:02.688772"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (1.0ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:03.028590"], ["event_date_time", "2015-04-13 16:14:03.027633"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3932"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:03.028590"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::UpdateEvent') AND "events"."pid" = 'changeme:3932' +  (1.4ms) rollback transaction +  (0.1ms) begin transaction +  (0.2ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 +  (0.1ms) ROLLBACK TO SAVEPOINT active_record_1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 +  (0.1ms) ROLLBACK TO SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 +  (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 +  (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 +  (0.1ms) ROLLBACK TO SAVEPOINT active_record_1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:06.393780"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-d2jlry/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:06.193491"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3935"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:06.393780"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:06.621402"], ["event_date_time", "2015-04-13 16:14:06.620794"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3935"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:06.621402"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:06.625455"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-1qvxynz: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:05.059053"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3935"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:06.625455"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3935' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3935' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:07.044520"], ["event_date_time", "2015-04-13 16:14:07.043702"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3935"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:07.044520"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.5ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:08.313140"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-1iqioxc/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:08.114461"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3936"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:08.313140"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:08.545051"], ["event_date_time", "2015-04-13 16:14:08.544530"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3936"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:08.545051"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:08.548580"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-6jaql: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:07.064335"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3936"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:08.548580"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:08.960633"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image1.tiff20150413-14483-1xp1c1f: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:08.771027"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3936"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:08.960633"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3936' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:09.371602"], ["event_date_time", "2015-04-13 16:14:09.370934"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3936"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:09.371602"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (1.5ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3937' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3937' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:10.119042"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-1irbvqz: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:09.377969"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3937"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:10.119042"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3937' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3937' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:10.563621"], ["event_date_time", "2015-04-13 16:14:10.562938"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3937"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:10.563621"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.5ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3938" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3938' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3938' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3938' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:11.024264"], ["event_date_time", "2015-04-13 16:14:11.023548"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3938"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:11.024264"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3938' +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3939" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3939' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3939' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3940" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3940' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3940" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3940' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3939" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3939' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3939' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:13.460552"], ["event_date_time", "2015-04-13 16:14:13.459862"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3940"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:13.460552"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:13.573430"], ["event_date_time", "2015-04-13 16:14:13.572821"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3939"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:13.573430"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.0ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3941" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3941' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3941' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:14.584254"], ["event_date_time", "2015-04-13 16:14:14.583462"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3941"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:14.584254"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3942" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3942' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3942' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:15.268004"], ["event_date_time", "2015-04-13 16:14:15.267368"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3942"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:15.268004"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3943" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3943' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3943' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:16.038175"], ["event_date_time", "2015-04-13 16:14:16.037503"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3943"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:16.038175"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by AnonymousController#index as HTML +Completed 401 Unauthorized in 0ms + Rendered text template (0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by AnonymousController#index as HTML +Completed 401 Unauthorized in 0ms +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.2ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3944" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3944' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3944" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3944' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3945" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3945' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3945" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3945' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3946" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3946' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3946" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3946' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3944" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3944' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3945" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3945' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3946" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3946' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:19.317539"], ["event_date_time", "2015-04-13 16:14:19.316852"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3944"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:19.317539"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:19.437610"], ["event_date_time", "2015-04-13 16:14:19.436975"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3945"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:19.437610"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:19.579082"], ["event_date_time", "2015-04-13 16:14:19.578348"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3946"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:19.579082"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.4ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3947" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3947' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3947" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3947' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:20.707013"], ["event_date_time", "2015-04-13 16:14:20.706212"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3947"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:20.707013"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +Processing by Users::SessionsController#new as HTML +Completed 200 OK in 37ms (Views: 16.5ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by Users::SessionsController#new as HTML +Completed 200 OK in 2ms (Views: 0.9ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by Users::SessionsController#new as HTML +Completed 200 OK in 2ms (Views: 0.9ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by Users::SessionsController#new as HTML +Redirected to http://test.host/users/auth/shibboleth +Completed 302 Found in 1ms (ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by Users::SessionsController#new as HTML +Redirected to http://test.host/users/auth/shibboleth +Completed 302 Found in 1ms (ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:20.820185"], ["event_date_time", "2015-04-13 16:14:20.819185"], ["exception", "null"], ["outcome", "success"], ["software", "Fedora Repository 3.7.0"], ["summary", "Validation of datastream checksums"], ["type", "Ddr::Events::FixityCheckEvent"], ["updated_at", "2015-04-13 16:14:20.820185"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:20.849264"], ["event_date_time", "2015-04-13 16:14:20.848590"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "Fedora Repository 3.7.0"], ["summary", "Validation of datastream checksums"], ["type", "Ddr::Events::FixityCheckEvent"], ["updated_at", "2015-04-13 16:14:20.849264"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:21.256412"], ["event_date_time", "2015-04-13 16:14:21.255819"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object created in the repository"], ["type", "Ddr::Events::CreationEvent"], ["updated_at", "2015-04-13 16:14:21.256412"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person64@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:21.958565"], ["email", "person64@example.com"], ["encrypted_password", "$2a$04$MO6uIxDUPyS/Lku5sSXCxeBwpgZRODJFuE1bPoUyRnAbLp.GbmZ1i"], ["updated_at", "2015-04-13 16:14:21.958565"], ["username", "person64@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = 1 ORDER BY "users"."id" ASC LIMIT 1 +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person65@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.3ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:21.974867"], ["email", "person65@example.com"], ["encrypted_password", "$2a$04$NmQVluyK1AlcBkX.Nj.ee.fJ474N6Ck7KfezaBvP5RofTCUOqB74u"], ["updated_at", "2015-04-13 16:14:21.974867"], ["username", "person65@example.com"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 + User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = 1 ORDER BY "users"."id" ASC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3950' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3950' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3951' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3951' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3952' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3952' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:25.238157"], ["event_date_time", "2015-04-13 16:14:25.237445"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3950"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:25.238157"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:25.385117"], ["event_date_time", "2015-04-13 16:14:25.384515"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3951"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:25.385117"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:25.522217"], ["event_date_time", "2015-04-13 16:14:25.521492"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3952"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:25.522217"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (1.5ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + User Exists (0.2ms) SELECT 1 AS one FROM "users" WHERE LOWER("users"."username") = LOWER('person66@example.com') LIMIT 1 +Binary data inserted for `string` type on column `encrypted_password` + SQL (0.4ms) INSERT INTO "users" ("created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:25.534428"], ["email", "person66@example.com"], ["encrypted_password", "$2a$04$LUaQ3ixdd7u8Ygmdpd0PiO068XgbI0a7WxLlG1MRUApOthdZ36s7."], ["updated_at", "2015-04-13 16:14:25.534428"], ["username", "person66@example.com"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = 1 ORDER BY "users"."id" ASC LIMIT 1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3953" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3953' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3953" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3953' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:26.800492"], ["event_date_time", "2015-04-13 16:14:26.799698"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3953"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:26.800492"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3954' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3954' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:28.130430"], ["event_date_time", "2015-04-13 16:14:28.129796"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3954"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:28.130430"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3955" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3955' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3955" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3955' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:28.989447"], ["event_date_time", "2015-04-13 16:14:28.988624"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3955"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:28.989447"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3956" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3956' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3956' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:29.452190"], ["event_date_time", "2015-04-13 16:14:29.451410"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3956"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:29.452190"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3956' +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3957" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3957' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3957" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3957' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3958" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3958' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3958" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3958' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3957" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3957' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:32.036233"], ["event_date_time", "2015-04-13 16:14:32.035428"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3957"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:32.036233"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:32.177919"], ["event_date_time", "2015-04-13 16:14:32.177323"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3958"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:32.177919"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (1.4ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3959" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3959' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3959" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3959' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:33.192700"], ["event_date_time", "2015-04-13 16:14:33.192033"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3959"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:33.192700"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3960" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3960' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3960" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3960' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:34.322865"], ["event_date_time", "2015-04-13 16:14:34.322094"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3960"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:34.322865"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3961" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3961' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:35.390844"], ["event_date_time", "2015-04-13 16:14:35.390040"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3961"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:35.390844"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3962" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3962' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3962' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::DescriptiveMetadataDatastream @pid="changeme:3962" @dsid="descMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3962" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3962' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3962' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:36.201020"], ["event_date_time", "2015-04-13 16:14:36.200366"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3962"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:36.201020"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:36.221842"], ["event_date_time", "2015-04-13 16:14:36.221377"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object ingested into the repository"], ["type", "Ddr::Events::IngestionEvent"], ["updated_at", "2015-04-13 16:14:36.221842"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:36.590595"], ["event_date_time", "2015-04-13 16:14:36.590110"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["updated_at", "2015-04-13 16:14:36.590595"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3965" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3966" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3966" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3967" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3968" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3969" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.6ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:39.294782"], ["event_date_time", "2015-04-13 16:14:39.293947"], ["exception", "[\"Ezid::Error\",\"Ezid::Error\"]"], ["outcome", "failure"], ["pid", "changeme:3969"], ["software", "ezid-client 1.1.0 (EZID API Version 2)"], ["summary", "Permanent ID assignment"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:39.294782"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::UpdateEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::UpdateEvent') AND "events"."pid" = 'changeme:3969' ORDER BY event_date_time DESC LIMIT 1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::UpdateEvent') AND "events"."pid" IS NULL +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3970" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:40.307436"], ["detail", "Permanent ID: ark:/99999/fk4zzz\nPermanent URL: http://id.library.duke.edu/ark:/99999/fk4zzz\nEZID Metadata:\n_target: http://example.com\n"], ["event_date_time", "2015-04-13 16:14:40.306829"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3970"], ["software", "ezid-client 1.1.0 (EZID API Version 2)"], ["summary", "Permanent ID assignment"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:40.307436"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::UpdateEvent') AND "events"."pid" = 'changeme:3970' +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3973' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3973' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:42.488107"], ["event_date_time", "2015-04-13 16:14:42.487442"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3973"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:42.488107"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.0ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3974' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:43.246161"], ["event_date_time", "2015-04-13 16:14:43.245354"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3974"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:43.246161"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3975" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3975' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:44.065266"], ["event_date_time", "2015-04-13 16:14:44.064620"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3975"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:44.065266"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3976' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:44.742562"], ["event_date_time", "2015-04-13 16:14:44.741623"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3976"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:44.742562"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3977' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:45.723848"], ["event_date_time", "2015-04-13 16:14:45.722923"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3977"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:45.723848"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:45.893207"], ["event_date_time", "2015-04-13 16:14:45.892731"], ["exception", "\"Gah!\""], ["outcome", "failure"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object updated"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:45.893207"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.8ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3979" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3979' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3979' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:47.297664"], ["event_date_time", "2015-04-13 16:14:47.296950"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3979"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:47.297664"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3980" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3980' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3980' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3980" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3980' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3980' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:48.151663"], ["event_date_time", "2015-04-13 16:14:48.151005"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3980"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:48.151663"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:49.551199"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-cn7k3/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:49.339387"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3981"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:49.551199"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:49.818581"], ["event_date_time", "2015-04-13 16:14:49.818010"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3981"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate multires_image derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:49.818581"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:50.262912"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-14q59bx/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:50.043110"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3981"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:50.262912"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:50.507684"], ["event_date_time", "2015-04-13 16:14:50.507112"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3981"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:50.507684"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:50.511541"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-18ifs3s: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:48.269307"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3981"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:50.511541"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' ORDER BY event_date_time DESC LIMIT 1 +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3981' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:51.561727"], ["event_date_time", "2015-04-13 16:14:51.560891"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3981"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:51.561727"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (2.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:52.573918"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-uhdcju/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:52.370670"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:52.573918"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:52.841889"], ["event_date_time", "2015-04-13 16:14:52.841335"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate multires_image derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:52.841889"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:53.286246"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-1mae279/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:53.058079"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:53.286246"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:53.908799"], ["event_date_time", "2015-04-13 16:14:53.908189"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:14:53.908799"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:53.912193"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-1vw3t56: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:51.583141"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:53.912193"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:54.807562"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image1.tiff20150413-14483-rxuc5u: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:54.580838"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:54.807562"]] + Ddr::Events::FixityCheckEvent Load (0.4ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3982' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:55.289214"], ["event_date_time", "2015-04-13 16:14:55.288522"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3982"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:55.289214"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3983' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3983' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:56.055675"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-oi5ruc: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:14:55.295496"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3983"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:14:56.055675"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3983' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3983' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3983' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3983' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:56.749215"], ["event_date_time", "2015-04-13 16:14:56.748377"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3983"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:56.749215"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.9ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.5ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3984" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3984' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3984' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::DescriptiveMetadataDatastream @pid="changeme:3984" @dsid="descMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3984" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3984' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3984' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3984' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:57.343646"], ["event_date_time", "2015-04-13 16:14:57.343016"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3984"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:57.343646"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3984' +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3985" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3985' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3985' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3985" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3985' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3985' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3986" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3986' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3986" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3986' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3985" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3985' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3985' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:14:59.882111"], ["event_date_time", "2015-04-13 16:14:59.881287"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3985"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:14:59.882111"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:00.031693"], ["event_date_time", "2015-04-13 16:15:00.031057"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3986"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:00.031693"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3987" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3987' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3987' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3987" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3987' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3987' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:01.032896"], ["event_date_time", "2015-04-13 16:15:01.032212"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3987"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:01.032896"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.7ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3988" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3988' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3988' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3988" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3988' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3988' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:02.284947"], ["event_date_time", "2015-04-13 16:15:02.284149"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3988"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:02.284947"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3989" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3989' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3989' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3989" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3989' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3989' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:03.518761"], ["event_date_time", "2015-04-13 16:15:03.517948"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3989"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:03.518761"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3990" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3990' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3991" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3991' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3991' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::DescriptiveMetadataDatastream @pid="changeme:3991" @dsid="descMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3991" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3991' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3991' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3992" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3992' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3992' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::DescriptiveMetadataDatastream @pid="changeme:3992" @dsid="descMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3992" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3992' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3992' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3993" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3993' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3993' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::DescriptiveMetadataDatastream @pid="changeme:3993" @dsid="descMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3993" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3993' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:3993' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:07.939358"], ["event_date_time", "2015-04-13 16:15:07.938554"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3990"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:07.939358"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:08.073356"], ["event_date_time", "2015-04-13 16:15:08.072610"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3991"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:08.073356"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:08.211670"], ["event_date_time", "2015-04-13 16:15:08.210920"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3992"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:08.211670"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:08.346097"], ["event_date_time", "2015-04-13 16:15:08.345476"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3993"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:08.346097"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.3ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3994" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3994' ORDER BY event_date_time DESC LIMIT 1 +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3994' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:09.173852"], ["event_date_time", "2015-04-13 16:15:09.173247"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3994"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:09.173852"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:3994' +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3995" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3995' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3996" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3996' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3996" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3996' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3995" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3995' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:11.131232"], ["event_date_time", "2015-04-13 16:15:11.130437"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3996"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:11.131232"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:11.240846"], ["event_date_time", "2015-04-13 16:15:11.240177"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3995"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:11.240846"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3997" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3997' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:11.971124"], ["event_date_time", "2015-04-13 16:15:11.970328"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3997"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:11.971124"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3998" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3998' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:12.874550"], ["event_date_time", "2015-04-13 16:15:12.873662"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3998"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:12.874550"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:3999" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.5ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:3999' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:13.599887"], ["event_date_time", "2015-04-13 16:15:13.599224"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:3999"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:13.599887"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.2ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:14.508558"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-1i486wd/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:14.313672"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4000"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:14.508558"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:15.082875"], ["event_date_time", "2015-04-13 16:15:15.082347"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4000"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:15:15.082875"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:15.086360"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-64i4s0: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:13.629752"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4000"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:15.086360"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4000' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4000' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:15.483343"], ["event_date_time", "2015-04-13 16:15:15.482456"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4000"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:15.483343"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.5ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:16.375610"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/d20150413-14483-1phk7lg/output: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:16.176118"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4001"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:16.375610"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:16.603376"], ["event_date_time", "2015-04-13 16:15:16.602768"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4001"], ["software", "ddr-models 1.13.0.post"], ["summary", "Generate thumbnail derivative"], ["type", "Ddr::Events::UpdateEvent"], ["updated_at", "2015-04-13 16:15:16.603376"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:16.607085"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-bitvxz: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:15.502774"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4001"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:16.607085"]] + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:17.394194"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image1.tiff20150413-14483-klqo9u: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:17.199521"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4001"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:17.394194"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4001' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:17.790396"], ["event_date_time", "2015-04-13 16:15:17.789557"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4001"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:17.790396"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.8ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4002' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4002' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:18.286079"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/library-devil.tiff20150413-14483-12n0x4r: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:17.798118"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4002"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:18.286079"]] + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4002' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4002' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:18.724476"], ["event_date_time", "2015-04-13 16:15:18.723635"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4002"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:18.724476"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.5ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4003" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4003' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4003' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:4003' +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:19.479963"], ["event_date_time", "2015-04-13 16:15:19.479335"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4003"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:19.479963"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SELECT COUNT(*) FROM "events" WHERE "events"."type" IN ('Ddr::Events::DeletionEvent') AND "events"."pid" = 'changeme:4003' +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4004" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4004' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4004' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4005" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4005' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4005" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4005' ORDER BY event_date_time DESC LIMIT 1 +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4004" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.2ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4004' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4004' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:21.469483"], ["event_date_time", "2015-04-13 16:15:21.468662"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4004"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:21.469483"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:21.609651"], ["event_date_time", "2015-04-13 16:15:21.608919"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4005"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:21.609651"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.1ms) rollback transaction +  (0.1ms) begin transaction + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.4ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::FixityCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" IS NULL ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4006" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4006' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4006' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:22.379070"], ["event_date_time", "2015-04-13 16:15:22.378404"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4006"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:22.379070"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.6ms) rollback transaction +  (0.0ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4007" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4007' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4007' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:23.310355"], ["event_date_time", "2015-04-13 16:15:23.309540"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4007"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:23.310355"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.9ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Cowardly refusing to save a datastream with empty content: #<Ddr::Datastreams::AdministrativeMetadataDatastream @pid="changeme:4008" @dsid="adminMetadata" @controlGroup="M" changed="true" @mimeType="application/n-triples" > + Ddr::Events::FixityCheckEvent Load (0.3ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::FixityCheckEvent') AND "events"."pid" = 'changeme:4008' ORDER BY event_date_time DESC LIMIT 1 + Ddr::Events::VirusCheckEvent Load (0.1ms) SELECT "events".* FROM "events" WHERE "events"."type" IN ('Ddr::Events::VirusCheckEvent') AND "events"."pid" = 'changeme:4008' ORDER BY event_date_time DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:24.080555"], ["event_date_time", "2015-04-13 16:15:24.079840"], ["exception", "null"], ["outcome", "success"], ["pid", "changeme:4008"], ["software", "ddr-models 1.13.0.post"], ["summary", "Object deleted"], ["type", "Ddr::Events::DeletionEvent"], ["updated_at", "2015-04-13 16:15:24.080555"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (11.7ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:24.576187"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image1.tiff20150413-14483-144uf36: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:24.250507"], ["exception", "null"], ["outcome", "success"], ["pid", "test:543"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:24.576187"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:24.749998"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image2.tiff20150413-14483-gn9fpy: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:24.680633"], ["exception", "null"], ["outcome", "success"], ["pid", "test:543"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:24.749998"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:24.924494"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image3.tiff20150413-14483-w3sa6l: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:24.848370"], ["exception", "null"], ["outcome", "success"], ["pid", "test:543"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:24.924494"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.5ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:25.806872"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image1.tiff20150413-14483-12zxf1n: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:25.554999"], ["exception", "null"], ["outcome", "success"], ["pid", "test:543"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:25.806872"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:25.976204"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image2.tiff20150413-14483-5px029: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:25.906376"], ["exception", "null"], ["outcome", "success"], ["pid", "test:543"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:25.976204"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.1ms) INSERT INTO "events" ("created_at", "detail", "event_date_time", "exception", "outcome", "pid", "software", "summary", "type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", "2015-04-13 16:15:26.145673"], ["detail", "/var/folders/s7/p2473dwx3v1b9dyxbgtym0j14yr8hw/T/image3.tiff20150413-14483-14z7461: NOT SCANNED - using :null scanner adapter. (ddr-antivirus 1.3.2)"], ["event_date_time", "2015-04-13 16:15:26.075065"], ["exception", "null"], ["outcome", "success"], ["pid", "test:543"], ["software", "ddr-antivirus 1.3.2"], ["summary", "Content file scanned for viruses"], ["type", "Ddr::Events::VirusCheckEvent"], ["updated_at", "2015-04-13 16:15:26.145673"]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (1.1ms) rollback transaction