[Rails] strange errors in dev.log and webserver log
András Tarsoly
tarsolya at gmail.com
Tue Sep 26 14:40:36 GMT 2006
Hi guys,
I have encountered a strange error in one of my applications.
The details are the following:
The application is a very simple one, I have one model, Cards, it has
some pretty basic stuff, some after_save methods for saving uploaded
files to their places.
I have an UI where I can manage the Card records, with the default
methods, including 'edit'
cards_controller.rb:
# def edit
# logger.info "Loading Card with ID: #{params[:id]}"
# @card = Card.find( params[:id] )
# end
Now each time I use the /cards/edit/3 (or any other valid id), I got
my card object properly loaded up onto the admin ui.
However - and here comes the strangeness - I see the following errors
in my dev and web logs:
development.log:
Processing CardsController#edit (for 192.168.2.6 at 2006-09-26 16:34:01) [GET]
Session ID: 548258e6b4bb56506af5cb9859226509
Parameters: {"action"=>"edit", "id"=>"5", "controller"=>"cards"}
Loading Card with ID: 5
Card Load (0.003020) SELECT * FROM cards WHERE (cards.id = '5') LIMIT 1
Rendering within layouts/admin
Rendering cards/edit
Card Columns (0.004928) SHOW FIELDS FROM cards
Tag Load (0.001597) SELECT tags.* FROM tags INNER JOIN taggings ON
tags.id = taggings.tag_id WHERE (taggings.taggable_id = 5 AND
taggings.taggable_type = 'Card')
Tag Load (0.002008) SELECT * FROM tags
Tag Columns (0.003178) SHOW FIELDS FROM tags
Rendered cards/_form (0.02718)
Completed in 0.05205 (19 reqs/sec) | Rendering: 0.02855 (54%) | DB:
0.01755 (33%) | 200 OK [http://dev/cards/edit/5]
Processing IndexController#not_found (for 192.168.2.6 at 2006-09-26
16:34:01) [GET]
Session ID: 548258e6b4bb56506af5cb9859226509
Parameters: {"anything"=>["images", "fieldbg.gif"],
"action"=>"not_found", "controller"=>"index"}
Rendering public/404.html
Completed in 0.00269 (371 reqs/sec) | Rendering: 0.00098 (36%) | DB:
0.00000 (0%) | 200 OK [http://dev/images/fieldbg.gif]
Processing CardsController#edit (for 192.168.2.6 at 2006-09-26 16:34:01) [GET]
Session ID: 548258e6b4bb56506af5cb9859226509
Parameters: {"action"=>"edit", "id"=>"undefined", "controller"=>"cards"}
Loading Card with ID: undefined
Card Load (0.002735) SELECT * FROM cards WHERE (cards.id =
'undefined') LIMIT 1
ActiveRecord::RecordNotFound (Couldn't find Card with ID=undefined):
/usr/lib/ruby/gems/1.8/gems/activerecord-1.14.4/lib/active_record/base.rb:955:in
`find_one'
/usr/lib/ruby/gems/1.8/gems/activerecord-1.14.4/lib/active_record/base.rb:941:in
`find_from_ids'
/usr/lib/ruby/gems/1.8/gems/activerecord-1.14.4/lib/active_record/base.rb:382:in
`find'
.//app/controllers/cards_controller.rb:36:in `edit'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/base.rb:941:in
`perform_action_without_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/filters.rb:368:in
`perform_action_without_benchmark'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/benchmarking.rb:69:in
`perform_action_without_rescue'
/usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/benchmarking.rb:69:in
`perform_action_without_rescue'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/rescue.rb:82:in
`perform_action'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/base.rb:408:in
`process_without_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/filters.rb:377:in
`process_without_session_management_support'
/usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/session_management.rb:117:in
`process'
/usr/lib/ruby/gems/1.8/gems/rails-1.1.6/lib/dispatcher.rb:38:in `dispatch'
/usr/lib/ruby/gems/1.8/gems/rails-1.1.6/lib/webrick_server.rb:115:in
`handle_dispatch'
/usr/lib/ruby/gems/1.8/gems/rails-1.1.6/lib/webrick_server.rb:81:in
`service'
/usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
/usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
/usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
/usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
/usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
/usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
/usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
/usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
/usr/lib/ruby/gems/1.8/gems/rails-1.1.6/lib/webrick_server.rb:67:in
`dispatch'
/usr/lib/ruby/gems/1.8/gems/rails-1.1.6/lib/commands/servers/webrick.rb:59
/usr/lib/ruby/1.8/rubygems/custom_require.rb:21:in `require'
/usr/lib/ruby/gems/1.8/gems/activesupport-1.3.1/lib/active_support/dependencies.rb:147:in
`require'
/usr/lib/ruby/gems/1.8/gems/rails-1.1.6/lib/commands/server.rb:30
/usr/lib/ruby/1.8/rubygems/custom_require.rb:21:in `require'
/usr/lib/ruby/gems/1.8/gems/activesupport-1.3.1/lib/active_support/dependencies.rb:147:in
`require'
script/server:3
Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-1.12.5/lib/action_controller/templates/rescues/layout.rhtml
(500 Internal Error)
--
Webrick log:
192.168.2.6 - - [26/Sep/2006:16:34:01 CEST] "GET /cards/edit/5
HTTP/1.1" 200 3981
http://dev:3000/admin -> /cards/edit/5
...
192.168.2.6 - - [26/Sep/2006:16:34:01 CEST] "GET /cards/edit/undefined
HTTP/1.1" 500 7649
http://dev:3000/cards/edit/5 -> /cards/edit/undefined
--
As you can see, the first request loads up properly, the page with the
admin ui is fine, no errors whatsoever, but before the request
completes, there is another /cards/edit/undefined request somehow,
which throws an exception ... and gets an internal server error.
However, the page doesn't shows this, everything loads up properly, I
can save everything properly, everything working as it should.
Any clue what might be causing this?
I have attached the appropriate files for further reference.
Thanks for your help in advance,
András
--
András Tarsoly
tarsolya at gmail.com
-------------- next part --------------
A non-text attachment was scrubbed...
Name: cards_controller.rb
Type: application/octet-stream
Size: 1226 bytes
Desc: not available
Url : http://wrath.rubyonrails.org/pipermail/rails/attachments/20060926/630d606a/cards_controller-0001.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: _form.rhtml
Type: application/octet-stream
Size: 2357 bytes
Desc: not available
Url : http://wrath.rubyonrails.org/pipermail/rails/attachments/20060926/630d606a/_form-0001.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: edit.rhtml
Type: application/octet-stream
Size: 136 bytes
Desc: not available
Url : http://wrath.rubyonrails.org/pipermail/rails/attachments/20060926/630d606a/edit-0001.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: card.rb
Type: application/octet-stream
Size: 1197 bytes
Desc: not available
Url : http://wrath.rubyonrails.org/pipermail/rails/attachments/20060926/630d606a/card-0001.obj
More information about the Rails
mailing list