glog icon indicating copy to clipboard operation
glog copied to clipboard
verified publisher icon google

0.6.0: test suite is failing in four units

Open kloczek opened this issue 2 years ago • 9 comments

cmake settings:

-- Cache values
BUILD_SHARED_LIBS:BOOL=ON
BUILD_TESTING:BOOL=ON
CMAKE_BUILD_TYPE:STRING=RelWithDebInfo
CMAKE_INSTALL_PREFIX:PATH=/usr
GTest_DIR:PATH=GTest_DIR-NOTFOUND
PRINT_UNSYMBOLIZED_STACK_TRACES:BOOL=OFF
WITH_CUSTOM_PREFIX:BOOL=ON
WITH_GFLAGS:BOOL=ON
WITH_GMOCK:BOOL=ON
WITH_GTEST:BOOL=ON
WITH_PKGCONFIG:BOOL=ON
WITH_SYMBOLIZE:BOOL=ON
WITH_THREADS:BOOL=ON
WITH_TLS:BOOL=ON
WITH_UNWIND:BOOL=ON
gflags_DIR:PATH=/usr/lib64/cmake/gflags

and test suite is failing:

+ cd glog-0.6.0
+ /usr/bin/ctest --test-dir x86_64-redhat-linux-gnu --output-on-failure --force-new-ctest-process -j48 ' '
Internal ctest changing into directory: /home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu
Test project /home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu
      Start  8: cmake_package_config_init
      Start 12: cleanup_init
      Start  1: logging_custom_prefix
      Start  2: demangle
      Start  3: logging
      Start  4: signalhandler
      Start  5: stacktrace
      Start  6: stl_logging
      Start  7: symbolize
 1/16 Test #12: cleanup_init .....................   Passed    0.01 sec
      Start 14: cleanup_immediately
      Start 15: cleanup_with_absolute_prefix
      Start 16: cleanup_with_relative_prefix
 2/16 Test  #2: demangle .........................   Passed    0.02 sec
 3/16 Test  #4: signalhandler ....................   Passed    0.01 sec
 4/16 Test  #6: stl_logging ......................   Passed    0.01 sec
 5/16 Test  #8: cmake_package_config_init ........   Passed    0.03 sec
      Start  9: cmake_package_config_generate
 6/16 Test  #5: stacktrace .......................Subprocess aborted***Exception:   0.12 sec
Obtained 10 stack frames.
Obtained 10 stack frames.
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x11c05) [0x555a4a620c05] 0x555a4a620c05
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x11dc7) [0x555a4a620dc7] 0x555a4a620dc7
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x11f87) [0x555a4a620f87] 0x555a4a620f87
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x12147) [0x555a4a621147] 0x555a4a621147
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x122d7) [0x555a4a6212d7] 0x555a4a6212d7
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x6764) [0x555a4a615764] 0x555a4a615764
/lib64/libc.so.6(+0x27a90) [0x7f8f167bea90] 0x7f8f167bea90
/lib64/libc.so.6(__libc_start_main+0x89) [0x7f8f167beb49] 0x7f8f167beb49
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x7955) [0x555a4a616955] 0x555a4a616955
[(nil)] (nil)
CheckStackTrace() addr: 0x555a4a621280
Backtrace 0: expected: 0x555a4a6206f7..0x555a4a62091f  actual: 0x555a4a620c05 ... F20230116 02:54:48.482174 776173 stacktrace_unittest.cc:109] Check failed: ret_addr <= range.end (0x555a4a620c05 vs. 0x555a4a62091f)
*** Check failure stack trace: ***
    @     0x555a4a61f1a7  google::LogMessage::SendToLog()
    @     0x555a4a618e3d  google::LogMessage::Flush()
    @     0x555a4a620a3a  CheckStackTraceLeaf()
    @     0x555a4a620c05  CheckStackTrace4()
    @     0x555a4a620dc7  CheckStackTrace3()
    @     0x555a4a620f87  CheckStackTrace2()
    @     0x555a4a621147  CheckStackTrace1()
    @     0x555a4a6212d7  CheckStackTrace()
    @     0x555a4a615764  google::thread_msg_data
    @     0x7f8f167bea90  __libc_start_call_main
    @     0x7f8f167beb49  __libc_start_main_alias_2
    @     0x555a4a616955  _start
    @              (nil)  (unknown)

 7/16 Test  #7: symbolize ........................Subprocess aborted***Exception:   0.13 sec
Test case TestWithPCInsideNonInlineFunction passed.
F20230116 02:54:48.486140 776178 symbolize_unittest.cc:373] CHECK_STREQ failed: symbol == "main" (google::thread_msg_data vs. main)
*** Check failure stack trace: ***
    @     0x560a0583cf67  google::LogMessage::SendToLog()
    @     0x560a0583460d  google::LogMessage::Flush()
    @     0x560a05834989  google::LogMessageFatal::~LogMessageFatal()
    @     0x560a0583df7e  TestWithReturnAddress()
    @     0x560a05830a96  google::thread_msg_data
    @     0x7fb601bd3a90  __libc_start_call_main
    @     0x7fb601bd3b49  __libc_start_main_alias_2
    @     0x560a05832155  _start
    @              (nil)  (unknown)

 8/16 Test  #1: logging_custom_prefix ............***Failed    0.26 sec
F20230116 02:54:48.475625 776169 logging_custom_prefix_unittest.cc:334] unexpected new
*** Check failure stack trace: ***
F00000000 00:00:00.000000 776169 logging_custom_prefix_unittest.cc:487] RAW: Check false failed: failure 1
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475730 776169 logging_custom_prefix_unittest.cc:640] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475730 776169 logging_custom_prefix_unittest.cc:640] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.475744 776169 logging_custom_prefix_unittest.cc:641] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475730 776169 logging_custom_prefix_unittest.cc:640] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.475744 776169 logging_custom_prefix_unittest.cc:641] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475755 776169 logging_custom_prefix_unittest.cc:642] CHECK_STRNE failed: __null != __null ( vs. )
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475730 776169 logging_custom_prefix_unittest.cc:640] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.475744 776169 logging_custom_prefix_unittest.cc:641] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475755 776169 logging_custom_prefix_unittest.cc:642] CHECK_STRNE failed: __null != __null ( vs. )F20230116 02:54:48.475767 776169 logging_custom_prefix_unittest.cc:643] CHECK_STRNE failed: "this" != "this" (this vs. this)
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475730 776169 logging_custom_prefix_unittest.cc:640] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.475744 776169 logging_custom_prefix_unittest.cc:641] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475755 776169 logging_custom_prefix_unittest.cc:642] CHECK_STRNE failed: __null != __null ( vs. )F20230116 02:54:48.475767 776169 logging_custom_prefix_unittest.cc:643] CHECK_STRNE failed: "this" != "this" (this vs. this)F20230116 02:54:48.475780 776169 logging_custom_prefix_unittest.cc:644] CHECK_STREQ failed: (string("a")+"b").c_str() == "abc" (ab vs. abc)
*** Check failure stack trace: ***
F20230116 02:54:48.475704 776169 logging_custom_prefix_unittest.cc:638] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.475718 776169 logging_custom_prefix_unittest.cc:639] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475730 776169 logging_custom_prefix_unittest.cc:640] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.475744 776169 logging_custom_prefix_unittest.cc:641] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.475755 776169 logging_custom_prefix_unittest.cc:642] CHECK_STRNE failed: __null != __null ( vs. )F20230116 02:54:48.475767 776169 logging_custom_prefix_unittest.cc:643] CHECK_STRNE failed: "this" != "this" (this vs. this)F20230116 02:54:48.475780 776169 logging_custom_prefix_unittest.cc:644] CHECK_STREQ failed: (string("a")+"b").c_str() == "abc" (ab vs. abc)F20230116 02:54:48.475819 776169 logging_custom_prefix_unittest.cc:659] Check failed: 'static_cast<void *>(NULL)' Must be non NULL
*** Check failure stack trace: ***
I20230116 02:54:48.475906 776169 logging_custom_prefix_unittest.cc:1377]
Passed 9 tests

PASS
Test with golden file failed. We'll try to show the diff:
--- /home/tkloczko/rpmbuild/BUILD/glog-0.6.0/src/logging_custom_prefix_unittest.err.munged      2023-01-16 02:54:48.725797608 +0000
+++ /tmp//captured.err.munged   2023-01-16 02:54:48.728797607 +0000
@@ -1,293 +1,10 @@
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=2 logtostderr=0 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-WARNING: Logging before InitGoogleLogging() is written to STDERR

-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=0 logtostderr=0 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] foo bar 10 3.4
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Plog every 2, iteration 1: Success [0]
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 3, iteration 1
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 4, iteration 1
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 5, iteration 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 1
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if less than 3 every 2, iteration 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 2
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Plog every 2, iteration 3: No such file or directory [2]
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 3
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if less than 3 every 2, iteration 3
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 3, iteration 4
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 4
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Plog every 2, iteration 5: Interrupted system call [4]
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 4, iteration 5
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 5
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 5, iteration 6
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 6
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Plog every 2, iteration 7: No such device or address [6]
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 3, iteration 7
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 7
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 8
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Plog every 2, iteration 9: Exec format error [8]
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 4, iteration 9
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 9
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log every 3, iteration 10
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Log if every 1, iteration 10
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if this
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] array
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] const array
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] foo 1000       1000 3e8
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] foo          1
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] inner
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] outer
-no prefix

-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: foo bar 10 3.400000
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: array
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: const array
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: ptr 0x12345678
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: ptr (nil)
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: foo 1000 0000001000 3e8
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: foo 1000
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: foo 1000
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: RAW_LOG ERROR: The Message was too long!
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: RAW_LOG ERROR: The Message was too long!
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0 on
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 1 on
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 2 on
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=0 logtostderr=0 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=1 stderrthreshold=0 logtostderr=0 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=-1 stderrthreshold=0 logtostderr=0 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=1 logtostderr=0 alsologtostderr=0
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=2 logtostderr=0 alsologtostderr=0
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=3 logtostderr=0 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=3 logtostderr=1 alsologtostderr=0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=0 stderrthreshold=3 logtostderr=0 alsologtostderr=1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=1 stderrthreshold=1 logtostderr=0 alsologtostderr=0
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Test: v=1 stderrthreshold=3 logtostderr=0 alsologtostderr=1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: vlog 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if -1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if info every 1 expr
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] log_if error every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] vlog_if 0 every 1 expr
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_STRING: reported info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_STRING: reported warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_STRING: reported error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_STRING:  LOG_STRING: collected info
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_STRING:  LOG_STRING: collected warning
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_STRING:  LOG_STRING: collected error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: collected info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: collected warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: collected error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: reported info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: reported warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: reported error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_TO_SINK:
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: collected info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: collected warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK: collected error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK_BUT_NOT_TO_LOGFILE: collected info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK_BUT_NOT_TO_LOGFILE: collected warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_SINK_BUT_NOT_TO_LOGFILE: collected error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_STRING: collected info
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_TO_STRING:  LOG_TO_STRING: collected info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_STRING: collected warning
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_TO_STRING:  LOG_TO_STRING: collected warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_STRING: collected error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Captured by LOG_TO_STRING:  LOG_TO_STRING: collected error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_STRING: reported info
-WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_STRING: reported warning
-EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] LOG_TO_STRING: reported error
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Message 1
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Buffering
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Buffered
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Waiting
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Sink got a messages
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Waited
-IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Sink is sending out a message:IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Message 1
+
+
+
+
+
 IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Have 0 left
 EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Message 2
 IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] RAW: Buffering
@@ -308,3 +25,28 @@
 IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Sink capture:IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Message 1
 IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Sink capture:EYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Message 2
 IYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Sink capture:WYEARDATE TIME__ THREADID logging_custom_prefix_unittest.cc:LINE] Message 3
+
+
+
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Waited
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Sink is sending out a message:IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 1
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Have 0 left
+EYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 2
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Buffering
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Buffered
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Waiting
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Sink got a messages
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Waited
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Sink is sending out a message:EYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 2
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Have 0 left
+WYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 3
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Buffering
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Buffered
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Waiting
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Sink got a messages
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] RAW: Waited
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Sink is sending out a message:WYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 3
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Have 0 left
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Sink capture:IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 1
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Sink capture:EYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 2
+IYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Sink capture:WYEARDATE TIME__ THREADID logging_unittest.cc:LINE] Message 3
diff command was failed.
Check failed: MungeAndDiffTestStderr(FLAGS_test_srcdir + "/src/logging_custom_prefix_unittest.err")

 9/16 Test  #3: logging ..........................***Failed    0.26 sec
F20230116 02:54:48.478909 776171 logging_unittest.cc:327] unexpected new
*** Check failure stack trace: ***
F00000000 00:00:00.000000 776171 logging_unittest.cc:498] RAW: Check false failed: failure 1
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479017 776171 logging_unittest.cc:651] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479017 776171 logging_unittest.cc:651] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.479030 776171 logging_unittest.cc:652] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479017 776171 logging_unittest.cc:651] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.479030 776171 logging_unittest.cc:652] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479043 776171 logging_unittest.cc:653] CHECK_STRNE failed: __null != __null ( vs. )
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479017 776171 logging_unittest.cc:651] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.479030 776171 logging_unittest.cc:652] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479043 776171 logging_unittest.cc:653] CHECK_STRNE failed: __null != __null ( vs. )F20230116 02:54:48.479056 776171 logging_unittest.cc:654] CHECK_STRNE failed: "this" != "this" (this vs. this)
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479017 776171 logging_unittest.cc:651] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.479030 776171 logging_unittest.cc:652] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479043 776171 logging_unittest.cc:653] CHECK_STRNE failed: __null != __null ( vs. )F20230116 02:54:48.479056 776171 logging_unittest.cc:654] CHECK_STRNE failed: "this" != "this" (this vs. this)F20230116 02:54:48.479069 776171 logging_unittest.cc:655] CHECK_STREQ failed: (string("a")+"b").c_str() == "abc" (ab vs. abc)
*** Check failure stack trace: ***
F20230116 02:54:48.478991 776171 logging_unittest.cc:649] CHECK_STREQ failed: __null == "this" ( vs. this)F20230116 02:54:48.479004 776171 logging_unittest.cc:650] CHECK_STREQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479017 776171 logging_unittest.cc:651] CHECK_STRCASEEQ failed: __null == "siht" ( vs. siht)F20230116 02:54:48.479030 776171 logging_unittest.cc:652] CHECK_STRCASEEQ failed: "this" == "siht" (this vs. siht)F20230116 02:54:48.479043 776171 logging_unittest.cc:653] CHECK_STRNE failed: __null != __null ( vs. )F20230116 02:54:48.479056 776171 logging_unittest.cc:654] CHECK_STRNE failed: "this" != "this" (this vs. this)F20230116 02:54:48.479069 776171 logging_unittest.cc:655] CHECK_STREQ failed: (string("a")+"b").c_str() == "abc" (ab vs. abc)F20230116 02:54:48.479110 776171 logging_unittest.cc:670] Check failed: 'static_cast<void *>(NULL)' Must be non NULL
*** Check failure stack trace: ***
Passed 8 tests

PASS
Test with golden file failed. We'll try to show the diff:
diff: /tmp//captured.err.munged: No such file or directory
diff command was failed.
Check failed: MungeAndDiffTestStderr(FLAGS_test_srcdir + "/src/logging_unittest.err")

10/16 Test  #9: cmake_package_config_generate ....   Passed    0.49 sec
      Start 10: cmake_package_config_build
11/16 Test #10: cmake_package_config_build .......   Passed    0.80 sec
      Start 11: cmake_package_config_cleanup
12/16 Test #11: cmake_package_config_cleanup .....   Passed    0.01 sec
13/16 Test #14: cleanup_immediately ..............   Passed    3.09 sec
14/16 Test #15: cleanup_with_absolute_prefix .....   Passed    3.09 sec
15/16 Test #16: cleanup_with_relative_prefix .....   Passed    6.10 sec
      Start 13: cleanup_logdir
16/16 Test #13: cleanup_logdir ...................   Passed    0.01 sec

75% tests passed, 4 tests failed out of 16

Total Test time (real) =   6.14 sec

The following tests FAILED:
          1 - logging_custom_prefix (Failed)
          3 - logging (Failed)
          5 - stacktrace (Subprocess aborted)
          7 - symbolize (Subprocess aborted)
Errors while running CTest

kloczek avatar Jan 16 '23 02:01 kloczek

Is this still a problem with glog at head?

sergiud avatar Jan 08 '24 18:01 sergiud

I'm closing the issue in preparation for new release.

In case the problem persists, please submit a new issue with an updated log.

sergiud avatar Jan 10 '24 10:01 sergiud

Is this still a problem with glog at head?

I don't see any commits added after last release related to this issue and just retested issue sill is present.

kloczek avatar Jan 10 '24 10:01 kloczek

Just tested 0.6.0 + all 130 commits added after release.

Here is result of the test suite and one unit still fails 
+ cd glog-0.6.0
+ /usr/bin/ctest --test-dir x86_64-redhat-linux-gnu --output-on-failure --force-new-ctest-process -j48 ' '
Internal ctest changing into directory: /home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu
Test project /home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu
      Start  7: cmake_package_config_init
      Start 11: cleanup_init
      Start  1: demangle
 1/26 Test  #1: demangle .........................***Not Run (Disabled)   0.00 sec
      Start  2: logging
      Start  3: signalhandler
      Start  4: stacktrace
      Start  5: stl_logging
      Start  6: symbolize
      Start 16: striplog0
      Start 17: striplog2
      Start 18: striplog10
      Start 19: log_severity_constants
      Start 20: log_severity_conversion
      Start 21: includes_logging
      Start 22: includes_vlog_is_on
      Start 23: includes_raw_logging
      Start 24: includes_stl_logging
      Start 25: dcheck_on
      Start 26: dcheck_off
 2/26 Test #26: dcheck_off .......................***Not Run (Disabled)   0.00 sec
 3/26 Test  #7: cmake_package_config_init ........   Passed    0.04 sec
      Start  8: cmake_package_config_generate
 4/26 Test #11: cleanup_init .....................   Passed    0.04 sec
      Start 13: cleanup_immediately
      Start 14: cleanup_with_absolute_prefix
      Start 15: cleanup_with_relative_prefix
 5/26 Test  #3: signalhandler ....................   Passed    0.04 sec
 6/26 Test  #5: stl_logging ......................   Passed    0.04 sec
 7/26 Test  #6: symbolize ........................   Passed    0.04 sec
 8/26 Test #16: striplog0 ........................   Passed    0.03 sec
 9/26 Test #17: striplog2 ........................   Passed    0.03 sec
10/26 Test #18: striplog10 .......................   Passed    0.03 sec
11/26 Test #13: cleanup_immediately ..............   Passed    0.04 sec
12/26 Test #14: cleanup_with_absolute_prefix .....   Passed    0.04 sec
13/26 Test #15: cleanup_with_relative_prefix .....   Passed    0.04 sec
      Start 12: cleanup_logdir
14/26 Test #12: cleanup_logdir ...................   Passed    0.01 sec
15/26 Test  #4: stacktrace .......................Subprocess aborted***Exception:   0.14 sec
Obtained 9 stack frames.
Obtained 9 stack frames.
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x1e485) [0x5609170c8485] 0x5609170c8485
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x1e677) [0x5609170c8677] 0x5609170c8677
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x1e857) [0x5609170c8857] 0x5609170c8857
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x1ea37) [0x5609170c8a37] 0x5609170c8a37
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x1ebe7) [0x5609170c8be7] 0x5609170c8be7
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0x9a74) [0x5609170b3a74] 0x5609170b3a74
/lib64/libc.so.6(+0x2814a) [0x7f1a8364514a] 0x7f1a8364514a
/lib64/libc.so.6(__libc_start_main+0x8b) [0x7f1a8364520b] 0x7f1a8364520b
/home/tkloczko/rpmbuild/BUILD/glog-0.6.0/x86_64-redhat-linux-gnu/stacktrace_unittest(+0xadd5) [0x5609170b4dd5] 0x5609170b4dd5
CheckStackTrace() addr: 0x5609170c8b90
Backtrace 0: expected: 0x5609170c7fc7..0x5609170c811b  actual: 0x5609170c8485 ... F20240110 10:49:18.937953 139751855402560 stacktrace_unittest.cc:114] Check failed: ret_addr <= range.end (0x5609170c8485 vs. 0x5609170c811b)
*** Check failure stack trace: ***
    @     0x5609170d2b5c  google::LogMessage::SendToLog()
    @     0x5609170b8906  google::LogMessage::Flush()
    @     0x5609170c824a  CheckStackTraceLeaf()
    @     0x5609170c8485  CheckStackTrace4(int)
    @     0x5609170c8677  CheckStackTrace3(int)
    @     0x5609170c8857  CheckStackTrace2(int)
    @     0x5609170c8a37  CheckStackTrace1(int)
    @     0x5609170c8be7  CheckStackTrace(int)
    @     0x5609170b3a74  main
    @     0x7f1a8364514a  __libc_start_call_main
    @     0x7f1a8364520b  __libc_start_main_alias_2
    @     0x5609170b4dd5  _start

16/26 Test  #2: logging ..........................   Passed    0.26 sec
17/26 Test  #8: cmake_package_config_generate ....   Passed    0.68 sec
      Start  9: cmake_package_config_build
18/26 Test #22: includes_vlog_is_on ..............   Passed    1.26 sec
19/26 Test #23: includes_raw_logging .............   Passed    1.26 sec
20/26 Test #20: log_severity_conversion ..........   Passed    1.32 sec
21/26 Test #19: log_severity_constants ...........   Passed    1.33 sec
22/26 Test #24: includes_stl_logging .............   Passed    1.43 sec
23/26 Test  #9: cmake_package_config_build .......   Passed    0.87 sec
      Start 10: cmake_package_config_cleanup
24/26 Test #10: cmake_package_config_cleanup .....   Passed    0.02 sec
25/26 Test #21: includes_logging .................   Passed    1.67 sec
26/26 Test #25: dcheck_on ........................   Passed    1.72 sec

96% tests passed, 1 tests failed out of 24

Total Test time (real) =   1.76 sec

The following tests did not run:
          1 - demangle (Disabled)
         26 - dcheck_off (Disabled)

The following tests FAILED:
          4 - stacktrace (Subprocess aborted)
Errors while running CTest

kloczek avatar Jan 10 '24 10:01 kloczek

Thanks!

sergiud avatar Jan 10 '24 10:01 sergiud

BTW is it any reason why glog.pc file is no longer installed? 🤔

kloczek avatar Jan 10 '24 12:01 kloczek

There are several reasons why pkg-config is no longer installed by default:

  1. pkg-config support is undocumented.
  2. The support is untested: there are no unit tests ensuring actual usability.
  3. CMake users do not need pkg-config files.

You can however opt in for generating the .pc file by explicitly setting WITH_PKGCONFIG to ON.

sergiud avatar Jan 10 '24 12:01 sergiud

cmake support is 100% procedural. pkgconfig support is purely declarative.

On packaging layer procedural description is easy on generate provides but it is IMPOSSIBLE to process on generate requires dependencies. pkgconfig support is so simple that it does not need any testing. cmake supports use pkgconfig OOTB https://cmake.org/cmake/help/latest/module/FindPkgConfig.html.

kloczek avatar Jan 10 '24 15:01 kloczek

pkgconfig support is so simple that it does not need any testing.

I certainly disagree. There is also no point arguing since you can enable .pc file generation at any time if you really need it.

sergiud avatar Jan 10 '24 15:01 sergiud