Robotics StackExchange | Archived questions

Unable to find/localize log file

Hi everyone

I was trying to run Bezier_examples package but for some reason after starting example launch file

roslaunch bezier_examples fanuc_m10ia_surfacing.launch surfacing_mode:=true mesh_cad:=plane/plane_defect.ply 

rviz crushes instantly. To resolve this problem I need to read log file but for some reason the file which path is given in console does not exist.

REQUIRED process [bezier_examples_surfacing-6] has died!
process has died [pid 24603, exit code -6, cmd /home/brian/catkin_workspace/devel/lib/bezier_examples/bezier_examples_surfacing __name:=bezier_examples_surfacing __log:=/home/brian/.ros/log/754af8fe-c8cb-11e9-b951-080027af27c1/bezier_examples_surfacing-6.log].
log file: /home/brian/.ros/log/754af8fe-c8cb-11e9-b951-080027af27c1/bezier_examples_surfacing-6*.log

Does anyone had the similar problem before? What can be the cause for log file not to be saved ?

My Setup: Ubuntu 18.04 VM with Melodic distro

Full log:

root@brian-VirtualBox:~/catkin_workspace# roslaunch bezier_examples fanuc_m10ia_surfacing.launch surfacing_mode:=true mesh_cad:=plane/plane_defect.ply
... logging to /home/brian/.ros/log/754af8fe-c8cb-11e9-b951-080027af27c1/roslaunch-brian-VirtualBox-24558.log
Checking log directory for disk usage. This may take awhile.
Press Ctrl-C to interrupt
Done checking log file disk usage. Usage is <1GB.

xacro: in-order processing became default in ROS Melodic. You can drop the option.
started roslaunch server http://brian-VirtualBox:36149/

SUMMARY
========

PARAMETERS
 * /controller_joint_names: ['joint_1', 'join...
 * /mesh_cad_param: plane/plane_defec...
 * /mesh_defect_param: 
 * /move_group/allow_trajectory_execution: True
 * /move_group/capabilities: 
 * /move_group/controller_list: [{'joints': ['joi...
 * /move_group/disable_capabilities: 
 * /move_group/generic_hw_control_loop/cycle_time_error_threshold: 0.01
 * /move_group/generic_hw_control_loop/loop_hz: 300
 * /move_group/hardware_interface/joints: ['joint_1', 'join...
 * /move_group/hardware_interface/sim_control_mode: 1
 * /move_group/jiggle_fraction: 0.05
 * /move_group/joint_state_controller/publish_rate: 50
 * /move_group/joint_state_controller/type: joint_state_contr...
 * /move_group/manipulator/default_planner_config: LBKPIECE
 * /move_group/manipulator/longest_valid_segment_fraction: 0.005
 * /move_group/manipulator/planner_configs: ['SBL', 'EST', 'L...
 * /move_group/manipulator/projection_evaluator: joints(joint_1,jo...
 * /move_group/max_range: 5.0
 * /move_group/max_safe_path_cost: 1
 * /move_group/moveit_controller_manager: moveit_simple_con...
 * /move_group/moveit_manage_controllers: True
 * /move_group/moveit_sim_hw_interface/joint_model_group: controllers_initi...
 * /move_group/moveit_sim_hw_interface/joint_model_group_pose: controllers_initi...
 * /move_group/octomap_resolution: 0.025
 * /move_group/planner_configs/BFMT/balanced: 0
 * /move_group/planner_configs/BFMT/cache_cc: 1
 * /move_group/planner_configs/BFMT/extended_fmt: 1
 * /move_group/planner_configs/BFMT/heuristics: 1
 * /move_group/planner_configs/BFMT/nearest_k: 1
 * /move_group/planner_configs/BFMT/num_samples: 1000
 * /move_group/planner_configs/BFMT/optimality: 1
 * /move_group/planner_configs/BFMT/radius_multiplier: 1.0
 * /move_group/planner_configs/BFMT/type: geometric::BFMT
 * /move_group/planner_configs/BKPIECE/border_fraction: 0.9
 * /move_group/planner_configs/BKPIECE/failed_expansion_score_factor: 0.5
 * /move_group/planner_configs/BKPIECE/min_valid_path_fraction: 0.5
 * /move_group/planner_configs/BKPIECE/range: 0.0
 * /move_group/planner_configs/BKPIECE/type: geometric::BKPIECE
 * /move_group/planner_configs/BiEST/range: 0.0
 * /move_group/planner_configs/BiEST/type: geometric::BiEST
 * /move_group/planner_configs/BiTRRT/cost_threshold: 1e300
 * /move_group/planner_configs/BiTRRT/frountier_node_ratio: 0.1
 * /move_group/planner_configs/BiTRRT/frountier_threshold: 0.0
 * /move_group/planner_configs/BiTRRT/init_temperature: 100
 * /move_group/planner_configs/BiTRRT/range: 0.0
 * /move_group/planner_configs/BiTRRT/temp_change_factor: 0.1
 * /move_group/planner_configs/BiTRRT/type: geometric::BiTRRT
 * /move_group/planner_configs/EST/goal_bias: 0.05
 * /move_group/planner_configs/EST/range: 0.0
 * /move_group/planner_configs/EST/type: geometric::EST
 * /move_group/planner_configs/FMT/cache_cc: 1
 * /move_group/planner_configs/FMT/extended_fmt: 1
 * /move_group/planner_configs/FMT/heuristics: 0
 * /move_group/planner_configs/FMT/nearest_k: 1
 * /move_group/planner_configs/FMT/num_samples: 1000
 * /move_group/planner_configs/FMT/radius_multiplier: 1.1
 * /move_group/planner_configs/FMT/type: geometric::FMT
 * /move_group/planner_configs/KPIECE/border_fraction: 0.9
 * /move_group/planner_configs/KPIECE/failed_expansion_score_factor: 0.5
 * /move_group/planner_configs/KPIECE/goal_bias: 0.05
 * /move_group/planner_configs/KPIECE/min_valid_path_fraction: 0.5
 * /move_group/planner_configs/KPIECE/range: 0.0
 * /move_group/planner_configs/KPIECE/type: geometric::KPIECE
 * /move_group/planner_configs/LBKPIECE/border_fraction: 0.9
 * /move_group/planner_configs/LBKPIECE/min_valid_path_fraction: 0.5
 * /move_group/planner_configs/LBKPIECE/range: 0.0
 * /move_group/planner_configs/LBKPIECE/type: geometric::LBKPIECE
 * /move_group/planner_configs/LBTRRT/epsilon: 0.4
 * /move_group/planner_configs/LBTRRT/goal_bias: 0.05
 * /move_group/planner_configs/LBTRRT/range: 0.0
 * /move_group/planner_configs/LBTRRT/type: geometric::LBTRRT
 * /move_group/planner_configs/LazyPRM/range: 0.0
 * /move_group/planner_configs/LazyPRM/type: geometric::LazyPRM
 * /move_group/planner_configs/LazyPRMstar/type: geometric::LazyPR...
 * /move_group/planner_configs/PDST/type: geometric::PDST
 * /move_group/planner_configs/PRM/max_nearest_neighbors: 10
 * /move_group/planner_configs/PRM/type: geometric::PRM
 * /move_group/planner_configs/PRMstar/type: geometric::PRMstar
 * /move_group/planner_configs/ProjEST/goal_bias: 0.05
 * /move_group/planner_configs/ProjEST/range: 0.0
 * /move_group/planner_configs/ProjEST/type: geometric::ProjEST
 * /move_group/planner_configs/RRT/goal_bias: 0.05
 * /move_group/planner_configs/RRT/range: 0.0
 * /move_group/planner_configs/RRT/type: geometric::RRT
 * /move_group/planner_configs/RRTConnect/range: 0.0
 * /move_group/planner_configs/RRTConnect/type: geometric::RRTCon...
 * /move_group/planner_configs/RRTstar/delay_collision_checking: 1
 * /move_group/planner_configs/RRTstar/goal_bias: 0.05
 * /move_group/planner_configs/RRTstar/range: 0.0
 * /move_group/planner_configs/RRTstar/type: geometric::RRTstar
 * /move_group/planner_configs/SBL/range: 0.0
 * /move_group/planner_configs/SBL/type: geometric::SBL
 * /move_group/planner_configs/SPARS/dense_delta_fraction: 0.001
 * /move_group/planner_configs/SPARS/max_failures: 1000
 * /move_group/planner_configs/SPARS/sparse_delta_fraction: 0.25
 * /move_group/planner_configs/SPARS/stretch_factor: 3.0
 * /move_group/planner_configs/SPARS/type: geometric::SPARS
 * /move_group/planner_configs/SPARStwo/dense_delta_fraction: 0.001
 * /move_group/planner_configs/SPARStwo/max_failures: 5000
 * /move_group/planner_configs/SPARStwo/sparse_delta_fraction: 0.25
 * /move_group/planner_configs/SPARStwo/stretch_factor: 3.0
 * /move_group/planner_configs/SPARStwo/type: geometric::SPARStwo
 * /move_group/planner_configs/STRIDE/degree: 16
 * /move_group/planner_configs/STRIDE/estimated_dimension: 0.0
 * /move_group/planner_configs/STRIDE/goal_bias: 0.05
 * /move_group/planner_configs/STRIDE/max_degree: 18
 * /move_group/planner_configs/STRIDE/max_pts_per_leaf: 6
 * /move_group/planner_configs/STRIDE/min_degree: 12
 * /move_group/planner_configs/STRIDE/min_valid_path_fraction: 0.2
 * /move_group/planner_configs/STRIDE/range: 0.0
 * /move_group/planner_configs/STRIDE/type: geometric::STRIDE
 * /move_group/planner_configs/STRIDE/use_projected_distance: 0
 * /move_group/planner_configs/TRRT/frountierNodeRatio: 0.1
 * /move_group/planner_configs/TRRT/frountier_threshold: 0.0
 * /move_group/planner_configs/TRRT/goal_bias: 0.05
 * /move_group/planner_configs/TRRT/init_temperature: 10e-6
 * /move_group/planner_configs/TRRT/k_constant: 0.0
 * /move_group/planner_configs/TRRT/max_states_failed: 10
 * /move_group/planner_configs/TRRT/min_temperature: 10e-10
 * /move_group/planner_configs/TRRT/range: 0.0
 * /move_group/planner_configs/TRRT/temp_change_factor: 2.0
 * /move_group/planner_configs/TRRT/type: geometric::TRRT
 * /move_group/planning_plugin: ompl_interface/OM...
 * /move_group/planning_scene_monitor/publish_geometry_updates: True
 * /move_group/planning_scene_monitor/publish_planning_scene: True
 * /move_group/planning_scene_monitor/publish_state_updates: True
 * /move_group/planning_scene_monitor/publish_transforms_updates: True
 * /move_group/request_adapters: default_planner_r...
 * /move_group/start_state_max_bounds_error: 0.1
 * /move_group/trajectory_execution/allowed_execution_duration_scaling: 1.2
 * /move_group/trajectory_execution/allowed_goal_duration_margin: 0.5
 * /move_group/trajectory_execution/allowed_start_tolerance: 0.01
 * /robot_description: <?xml version="1....
 * /robot_description_kinematics/manipulator/kinematics_solver: kdl_kinematics_pl...
 * /robot_description_kinematics/manipulator/kinematics_solver_attempts: 3
 * /robot_description_kinematics/manipulator/kinematics_solver_search_resolution: 0.005
 * /robot_description_kinematics/manipulator/kinematics_solver_timeout: 0.005
 * /robot_description_planning/joint_limits/joint_1/has_acceleration_limits: False
 * /robot_description_planning/joint_limits/joint_1/has_velocity_limits: True
 * /robot_description_planning/joint_limits/joint_1/max_acceleration: 0
 * /robot_description_planning/joint_limits/joint_1/max_velocity: 3.67
 * /robot_description_planning/joint_limits/joint_2/has_acceleration_limits: False
 * /robot_description_planning/joint_limits/joint_2/has_velocity_limits: True
 * /robot_description_planning/joint_limits/joint_2/max_acceleration: 0
 * /robot_description_planning/joint_limits/joint_2/max_velocity: 3.32
 * /robot_description_planning/joint_limits/joint_3/has_acceleration_limits: False
 * /robot_description_planning/joint_limits/joint_3/has_velocity_limits: True
 * /robot_description_planning/joint_limits/joint_3/max_acceleration: 0
 * /robot_description_planning/joint_limits/joint_3/max_velocity: 3.67
 * /robot_description_planning/joint_limits/joint_4/has_acceleration_limits: False
 * /robot_description_planning/joint_limits/joint_4/has_velocity_limits: True
 * /robot_description_planning/joint_limits/joint_4/max_acceleration: 0
 * /robot_description_planning/joint_limits/joint_4/max_velocity: 6.98
 * /robot_description_planning/joint_limits/joint_5/has_acceleration_limits: False
 * /robot_description_planning/joint_limits/joint_5/has_velocity_limits: True
 * /robot_description_planning/joint_limits/joint_5/max_acceleration: 0
 * /robot_description_planning/joint_limits/joint_5/max_velocity: 6.98
 * /robot_description_planning/joint_limits/joint_6/has_acceleration_limits: False
 * /robot_description_planning/joint_limits/joint_6/has_velocity_limits: True
 * /robot_description_planning/joint_limits/joint_6/max_acceleration: 0
 * /robot_description_planning/joint_limits/joint_6/max_velocity: 10.47
 * /robot_description_semantic: <?xml version="1....
 * /rosdistro: melodic
 * /rosversion: 1.14.3
 * /surfacing_mode_param: True

NODES
  /
    bezier_examples_surfacing (bezier_examples/bezier_examples_surfacing)
    industrial_robot_simulator (industrial_robot_simulator/industrial_robot_simulator)
    joint_trajectory_action (industrial_robot_client/joint_trajectory_action)
    move_group (moveit_ros_move_group/move_group)
    robot_state_publisher (robot_state_publisher/robot_state_publisher)
    rviz (rviz/rviz)

auto-starting new master
process[master]: started with pid [24575]
ROS_MASTER_URI=http://localhost:11311

setting /run_id to 754af8fe-c8cb-11e9-b951-080027af27c1
process[rosout-1]: started with pid [24586]
started core service [/rosout]
process[industrial_robot_simulator-2]: started with pid [24593]
process[joint_trajectory_action-3]: started with pid [24594]
process[robot_state_publisher-4]: started with pid [24595]
process[move_group-5]: started with pid [24601]
process[bezier_examples_surfacing-6]: started with pid [24603]
process[rviz-7]: started with pid [24605]
[ INFO] [1566911181.178732872]: Loading robot model 'fanuc_m10ia'...
[ INFO] [1566911181.179926319]: No root/virtual joint specified in SRDF. Assuming fixed joint
QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-root'
[ WARN] [1566911181.272918865]: Kinematics solver doesn't support #attempts anymore, but only a timeout.
Please remove the parameter '/robot_description_kinematics/manipulator/kinematics_solver_attempts' from your configuration.
[ INFO] [1566911181.355999815]: Publishing maintained planning scene on 'monitored_planning_scene'
[ INFO] [1566911181.359033441]: MoveGroup debug mode is OFF
Starting planning scene monitors...
[ INFO] [1566911181.359111104]: Starting planning scene monitor
[ INFO] [1566911181.365931245]: Listening to '/planning_scene'
[ INFO] [1566911181.366009467]: Starting world geometry update monitor for collision objects, attached objects, octomap updates.
[ INFO] [1566911181.369936223]: Listening to '/collision_object' using message notifier with target frame 'base_link '
[ INFO] [1566911181.372983538]: Listening to '/planning_scene_world' for planning scene world geometry
[ INFO] [1566911181.427468675]: Listening to '/attached_collision_object' for attached collision objects
Planning scene monitors started.
[ INFO] [1566911181.470066267]: Initializing OMPL interface using ROS parameters
[ INFO] [1566911181.526030922]: Using planning interface 'OMPL'
[ INFO] [1566911181.539623152]: Param 'default_workspace_bounds' was not set. Using default value: 10
[ INFO] [1566911181.541573680]: Param 'start_state_max_bounds_error' was set to 0.1
[ INFO] [1566911181.544123445]: Param 'start_state_max_dt' was not set. Using default value: 0.5
[ INFO] [1566911181.546709807]: Param 'start_state_max_dt' was not set. Using default value: 0.5
[ INFO] [1566911181.548333757]: Param 'jiggle_fraction' was set to 0.05
[ INFO] [1566911181.549907420]: Param 'max_sampling_attempts' was not set. Using default value: 100
[ INFO] [1566911181.550326189]: Using planning request adapter 'Add Time Parameterization'
[ INFO] [1566911181.550573427]: Using planning request adapter 'Fix Workspace Bounds'
[ INFO] [1566911181.550776805]: Using planning request adapter 'Fix Start State Bounds'
[ INFO] [1566911181.550995268]: Using planning request adapter 'Fix Start State In Collision'
[ INFO] [1566911181.551203116]: Using planning request adapter 'Fix Start State Path Constraints'
[ERROR] [1566911181.586105596]: name, joints, action_ns, and type must be specifed for each controller
[ INFO] [1566911181.586386917]: Returned 0 controllers in list
terminate called after throwing an instance of 'std::bad_alloc'
  what():  std::bad_alloc
[ INFO] [1566911181.639428295]: Trajectory execution is managing controllers
Loading 'move_group/ApplyPlanningSceneService'...
Loading 'move_group/ClearOctomapService'...
Loading 'move_group/MoveGroupCartesianPathService'...
Loading 'move_group/MoveGroupExecuteTrajectoryAction'...
Loading 'move_group/MoveGroupGetPlanningSceneService'...
Loading 'move_group/MoveGroupKinematicsService'...
Loading 'move_group/MoveGroupMoveAction'...
Loading 'move_group/MoveGroupPickPlaceAction'...
Loading 'move_group/MoveGroupPlanService'...
Loading 'move_group/MoveGroupQueryPlannersService'...
Loading 'move_group/MoveGroupStateValidationService'...
[ INFO] [1566911181.765816984]: 

********************************************************
* MoveGroup using: 
*     - ApplyPlanningSceneService
*     - ClearOctomapService
*     - CartesianPathService
*     - ExecuteTrajectoryAction
*     - GetPlanningSceneService
*     - KinematicsService
*     - MoveAction
*     - PickPlaceAction
*     - MotionPlanService
*     - QueryPlannersService
*     - StateValidationService
********************************************************

[ INFO] [1566911181.766076514]: MoveGroup context using planning plugin ompl_interface/OMPLPlanner
[ INFO] [1566911181.766381022]: MoveGroup context initialization complete

You can start planning now!

================================================================================REQUIRED process [bezier_examples_surfacing-6] has died!
process has died [pid 24603, exit code -6, cmd /home/brian/catkin_workspace/devel/lib/bezier_examples/bezier_examples_surfacing __name:=bezier_examples_surfacing __log:=/home/brian/.ros/log/754af8fe-c8cb-11e9-b951-080027af27c1/bezier_examples_surfacing-6.log].
log file: /home/brian/.ros/log/754af8fe-c8cb-11e9-b951-080027af27c1/bezier_examples_surfacing-6*.log
Initiating shutdown!
================================================================================
[rviz-7] killing on exit
[bezier_examples_surfacing-6] killing on exit
[move_group-5] killing on exit
[robot_state_publisher-4] killing on exit
[joint_trajectory_action-3] killing on exit
[industrial_robot_simulator-2] killing on exit
[rosout-1] killing on exit
[master] killing on exit
shutting down processing monitor...
... shutting down processing monitor complete
done

Asked by Shortfollower on 2019-08-27 08:26:05 UTC

Comments

Not an answer, but:

My Setup: Ubuntu 18.04 VM with Melodic distro

RViz (and Gazebo) in a VM has been very difficult in the past. You may want to verify whether the crash also happens on a non-VM install.

Asked by gvdhoorn on 2019-08-27 09:17:27 UTC

what is the latest log in your log directory? To check it, type:

roscd log

then list the logs by date with:

ls -lt

Asked by ermanas on 2019-08-27 09:26:03 UTC

Most likely there is no log. RViz crashes too soon.

Asked by gvdhoorn on 2019-08-27 10:46:22 UTC

Try removing or commenting out the rviz included launch and see if that isolates. You could run it separatately too. I hate to suggest it but do you have all of the dependencies installed?

Asked by prefpkg21 on 2019-08-27 11:08:09 UTC

When it comes to log files that’s what I got

-rw-r--r-- 1 root root  1365 srp 28 08:15 master.log
-rw-r--r-- 1 root root   151 srp 28 08:15 rosout-1-stdout.log
-rw-r--r-- 1 root root    37 srp 28 08:15 rosout.log
-rw-r--r-- 1 root root 12017 srp 28 08:15 roslaunch-brian-VirtualBox-2692.log

There is no really much info about crush in those logs. I tried to reinstall package, but problem is still there. The only clue I got is the warning while building Bezier_examples package

/usr/bin/ld: warning: libboost_system.so.1.65.1, needed by /opt/ros/melodic/lib/libmoveit_move_group_interface.so, may conflict with libboost_system.so.1.58.0
cd /home/brian/catkin_workspace/build/bezier_examples; catkin build --get-env bezier_examples | catkin env -si  /usr/bin/make --jobserver-fds=6,7 -j; cd -

After commenting out the rviz launch problem was still there. So I commented out the Bezier launch and it turned out that it was causing crash.

Asked by Shortfollower on 2019-08-28 05:19:35 UTC

I tried to run it separately but for some reason node also have some issues.

~/catkin_workspace# rosrun bezier_examples painting.cpp 
/home/brian/catkin_workspace/src/bezier_examples/bezier_examples/src/painting.cpp: line 1: //: Is a directory
/home/brian/catkin_workspace/src/bezier_examples/bezier_examples/src/painting.cpp: line 5: //#pragma: No such file or directory
/home/brian/catkin_workspace/src/bezier_examples/bezier_examples/src/painting.cpp: line 6: //#pragma: No such file or directory
/home/brian/catkin_workspace/src/bezier_examples/bezier_examples/src/painting.cpp: line 9: //#pragma: No such file or directory
/home/brian/catkin_workspace/src/bezier_examples/bezier_examples/src/painting.cpp: line 17: /bin: Is a directory

If resolving the issue with library conflict won’t help, I will try to reinstall everything on non-VM machine and check if the problem is repeatable on it. Thanks for help

Asked by Shortfollower on 2019-08-28 05:21:20 UTC

The Boost conflict is a likely candidate. It's very likely that it is causing the crash.

re: rosrun bezier_examples painting.cpp: you cannot execute a .cpp source file directly. It's not a script, it must be compiled first (and bezier_examples painting.cpp is not the node, it's one of the source files that make up the node, after compilation).

Asked by gvdhoorn on 2019-08-28 05:28:48 UTC

Answers