(351.6ms) CREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB  (204.6ms) CREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)  (0.5ms) SELECT `schema_migrations`.`version` FROM `schema_migrations`  Migrating to CreateImports (20110925192802)  (106.9ms) CREATE TABLE `imports` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `type` varchar(255), `input` text, `parameters` text, `email` varchar(255), `author_id` int(11), `parent_type` varchar(255), `parent_id` int(11), `exception` text, `state` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB  (130.0ms) CREATE INDEX `index_imports_on_type` ON `imports` (`type`)  (50.0ms) CREATE INDEX `index_imports_on_author_id` ON `imports` (`author_id`)  (51.0ms) CREATE INDEX `index_imports_on_parent_type_and_parent_id` ON `imports` (`parent_type`, `parent_id`)  (102.3ms) CREATE INDEX `index_imports_on_state` ON `imports` (`state`)  (9.7ms) INSERT INTO `schema_migrations` (`version`) VALUES ('20110925192802')  (0.2ms) SELECT `schema_migrations`.`version` FROM `schema_migrations`  (287.1ms) DROP DATABASE IF EXISTS `dummy_import_test`  (0.2ms) CREATE DATABASE `dummy_import_test` DEFAULT CHARACTER SET `utf8` COLLATE `utf8_unicode_ci`  (42.3ms) CREATE TABLE `imports` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `type` varchar(255), `input` text, `parameters` text, `email` varchar(255), `author_id` int(11), `parent_type` varchar(255), `parent_id` int(11), `exception` text, `state` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB  (67.8ms) CREATE INDEX `index_imports_on_author_id` ON `imports` (`author_id`)  (120.3ms) CREATE INDEX `index_imports_on_parent_type_and_parent_id` ON `imports` (`parent_type`, `parent_id`)  (112.3ms) CREATE INDEX `index_imports_on_state` ON `imports` (`state`)  (56.6ms) CREATE INDEX `index_imports_on_type` ON `imports` (`type`)  (25.0ms) CREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB  (33.9ms) CREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)  (0.6ms) SELECT version FROM `schema_migrations`  (3.1ms) INSERT INTO `schema_migrations` (version) VALUES ('20110925192802') Started GET "/artists" for 127.0.0.1 at 2011-09-25 22:08:23 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 1ms RuntimeError (ok): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (5.5ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.4ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (156.2ms) Started GET "/artists" for 127.0.0.1 at 2011-09-25 22:09:29 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 7ms NameError (uninitialized constant Import::NoRelation): app/models/importer/artist.rb:1:in `' app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.8ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (6.7ms) Started GET "/artists" for 127.0.0.1 at 2011-09-25 22:14:35 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 9ms NameError (uninitialized constant Import::NoRelation): app/models/importer/artist.rb:1:in `' app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (4.0ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (138.8ms) Started GET "/artists" for 127.0.0.1 at 2011-09-25 22:33:38 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 136ms RuntimeError (#): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.8ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (39.0ms)  (0.2ms) SELECT `schema_migrations`.`version` FROM `schema_migrations`  Migrating to CreateImports (20110925192802) Migrating to CreateMusicArtists (20110925204213)  (0.1ms) SELECT `schema_migrations`.`version` FROM `schema_migrations`  Migrating to CreateImports (20110925192802) Migrating to CreateMusicArtists (20110925204213)  (15.6ms) CREATE TABLE `music_artists` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `name` varchar(255), `tag_list` text, `author_id` int(11), `state` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB  (3.7ms) INSERT INTO `schema_migrations` (`version`) VALUES ('20110925204213')  (0.3ms) SELECT `schema_migrations`.`version` FROM `schema_migrations`  (89.3ms) DROP DATABASE IF EXISTS `dummy_import_test`  (0.2ms) CREATE DATABASE `dummy_import_test` DEFAULT CHARACTER SET `utf8` COLLATE `utf8_unicode_ci`  (43.9ms) CREATE TABLE `imports` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `type` varchar(255), `input` text, `parameters` text, `email` varchar(255), `author_id` int(11), `parent_type` varchar(255), `parent_id` int(11), `exception` text, `state` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB  (29.9ms) CREATE INDEX `index_imports_on_author_id` ON `imports` (`author_id`)  (84.3ms) CREATE INDEX `index_imports_on_parent_type_and_parent_id` ON `imports` (`parent_type`, `parent_id`)  (25.1ms) CREATE INDEX `index_imports_on_state` ON `imports` (`state`)  (32.0ms) CREATE INDEX `index_imports_on_type` ON `imports` (`type`)  (25.5ms) CREATE TABLE `music_artists` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `name` varchar(255), `tag_list` text, `author_id` int(11), `state` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB  (6.0ms) CREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB  (92.6ms) CREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)  (0.2ms) SELECT version FROM `schema_migrations`  (18.4ms) INSERT INTO `schema_migrations` (version) VALUES ('20110925204213')  (77.3ms) INSERT INTO `schema_migrations` (version) VALUES ('20110925192802') Started GET "/artists" for 127.0.0.1 at 2011-09-26 02:39:25 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 33ms NoMethodError (undefined method `import' for #): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.7ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (33.5ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 02:40:06 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 21ms NoMethodError (undefined method `import' for #): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.9ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.5ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (38.3ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 02:41:54 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 18ms NoMethodError (undefined method `import' for #): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.3ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (101.0ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 02:59:41 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 92ms TypeError (can't convert Time into String): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (68.5ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:02:04 +0200 Processing by ArtistsController#index as HTML  (82.1ms) SELECT 1 FROM `music_artists` WHERE LOWER(`music_artists`.`name`) = LOWER('Paradise Lost') LIMIT 1  (14.1ms) SHOW VARIABLES like 'max_allowed_packet'; Class Create Many Without Validations Or Callbacks (5.1ms) INSERT INTO `music_artists` (`name`,`created_at`,`updated_at`) VALUES ('Paradise Lost','2011-09-26 01:02:04','2011-09-26 01:02:04') ON DUPLICATE KEY UPDATE `music_artists`.`updated_at`=VALUES(`updated_at`) Completed 500 Internal Server Error in 466ms NameError (uninitialized constant Importer::Artist): app/controllers/artists_controller.rb:4:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (3.6ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (11.4ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:04:20 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 118ms RuntimeError (#): app/controllers/artists_controller.rb:4:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.3ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (55.9ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:05:23 +0200 Processing by ArtistsController#index as HTML  (0.2ms) BEGIN SQL (0.5ms) INSERT INTO `imports` (`author_id`, `created_at`, `email`, `exception`, `input`, `parameters`, `parent_id`, `parent_type`, `state`, `type`, `updated_at`) VALUES (NULL, '2011-09-26 01:05:23', NULL, NULL, 'Paradise Lost', '--- {}\n', NULL, NULL, 'draft', 'Importer::Music::Artist', '2011-09-26 01:05:23')  (3.9ms) COMMIT Music::Artist Load (3.2ms) SELECT music_artists.id AS id, music_artists.name AS name FROM music_artists WHERE (music_artists.name = 'Paradise Lost') Completed 500 Internal Server Error in 199ms RuntimeError (Music::Artist): app/controllers/artists_controller.rb:6:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.4ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (12.4ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:06:22 +0200 Processing by ArtistsController#index as HTML  (0.2ms) BEGIN SQL (0.3ms) INSERT INTO `imports` (`author_id`, `created_at`, `email`, `exception`, `input`, `parameters`, `parent_id`, `parent_type`, `state`, `type`, `updated_at`) VALUES (NULL, '2011-09-26 01:06:22', NULL, NULL, 'Paradise Lost', '--- {}\n', NULL, NULL, 'draft', 'Importer::Music::Artist', '2011-09-26 01:06:22')  (26.5ms) COMMIT Music::Artist Load (2.9ms) SELECT music_artists.id AS id, music_artists.name AS name FROM music_artists WHERE (music_artists.name = 'Paradise Lost') Completed 500 Internal Server Error in 75ms RuntimeError (Music::Artist): app/controllers/artists_controller.rb:6:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.3ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (14.5ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:07:26 +0200 Processing by ArtistsController#index as HTML  (3.0ms) BEGIN SQL (0.5ms) INSERT INTO `imports` (`author_id`, `created_at`, `email`, `exception`, `input`, `parameters`, `parent_id`, `parent_type`, `state`, `type`, `updated_at`) VALUES (NULL, '2011-09-26 01:07:26', NULL, NULL, 'Paradise Lost', '--- {}\n', NULL, NULL, 'draft', 'Importer::Music::Artist', '2011-09-26 01:07:26')  (4.1ms) COMMIT Music::Artist Load (2.1ms) SELECT music_artists.id AS id, music_artists.name AS name FROM music_artists WHERE (music_artists.name = 'Paradise Lost') Completed 500 Internal Server Error in 232ms NoMethodError (undefined method `import' for #): app/controllers/artists_controller.rb:6:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.4ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (32.3ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:08:34 +0200 Processing by ArtistsController#index as HTML  (0.3ms) BEGIN SQL (0.7ms) INSERT INTO `imports` (`author_id`, `created_at`, `email`, `exception`, `input`, `parameters`, `parent_id`, `parent_type`, `state`, `type`, `updated_at`) VALUES (NULL, '2011-09-26 01:08:34', NULL, NULL, 'Paradise Lost', '--- {}\n', NULL, NULL, 'draft', 'Importer::Music::Artist', '2011-09-26 01:08:34')  (4.6ms) COMMIT Music::Artist Load (0.4ms) SELECT music_artists.id AS id, music_artists.name AS name FROM music_artists WHERE (music_artists.name = 'Paradise Lost') Completed 500 Internal Server Error in 240ms RuntimeError (Music::Artist): app/controllers/artists_controller.rb:6:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (5.2ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (65.2ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:08:48 +0200 Processing by ArtistsController#index as HTML Completed 500 Internal Server Error in 76ms NoMethodError (undefined method `import' for #): app/controllers/artists_controller.rb:3:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (5.9ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (15.7ms) Started GET "/artists" for 127.0.0.1 at 2011-09-26 03:10:41 +0200 Processing by ArtistsController#index as HTML  (1.7ms) BEGIN SQL (3.0ms) INSERT INTO `imports` (`author_id`, `created_at`, `email`, `exception`, `input`, `parameters`, `parent_id`, `parent_type`, `state`, `type`, `updated_at`) VALUES (NULL, '2011-09-26 01:10:41', NULL, NULL, 'Paradise Lost', '--- {}\n', NULL, NULL, 'draft', 'Importer::Music::Artist', '2011-09-26 01:10:41')  (37.1ms) COMMIT Music::Artist Load (0.3ms) SELECT music_artists.id AS id, music_artists.name AS name FROM music_artists WHERE (music_artists.name = 'Paradise Lost')  (0.4ms) SHOW VARIABLES like 'max_allowed_packet'; Class Create Many Without Validations Or Callbacks (9.7ms) INSERT INTO `music_artists` (`name`,`created_at`,`updated_at`) VALUES ('Paradise Lost','2011-09-26 01:10:41','2011-09-26 01:10:41') ON DUPLICATE KEY UPDATE `music_artists`.`updated_at`=VALUES(`updated_at`) Completed 500 Internal Server Error in 256ms NameError (undefined local variable or method `e' for #): app/controllers/artists_controller.rb:6:in `index' Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.0ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.3ms) Rendered /home/murdoch/.rvm/gems/ruby-1.9.2-p290@import-gem/gems/actionpack-3.1.0/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (38.8ms)