2 Replies Latest reply on Feb 27, 2014 11:59 AM by Mark Ross

    Error with RhoConnect server - timeout on wait

    Mark Ross

      When my app syncs with my RhoConnect server, I get timeout errors - often.  The process recovers (restarts node process?) and syncs successfully, so I dont think its an issue with the backend.  The RESTful backend can be hit directly with a browser or Fiddler and it never times out.  Here is the stacktrace when Rhoconenct (or node?) times out:

       

      192.168.3.136 - - [27/Feb/2014 10:43:38] "POST /rc/v1/app/login HTTP/1.1" 200 - 0.0300

      192.168.3.136 - - [27/Feb/2014 10:43:38] "GET /app/v1/Incident?p_size=2000&version=3 HTTP/1.1" 200 83 0.3300

      192.168.3.136 - - [27/Feb/2014 10:43:39] "GET /app/v1/Plant?p_size=2000&version=3 HTTP/1.1" 200 83 0.2300

      [6792][10:44:09.309 AM 2014-02-27] Timeout on wait, setting JavaScript result state to broken: execution expired

      [6792][10:44:09.310 AM 2014-02-27] C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/js-adapters/node_channel.rb:110:in

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/js-adapters/node_channel.rb:110:in `block in wait_for_result'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/js-adapters/node_channel.rb:97:in `wait_for_result'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/js-adapters/node_channel.rb:80:in `publish_channel'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/js-adapters/node_channel.rb:61:in `publish_channel_and_wait'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/controller/js_base.rb:77:in `block in js_block'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/engine.rb:55:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/engine.rb:55:in `run_query'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/engine.rb:32:in `block (2 levels) in do_

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/stats/record.rb:50:in `update'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/engine.rb:30:in `block in do_sync'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/source.rb:384:in `if_need_refresh'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/engine.rb:29:in `do_sync'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/runner.rb:25:in `run'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/handler/query/execute_methods.rb:13:in `execute_query_

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/server.rb:135:in `block in execute_api_call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/server.rb:109:in `catch_all'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/server.rb:131:in `execute_api_call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/server.rb:280:in `block in api4'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1593:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1593:in `block in compile!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:957:in `[]'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:957:in `block (3 levels) in route!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:976:in `route_eval'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:957:in `block (2 levels) in route!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:997:in `block in process_route'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:995:in `catch'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:995:in `process_route'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:955:in `block in route!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:954:in `each'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:954:in `route!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1067:in `block in dispatch!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1049:in `block in invoke'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1049:in `catch'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1049:in `invoke'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1064:in `dispatch!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:889:in `block in call!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1049:in `block in invoke'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1049:in `catch'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:1049:in `invoke'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:889:in `call!'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/sinatra-1.4.4/lib/sinatra/base.rb:877:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/middleware/current_user.rb:14:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/middleware/current_app.rb:12:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-1.5.2/lib/rack/session/abstract/id.rb:225:in `context'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-1.5.2/lib/rack/session/abstract/id.rb:220:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/async-rack-0.5.1/lib/async_rack/async_callback.rb:114:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/async-rack-0.5.1/lib/async_rack/async_callback.rb:91:in `block in new'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/middleware/stats.rb:13:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/middleware/stats.rb:13:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/middleware/body_content_type_parser.rb:31:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/middleware/x_domain_session_wrapper.rb:25:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-1.5.2/lib/rack/static.rb:119:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-protection-1.5.1/lib/rack/protection/xss_header.rb:18:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-protection-1.5.1/lib/rack/protection/path_traversal.rb:16:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-protection-1.5.1/lib/rack/protection/json_csrf.rb:18:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-protection-1.5.1/lib/rack/protection/base.rb:50:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-protection-1.5.1/lib/rack/protection/base.rb:50:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rack-protection-1.5.1/lib/rack/protection/frame_options.rb:31:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/rhoconnect-4.0.0/lib/rhoconnect/async.rb:51:in `block in call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/eventmachine-1.0.3-x86-mingw32/lib/eventmachine.rb:1037:in `call'

      C:/MotorolaRhoMobileSuite4.0.0/ruby/lib/ruby/gems/1.9.1/gems/eventmachine-1.0.3-x86-mingw32/lib/eventmachine.rb:1037:in `block in spawn_threadpool'

      [6792][10:44:09.340 AM 2014-02-27] ERROR: Cannot communicate with Node.js process.

      [6792][10:44:09.355 AM 2014-02-27] Stopping Node.js process: 5816

      [6792][10:44:09.364 AM 2014-02-27] Starting Node.js process: 6040

      [6792][10:44:09.383 AM 2014-02-27] ERROR: Cannot communicate with Node.js process.

      [6792][10:44:09.388 AM 2014-02-27] Stopping Node.js process: 6040

      [6792][10:44:09.398 AM 2014-02-27] Starting Node.js process: 5492

      192.168.3.136 - - [27/Feb/2014 10:44:09] "GET /app/v1/Shift?p_size=2000&version=3 HTTP/1.1" 200 660 604.6970

      192.168.3.136 - - [27/Feb/2014 10:44:39] "GET /app/v1/Incident?p_size=2000&version=3 HTTP/1.1" 200 83 0.0700

      192.168.3.136 - - [27/Feb/2014 10:44:39] "GET /app/v1/Plant?p_size=2000&version=3 HTTP/1.1" 200 83 0.0600

      192.168.3.136 - - [27/Feb/2014 10:44:39] "GET /app/v1/Unit?p_size=2000&version=3 HTTP/1.1" 200 82 0.0400

      192.168.3.136 - - [27/Feb/2014 10:44:39] "GET /app/v1/Shift?p_size=2000&version=3 HTTP/1.1" 200 660 0.0300

      192.168.3.136 - - [27/Feb/2014 10:44:39] "GET /app/v1/Shift?p_size=2000&version=3&token=162729849751386 HTTP/1.1" 200 82 0.0600

       

      I'm on Windows 7, 64 bit using RhoStudio and the 4.0 Javascript only APIs (no ruby code).  If you need any other details, let me know.

       

      Thanks,

      -Mark