erlide_eclipse icon indicating copy to clipboard operation
erlide_eclipse copied to clipboard

Trace plugins fail with badarg

Open sylane opened this issue 12 years ago • 2 comments

System: Ubuntu 12.10 64 bits with Eclipse 3.8, erlide release and Erlang R16B.

I am trying to make the tracing plugin works. I have a very simple module called dummy.erl that loops over a function for a long time with a sleep of on second. I am trying to get some traces but the plugins fail with the error:

Error
{badrpc,{'EXIT',{badarg,[{ttbe,stop_return,2,[]},{ttb_integration,stop,0,[]},{rpc,'-handle_call_call/6-fun-0-',5,[{file,"rpc.erl"},{line,205}]}]}}}

Maybe I am doing something wrong, here is what I am doing:

  • Configure the erlang application:
  • Start the application
  • In the Erlang tracing control panel:
    • Click "Add existing Nodes"
    • Select [email protected]
    • Set the cookie to dummy
    • Select call in the "Process" tab
    • Add a pattern
    • Select "Enabled", "Local"
    • Set the module name to dummy
    • Click "Start Tracing"
    • Wait for some time (Noting happen in any views)
    • Click "Stop Tracing"

Console log when starting the application:

20:02:24,992 F: (ErlangLaunchDelegate.java:90) : doLaunch runtime r16b
20:02:24,992 F: (ErlangLaunchDelegate.java:91) : doLaunch cookie dummy (dummy)
20:02:24,993 I: (RuntimeData.java:307) : Data:: org.erlide.backend.BackendData
20:02:24,993 I: (RuntimeData.java:316) : beamLocator         : org.erlide.model.BeamLocator@6dd81095
20:02:24,993 I: (RuntimeData.java:316) : launch              : org.eclipse.debug.core.Launch@2b47ac1e
20:02:24,993 I: (RuntimeData.java:316) : projects            : [P/test]
20:02:24,994 I: (RuntimeData.java:316) : cookie              : dummy
20:02:24,994 I: (RuntimeData.java:316) : managed             : true
20:02:24,994 I: (RuntimeData.java:316) : restartable         : false
20:02:24,995 I: (RuntimeData.java:316) : startShell          : true
20:02:24,995 I: (RuntimeData.java:316) : console             : true
20:02:24,995 I: (RuntimeData.java:316) : interpretedModules  : [test:dummy.erl]
20:02:24,995 I: (RuntimeData.java:316) : nodeName            : dummy
20:02:24,996 I: (RuntimeData.java:316) : longName            : true
20:02:24,996 I: (RuntimeData.java:316) : extraArgs           : 
20:02:24,996 I: (RuntimeData.java:316) : workingDir          : .
20:02:24,996 I: (RuntimeData.java:316) : env                 : {}
20:02:24,997 I: (RuntimeData.java:316) : initialCall         : dummy:paf
20:02:24,997 I: (RuntimeData.java:316) : debugFlags          : [DISTRIBUTED_DEBUG, ATTACH_ON_BREAKPOINT]
20:02:24,997 I: (RuntimeData.java:316) : loadOnAllNodes      : true
20:02:24,998 I: (RuntimeData.java:316) : internal            : false
20:02:25,000 I: (RuntimeData.java:316) : runtimeInfo         : Runtime
20:02:25,001 I: (RuntimeData.java:316) : debug               : false
20:02:25,001 I: (RuntimeData.java:316) : reportErrors        : false
20:02:25,001 I: (RuntimeData.java:326) : ---------------
20:02:25,001 F: (ErlangLaunchDelegate.java:164) : START node :> [/usr/local/otp/r16b/bin/erl, -name, [email protected], -setcookie, dummy] *** /opt
20:02:25,002 F: (ErlangLaunchDelegate.java:183) : process is running
20:02:25,005 F: (ErlangLaunchDelegate.java:143) : Started erts: dummy >> dummy
20:02:25,005 F: (BackendManager.java:134) : create execution backend dummy
20:02:25,005 F: (BackendFactory.java:81) : Create backend dummy
20:02:25,007 F: (ErlRuntime.java:383) : using cookie 'dummy...'5 (info: 'dummy')
20:02:25,007 F: (ErlRuntime.java:468) : [email protected]: waiting connection to peer...
20:02:25,110 F: (ErlRuntime.java:164) : # [email protected] Worker-6
20:02:25,117 F: (ErlRuntime.java:103) : Node [email protected] is up
20:02:25,117 F: (ErlRuntime.java:164) : # [email protected] Worker-6
20:02:25,330 F: (ErlRuntime.java:555) : code server started
20:02:25,330 F: (ErlRuntime.java:474) : connected!
20:02:25,339 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.test_support_0.20.2.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,348 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.tracing.erlang_0.2.8.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,354 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.ide_0.20.2.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,360 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.tracing.ttbe_0.1.6.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,366 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.builder_0.20.1.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,370 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.cover.erlang_0.2.4.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,376 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.wrangler.core_0.9.9.201304230851/wrangler/ebin to code path for @302212330:: Runtime
20:02:25,381 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.wrangler.core_0.9.9.201304230851/wrangler/app to code path for @302212330:: Runtime
20:02:25,387 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.debugger_0.20.1.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,395 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.common_0.20.1.201304230851/ebin to code path for @302212330:: Runtime
20:02:25,395 F: (Backend.java:180) : initialize [email protected]: true
20:02:25,408 F: (LogEventHandler.java:25) : info: {"Start monitor process: ",10000000,50000000}
20:02:25,413 F: (Backend.java:281) : backend [email protected]: add path /home/sylane/workspace/test/ebin
20:02:25,422 F: (Backend.java:407) : calling startup function dummy:paf
20:02:25,422 F: (ErlConsoleManager.java:42) : console ADDED to org.erlide.backend.BackendData@465466c3
20:02:26,522 F: (BackendFactory.java:72) : Create build backend R16
20:02:26,522 F: (BackendFactory.java:81) : Create backend R16_c3a79_sylane_f4f15a
20:02:26,523 F: (ErlangLaunchDelegate.java:90) : doLaunch runtime r16b
20:02:26,524 F: (ErlangLaunchDelegate.java:91) : doLaunch cookie erlide (erlide)
20:02:26,524 I: (RuntimeData.java:307) : Data:: org.erlide.backend.BackendData
20:02:26,524 I: (RuntimeData.java:316) : beamLocator         : org.erlide.model.BeamLocator@57498098
20:02:26,525 I: (RuntimeData.java:316) : launch              : org.eclipse.debug.core.Launch@1a99a7ed
20:02:26,525 I: (RuntimeData.java:316) : projects            : []
20:02:26,525 I: (RuntimeData.java:316) : cookie              : erlide
20:02:26,525 I: (RuntimeData.java:316) : managed             : true
20:02:26,526 I: (RuntimeData.java:316) : restartable         : false
20:02:26,526 I: (RuntimeData.java:316) : startShell          : true
20:02:26,526 I: (RuntimeData.java:316) : console             : true
20:02:26,526 I: (RuntimeData.java:316) : interpretedModules  : []
20:02:26,527 I: (RuntimeData.java:316) : nodeName            : R16_c3a79_sylane_f4f15a
20:02:26,527 I: (RuntimeData.java:316) : longName            : true
20:02:26,527 I: (RuntimeData.java:316) : extraArgs           : 
20:02:26,527 I: (RuntimeData.java:316) : workingDir          : .
20:02:26,528 I: (RuntimeData.java:316) : env                 : {}
20:02:26,528 I: (RuntimeData.java:316) : initialCall         : 
20:02:26,528 I: (RuntimeData.java:316) : debugFlags          : [ATTACH_ON_BREAKPOINT]
20:02:26,529 I: (RuntimeData.java:316) : loadOnAllNodes      : false
20:02:26,529 I: (RuntimeData.java:316) : internal            : true
20:02:26,530 I: (RuntimeData.java:316) : runtimeInfo         : Runtime
20:02:26,531 I: (RuntimeData.java:316) : debug               : false
20:02:26,531 I: (RuntimeData.java:316) : reportErrors        : false
20:02:26,531 I: (RuntimeData.java:326) : ---------------
20:02:26,531 F: (ErlangLaunchDelegate.java:164) : START node :> [/usr/local/otp/r16b/bin/erl, -name, [email protected], -setcookie, erlide] *** /opt
20:02:26,533 F: (ErlangLaunchDelegate.java:183) : process is running
20:02:26,534 F: (ErtsProcess.java:27) : # create ErtsProcess: R16_c3a79_sylane_f4f15a
20:02:26,534 F: (ErlangLaunchDelegate.java:143) : Started erts: R16_c3a79_sylane_f4f15a >> R16_c3a79_sylane_f4f15a
20:02:26,536 F: (ErlRuntime.java:383) : using cookie 'erlide...'6 (info: 'erlide')
20:02:26,537 F: (ErlRuntime.java:468) : [email protected]: waiting connection to peer...
20:02:26,637 F: (ErlRuntime.java:164) : # [email protected] Text Viewer Hover Presenter
20:02:27,039 F: (ErlRuntime.java:164) : # [email protected] Text Viewer Hover Presenter
20:02:27,043 F: (ErlRuntime.java:103) : Node [email protected] is up
20:02:27,043 F: (ErlRuntime.java:164) : # [email protected] Text Viewer Hover Presenter
20:02:27,245 F: (ErlRuntime.java:555) : code server started
20:02:27,245 F: (ErlRuntime.java:474) : connected!
20:02:27,251 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.test_support_0.20.2.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,257 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.tracing.erlang_0.2.8.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,262 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.ide_0.20.2.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,266 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.tracing.ttbe_0.1.6.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,270 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.builder_0.20.1.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,274 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.cover.erlang_0.2.4.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,279 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.wrangler.core_0.9.9.201304230851/wrangler/ebin to code path for @1820095640:: Runtime
20:02:27,287 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.wrangler.core_0.9.9.201304230851/wrangler/app to code path for @1820095640:: Runtime
20:02:27,293 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.debugger_0.20.1.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,302 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.common_0.20.1.201304230851/ebin to code path for @1820095640:: Runtime
20:02:27,303 F: (Backend.java:180) : initialize [email protected]: true
20:02:27,311 F: (LogEventHandler.java:25) : info: {"Start monitor process: ",10000000,50000000}

Console log when starting the tracer:

20:03:20,702 F: (BackendManager.java:134) : create execution backend tracing
20:03:20,703 F: (BackendFactory.java:81) : Create backend tracing
20:03:20,704 F: (ErlangLaunchDelegate.java:90) : doLaunch runtime r16b
20:03:20,704 F: (ErlangLaunchDelegate.java:91) : doLaunch cookie erlide (erlide)
20:03:20,704 I: (RuntimeData.java:307) : Data:: org.erlide.backend.BackendData
20:03:20,705 I: (RuntimeData.java:316) : beamLocator         : org.erlide.model.BeamLocator@176f61bb
20:03:20,705 I: (RuntimeData.java:316) : launch              : org.eclipse.debug.core.Launch@11794802
20:03:20,705 I: (RuntimeData.java:316) : projects            : []
20:03:20,705 I: (RuntimeData.java:316) : cookie              : erlide
20:03:20,706 I: (RuntimeData.java:316) : managed             : true
20:03:20,706 I: (RuntimeData.java:316) : restartable         : false
20:03:20,706 I: (RuntimeData.java:316) : startShell          : true
20:03:20,706 I: (RuntimeData.java:316) : console             : true
20:03:20,707 I: (RuntimeData.java:316) : interpretedModules  : []
20:03:20,707 I: (RuntimeData.java:316) : nodeName            : tracing
20:03:20,707 I: (RuntimeData.java:316) : longName            : true
20:03:20,708 I: (RuntimeData.java:316) : extraArgs           : 
20:03:20,708 I: (RuntimeData.java:316) : workingDir          : .
20:03:20,708 I: (RuntimeData.java:316) : env                 : {}
20:03:20,708 I: (RuntimeData.java:316) : initialCall         : 
20:03:20,709 I: (RuntimeData.java:316) : debugFlags          : [ATTACH_ON_BREAKPOINT]
20:03:20,709 I: (RuntimeData.java:316) : loadOnAllNodes      : false
20:03:20,709 I: (RuntimeData.java:316) : internal            : false
20:03:20,712 I: (RuntimeData.java:316) : runtimeInfo         : Runtime
20:03:20,713 I: (RuntimeData.java:316) : debug               : false
20:03:20,713 I: (RuntimeData.java:316) : reportErrors        : false
20:03:20,713 I: (RuntimeData.java:326) : ---------------
20:03:20,713 F: (ErlangLaunchDelegate.java:164) : START node :> [/usr/local/otp/r16b/bin/erl, -name, [email protected], -setcookie, erlide] *** /opt
20:03:20,715 F: (ErlangLaunchDelegate.java:183) : process is running
20:03:20,716 F: (ErtsProcess.java:27) : # create ErtsProcess: tracing
20:03:20,717 F: (ErlangLaunchDelegate.java:143) : Started erts: tracing >> tracing
20:03:20,718 F: (ErlRuntime.java:383) : using cookie 'erlide...'6 (info: 'erlide')
20:03:20,719 F: (ErlRuntime.java:468) : [email protected]: waiting connection to peer...
20:03:20,820 F: (ErlRuntime.java:164) : # [email protected] ModalContext
20:03:20,826 F: (ErlRuntime.java:103) : Node [email protected] is up
20:03:20,826 F: (ErlRuntime.java:164) : # [email protected] ModalContext
20:03:21,046 F: (ErlRuntime.java:555) : code server started
20:03:21,047 F: (ErlRuntime.java:474) : connected!
20:03:21,053 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.test_support_0.20.2.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,065 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.tracing.erlang_0.2.8.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,070 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.ide_0.20.2.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,075 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.tracing.ttbe_0.1.6.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,080 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.builder_0.20.1.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,085 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.cover.erlang_0.2.4.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,095 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.wrangler.core_0.9.9.201304230851/wrangler/ebin to code path for @387837533:: Runtime
20:03:21,100 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.wrangler.core_0.9.9.201304230851/wrangler/app to code path for @387837533:: Runtime
20:03:21,105 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.debugger_0.20.1.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,112 F: (CodeManager.java:223) : adding /opt/eclipse/plugins/org.erlide.kernel.common_0.20.1.201304230851/ebin to code path for @387837533:: Runtime
20:03:21,113 F: (Backend.java:180) : initialize [email protected]: true

Console log when stopping the tracer:

20:04:25,907 F: (LogEventHandler.java:25) : info: no answer from ttbe:stop
20:04:25,907 S: (TraceBackend.java:308) : Could not stop tracing tool: {badrpc,{'EXIT',{badarg,[{ttbe,stop_return,2,[]},{ttb_integration,stop,0,[]},{rpc,'-handle_call_call/6-fun-0-',5,[{file,"rpc.erl"},{line,205}]}]}}}

sylane avatar May 19 '13 18:05 sylane

I got exactly the same problem when running an external node.

sylane avatar May 19 '13 18:05 sylane

Thanks for the report.

The tracing plugin needs some love, we didn't have enough users for it to get feedback and other things got higher priority.

https://erlide-tools.assembla.com/spaces/erlide/tickets/1217

vladdu avatar May 20 '13 08:05 vladdu