Uploaded image for project: 'SonarQube'
  1. SonarQube
  2. SONAR-10078

Logs ordering is messed up in case of exception during file indexing

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed
    • Priority: Major
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 7.0
    • Component/s: Scanner
    • Labels:
      None

      Description

      In case of exception during file indexing, logs are messed up (error starts to be logged, while indexing debug logs continue to be shown).
      Also the MessageException is logged alone on a line (separated from the first part of the error message) and that make it hard to read:

      INFO: Option -e/--errors is no longer supported and will be ignored
      20:20:59.878 INFO: Scanner configuration file: /xxx/sonar-scanner-3.0.3.778-linux/conf/sonar-scanner.properties
      20:20:59.879 INFO: Project root configuration file: /xxx/source/sonar-project.properties
      20:20:59.901 INFO: SonarQube Scanner 3.0.3.778
      20:20:59.901 INFO: Java 1.8.0_121 Oracle Corporation (64-bit)
      20:20:59.901 INFO: Linux 3.13.0-125-generic amd64
      20:20:59.997 DEBUG: keyStore is : 
      20:20:59.997 DEBUG: keyStore type is : jks
      20:20:59.997 DEBUG: keyStore provider is : 
      20:20:59.997 DEBUG: init keystore
      20:20:59.997 DEBUG: init keymanager of type SunX509
      20:21:00.060 INFO: User cache: /home/rbot/.sonar/cache
      20:21:00.060 DEBUG: Extract sonar-scanner-api-batch in temp...
      20:21:00.063 DEBUG: Get bootstrap index...
      20:21:00.064 DEBUG: Download: https://xxx/batch/index
      20:21:00.219 DEBUG: Get bootstrap completed
      20:21:00.220 DEBUG: Download https://xxx/batch/file?name=sonar-scanner-engine-shaded-6.7.jar to /home/rbot/.sonar/cache/_tmp/fileCache2167205082771660598.tmp
      20:21:00.852 DEBUG: Create isolated classloader...
      20:21:00.858 DEBUG: Start temp cleaning...
      20:21:00.888 DEBUG: Temp cleaning done
      20:21:00.888 DEBUG: Execution getVersion
      20:21:00.891 DEBUG: Execution start
      20:21:00.999 INFO: Publish mode
      20:21:01.077 INFO: Load global settings
      20:21:01.345 DEBUG: GET 200 https://xxx/api/settings/values.protobuf | time=263ms
      20:21:01.357 INFO: Load global settings (done) | time=281ms
      20:21:01.362 INFO: Server id: 185754ac3034f0d
      20:21:01.369 INFO: User cache: /home/rbot/.sonar/cache
      20:21:01.525 INFO: Load plugins index
      20:21:01.637 DEBUG: GET 200 https://xxx/api/plugins/installed | time=112ms
      20:21:01.661 INFO: Load plugins index (done) | time=136ms
      20:21:01.661 DEBUG: Load plugins
      [...]
      20:21:02.982 DEBUG: Load plugins (done) | time=1321ms
      20:21:02.990 DEBUG: API compatibility mode is enabled on plugin SVN [scmsvn] (built with API lower than 5.2)
      20:21:03.023 DEBUG: API compatibility mode is enabled on plugin Git [scmgit] (built with API lower than 5.2)
      20:21:03.308 DEBUG: Plugins:
      20:21:03.308 DEBUG:   * Branch 1.0.0.507 (branch)
      20:21:03.308 DEBUG:   * SVN 1.5.0.715 (scmsvn)
      20:21:03.308 DEBUG:   * SonarPLSQL 3.1.0.1464 (plsql)
      20:21:03.308 DEBUG:   * C# 5.10.1.1411 (csharp)
      20:21:03.309 DEBUG:   * SonarJava 4.11.0.10660 (java)
      20:21:03.309 DEBUG:   * LDAP 2.2.0.608 (ldap)
      20:21:03.309 DEBUG:   * Web 2.5.0.476 (web)
      20:21:03.309 DEBUG:   * Flex 2.3 (flex)
      20:21:03.309 DEBUG:   * SonarXML 1.4.3.1027 (xml)
      20:21:03.309 DEBUG:   * SonarVB 4.1.0.156 (vbnet)
      20:21:03.309 DEBUG:   * SonarSwift 3.1.0.2067 (swift)
      20:21:03.309 DEBUG:   * Python 1.7-SNAPSHOT (python)
      20:21:03.309 DEBUG:   * SonarCFamily 5.0.0.9359 (cpp)
      20:21:03.309 DEBUG:   * SonarRPG 2.2.0.1005 (rpg)
      20:21:03.309 DEBUG:   * SonarPLI 1.7.0.1117 (pli)
      20:21:03.309 DEBUG:   * SonarTSQL 1.1.0.2177 (tsql)
      20:21:03.310 DEBUG:   * SonarVB6 2.3.0.992 (vb)
      20:21:03.310 DEBUG:   * SonarJS 3.1.1.5128 (javascript)
      20:21:03.310 DEBUG:   * License 3.2.0.1163 (license)
      20:21:03.310 DEBUG:   * SonarCOBOL 4.1.0.2626 (cobol)
      20:21:03.310 DEBUG:   * Git 1.2 (scmgit)
      20:21:03.310 DEBUG:   * SonarPHP 2.10.0.2087 (php)
      20:21:03.310 DEBUG:   * Developer 1.0.0.240 (developer)
      20:21:03.310 DEBUG:   * SonarABAP 3.5.0.1080 (abap)
      20:21:03.310 DEBUG:   * Governance 2.0.1.2981 (governance)
      20:21:03.323 DEBUG: Execution getVersion
      20:21:03.323 INFO: SonarQube server 6.7.0
      [...]
      20:25:52.175 DEBUG: 'perception/tests/pixel_pedestrian_test.cc' indexed with language 'cpp'
      20:25:52.175 INFO: ------------------------------------------------------------------------
      20:25:52.175 INFO: EXECUTION FAILURE
      20:25:52.175 INFO: ------------------------------------------------------------------------
      20:25:52.175 DEBUG: 'perception/tests/rectangle_helpers_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/segmentation_3d_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/region_3d_geometry_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/traffic_signal_state_estimator_utilities_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/grid_3d_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/region_3d_level_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/pointwise_cnn_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/traffic_light_image_synchronizer_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/pixel_cnn_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/segmentation_2d_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/pixel_acf_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/connected_components_util_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/hidl_eigen_matrix_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/polar_occlusion_map_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/traffic_signal_state_estimator_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/traffic_light_roi_extractor_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/region_pooling_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/oriented_rectangle_sorting_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/pixel_texture_config_autocheck.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/point_quantization_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/pedestrian_topview_detector/pedestrian_topview_detector.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/pixel_spectral3d_test.cc' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/sample_labeling_utils_impl.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/config/BUILD' indexed with language 'null'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/sample_labeling_utils.cc' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/sampling_utils.cc' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/BUILD' indexed with language 'null'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/config/pedestrian_detector_training_utils.hidl' indexed with language 'null'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/pedestrian_filter_utils_impl.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/tests/sampling_utils_test.cc' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/pedestrian_filter_impl.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/pedestrian_filter_utils.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/pedestrian_filter.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/tests/BUILD' indexed with language 'null'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/tests/sample_labeling_utils_test.cc' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/reference_pedestrian_topview_dagger_training_config.yml' indexed with language 'null'
      20:25:52.176 DEBUG: 'perception/utils/hidl_verification.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/sampling_utils.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/utils/tracked_object_helpers.pyc' indexed with language 'null'
      20:25:52.176 DEBUG: 'perception/utils/cluster_utils.py' indexed with language 'py'
      20:25:52.176 DEBUG: 'perception/utils/__init__.pyc' indexed with language 'null'
      20:25:52.173 DEBUG: 'perception/image_pipeline/python/tracker_metrics_utils.py' indexed with language 'py'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/pedestrian_visualization.hh' indexed with language 'cpp'
      20:25:52.176 DEBUG: 'perception/pedestrian_topview_detector/sample_labeling_utils.hh' indexed with language 'cpp'
      20:25:52.177 DEBUG: 'perception/utils/logsets.pyc' indexed with language 'null'
      20:25:52.175 DEBUG: 'perception/tests/estimate_line2d_test.cc' indexed with language 'cpp'
      20:25:52.175 INFO: Total time: 4:52.310s
      20:25:52.175 DEBUG: 'perception/tests/traffic_signal_face_validation_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/bounded_polygon_2d_test.cc' indexed with language 'cpp'
      20:25:52.175 DEBUG: 'perception/tests/cell_surface_normal_test.cc' indexed with language 'cpp'
      20:25:52.477 INFO: Final Memory: 168M/13128M
      20:25:52.174 DEBUG: 'perception/tests/region_2d_hierarchy_test.cc' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/tests/random_color_test.cc' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/tests/traffic_signal_forcer_test.cc' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tests/serializable_feature_helpers_test.cc' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/fh.hh' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tests/image_object_detection_to_3d_test.cc' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tracking/tracked_object.cc' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tests/image_pipeline_association_tests.cc' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tracking/tracker_base.hh' indexed with language 'cpp'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tracking/tracker_util.cc' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/random_color.cc' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/logsets.py' indexed with language 'py'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tools/compute_tracker_2d_metrics.py' indexed with language 'py'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tracking/associator.cc' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/create_logset.py' indexed with language 'py'
      20:25:52.174 DEBUG: 'perception/image_pipeline/tracking/life_cycle_stats.hh' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/tests/__init__.py' indexed with language 'py'
      20:25:52.477 DEBUG: 'perception/utils/tests/__init__.pyc' indexed with language 'null'
      20:25:52.173 DEBUG: 'perception/image_pipeline/tools/BUILD' indexed with language 'null'
      20:25:52.477 DEBUG: 'perception/utils/tests/test_cluster_utils.py' indexed with language 'py'
      20:25:52.173 DEBUG: 'perception/image_pipeline/tools/association_metrics/association_metrics.cc' indexed with language 'cpp'
      20:25:52.173 DEBUG: 'perception/image_pipeline/detection_localization/tests/detection_localization_stereo_test.cc' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/log_utils.pyc' indexed with language 'null'
      20:25:52.477 DEBUG: 'perception/utils/tests/test_cluster_utils_rpc_helper.py' indexed with language 'py'
      20:25:52.477 DEBUG: 'perception/utils/random_color.hh' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/map_2d.hh' indexed with language 'cpp'
      20:25:52.477 DEBUG: 'perception/utils/mr.py' indexed with language 'py'
      20:25:52.477 DEBUG: 'perception/utils/log_locator.py' indexed with language 'py'
      20:25:52.477 INFO: ------------------------------------------------------------------------
      20:25:52.477 DEBUG: 'perception/utils/cluster_utils.pyc' indexed with language 'null'
      20:25:52.177 DEBUG: 'perception/utils/perception_cluster_rpc
      
      20:25:52.177 DEBUG: 'perception/utils/BUILD' indexed with language 'null'
      20:25:52.177 DEBUG: 'perception/utils/tracked_object_helpers.py' indexed with language 'py'
      20:25:52.177 DEBUG: 'perception/utils/fh.cc' indexed with language 'cpp'
      20:25:52.177 DEBUG: 'perception/utils/__init__.py' indexed with language 'py'
      20:25:52.177 DEBUG: 'perception/utils/eigen_utils.hh' indexed with language 'cpp'
      20:25:52.177 DEBUG: 'perception/utils/log_locator.pyc' indexed with language 'null'
      20:25:52.177 DEBUG: 'perception/utils/log_utils.py' indexed with language 'py'
      20:25:52.477 DEBUG: 'perception/utils/tests/test_cluster_utils_rpc_helper.pyc' indexed with language 'null'
      Language of file 'perception/tools/labeling_tool/server/db/001_create_table.sql' can not be decided as the file matches patterns of both sonar.lang.patterns.plsql : **/*.sql,**/*.pks,**/*.pkb and sonar.lang.patterns.tsql : **/*.sql,**/*.tsql
      20:25:52.478 DEBUG: Execution getVersion
      20:25:52.478 DEBUG: Execution stop
      

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              julien.henry Julien Henry
              Reporter:
              julien.henry Julien Henry
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

                Dates

                Due:
                Created:
                Updated:
                Resolved: