gistfile1.txt

I, [2015-09-01T14:56:50.560670 #24890]  INFO -- : Started POST "/concern/images" for 75.149.149.10 at 2015-09-01 14:56:50 +0000
I, [2015-09-01T14:56:50.562129 #24890]  INFO -- : Processing by CurationConcerns::ImagesController#create as HTML
I, [2015-09-01T14:56:50.562284 #24890]  INFO -- :   Parameters: {"utf8"=>"✓", "authenticity_token"=>"uW8Yz27QidnQlYVSfnLklIyjnQurotRaXUA5dl489uQGtrwUqTqdOzJ8ON7ouLR9GRuf6XPBp844ACCe6Fos9w==", "image"=>{"title"=>["Testing timing"], "creator"=>[""], "contributor"=>[""], "description"=>[""], "subject"=>[""], "publisher"=>[""], "source"=>[""], "language"=>[""], "files"=>#<ActionDispatch::Http::UploadedFile:0x000000061c3210 @tempfile=#<Tempfile:/tmp/RackMultipart20150901-24890-ooidmg.tiff>, @original_filename="Arch1.tiff", @content_type="image/tiff", @headers="Content-Disposition: form-data; name=\"image[files]\"; filename=\"Arch1.tiff\"\r\nContent-Type: image/tiff\r\n">, "visibility_during_embargo"=>"restricted", "embargo_release_date"=>"2015-09-02", "visibility_after_embargo"=>"open", "visibility_during_lease"=>"open", "lease_expiration_date"=>"2015-09-02", "visibility_after_lease"=>"restricted", "visibility"=>"restricted", "rights"=>""}, "commit"=>"Create Image"}
D, [2015-09-01T14:56:50.566335 #24890] DEBUG -- :   User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1  ORDER BY "users"."id" ASC LIMIT 1  [["id", 3]]
D, [2015-09-01T14:56:50.566781 #24890] DEBUG -- : Usergroups are ["public", "registered"]
W, [2015-09-01T14:56:50.626213 #24890]  WARN -- : unable to find batch to attach to
D, [2015-09-01T14:56:51.027068 #24890] DEBUG -- :   Load LDP (215.4ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g Service: 20129500
D, [2015-09-01T14:56:51.789063 #24890] DEBUG -- :   Load LDP (147.0ms) http://127.0.0.1:8080/fedora/rest/prod/c7/55/c0/09/c755c009-ca06-4c62-9759-4ff948eaefbd Service: 20129500
D, [2015-09-01T14:56:52.617542 #24890] DEBUG -- :   Load LDP (492.2ms)  Service: 20129500
D, [2015-09-01T14:56:53.778326 #24890] DEBUG -- :   Load LDP (180.5ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g Service: 20129500
D, [2015-09-01T14:56:54.629726 #24890] DEBUG -- :   Load LDP (165.8ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g/files Service: 20129500
D, [2015-09-01T14:56:55.364315 #24890] DEBUG -- :   Load LDP (197.9ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g Service: 20129500
D, [2015-09-01T14:56:56.227341 #24890] DEBUG -- :   Load LDP (200.8ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g Service: 20129500
I, [2015-09-01T14:56:56.827327 #24890]  INFO -- : [ActiveJob] Enqueued CharacterizeJob (Job ID: 4136c044-8d0a-4614-942f-8384c3025888) to Resque(characterize) with arguments: "sb397824g"
D, [2015-09-01T14:56:56.854195 #24890] DEBUG -- :   Load LDP (24.8ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g/files/8201e978-97a1-4ea2-aa8a-fca8cddf75bd/fcr:versions Service: 20129500
D, [2015-09-01T14:56:56.936755 #24890] DEBUG -- :   Load LDP (22.3ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g/files/8201e978-97a1-4ea2-aa8a-fca8cddf75bd/fcr:versions Service: 20129500
D, [2015-09-01T14:56:56.997054 #24890] DEBUG -- :    (0.2ms)  BEGIN
D, [2015-09-01T14:56:56.998429 #24890] DEBUG -- :   SQL (0.3ms)  INSERT INTO "version_committers" ("version_id", "committer_login", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id"  [["version_id", "http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g/files/8201e978-97a1-4ea2-aa8a-fca8cddf75bd/fcr:versions/87a0a8c317f1e7f03656d5-0c53-4573-9b69-286abc94fc55"], ["committer_login", "[email protected]"], ["created_at", "2015-09-01 14:56:56.997272"], ["updated_at", "2015-09-01 14:56:56.997272"]]
D, [2015-09-01T14:56:57.000750 #24890] DEBUG -- :    (1.9ms)  COMMIT
D, [2015-09-01T14:56:57.382977 #24890] DEBUG -- :   Load LDP (166.0ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z Service: 20129500
I, [2015-09-01T14:56:58.030801 #10320]  INFO -- : [ActiveJob] [CharacterizeJob] [4136c044-8d0a-4614-942f-8384c3025888] Performing CharacterizeJob from Resque(characterize) with arguments: "sb397824g"
I, [2015-09-01T14:56:58.292090 #10320]  INFO -- : [ActiveJob] [CharacterizeJob] [4136c044-8d0a-4614-942f-8384c3025888] Attempted to init base path `prod`, but it already exists
D, [2015-09-01T14:56:58.526349 #24890] DEBUG -- :   Load LDP (283.5ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z/members Service: 20129500
D, [2015-09-01T14:56:59.753891 #24890] DEBUG -- :   Load LDP (424.3ms) http://127.0.0.1:8080/fedora/rest/prod/sb/39/78/24/sb397824g Service: 20129500
D, [2015-09-01T14:57:01.530354 #24890] DEBUG -- :   Load LDP (314.1ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z/members/e96b8ead-c04b-4509-98c6-c52dfe6e95d2 Service: 20129500
D, [2015-09-01T14:57:02.713906 #24890] DEBUG -- :   Load LDP (542.2ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z/members/e96b8ead-c04b-4509-98c6-c52dfe6e95d2 Service: 20129500
D, [2015-09-01T14:57:04.412325 #24890] DEBUG -- :   Load LDP (455.3ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z/members/e96b8ead-c04b-4509-98c6-c52dfe6e95d2 Service: 20129500
D, [2015-09-01T14:57:05.868751 #24890] DEBUG -- :   Load LDP (433.2ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z Service: 20129500
D, [2015-09-01T14:57:08.107969 #24890] DEBUG -- :   Load LDP (550.4ms) http://127.0.0.1:8080/fedora/rest/prod/ab/e5/0b/70/abe50b70-4c00-47b6-877f-9461700a058d Service: 20129500
D, [2015-09-01T14:57:10.178938 #24890] DEBUG -- :   Load LDP (649.0ms) http://127.0.0.1:8080/fedora/rest/prod/mk/61/rg/92/mk61rg92z Service: 20129500
I, [2015-09-01T14:57:12.937350 #24890]  INFO -- : Redirected to http://wustl.curationexperts.com/concern/images/mk61rg92z
I, [2015-09-01T14:57:12.937549 #24890]  INFO -- : Completed 302 Found in 22375ms (ActiveRecord: 2.7ms)