[vbox-dev] debug version of VirtualBox coredumps

Tomas Hurka tomas.hurka at googlemail.com
Sun May 22 10:37:48 GMT 2016 

Hi All,
I am trying to run debug version of VirtualBox with logging enabled and VirtualBox (current version compiled from sources) coredumps. I am using the following env. properties to enable logging:
export VBOX_LOG=main.e.l.f
export VBOX_LOG_FLAGS="time tid thread"
export VBOX_LOG_DEST=dir=/tmp/vbox

VirtualBox crashes while starting with the following back-trace:

Core was generated by `Projects/Source/VirtualBox/out/linux.amd64/debug/bin/VirtualBox'.
Program terminated with signal SIGTRAP, Trace/breakpoint trap.
#0  0x00007fdd6587ea8a in rtCritSectEnter (pSrcPos=0x7fffcd0b67d0, pCritSect=0x2188230)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/generic/critsect-generic.cpp:291
291	            AssertBreakpoint(); /* don't do normal assertion here, the logger uses this code too. */
(gdb) bt
#0  0x00007fdd6587ea8a in rtCritSectEnter (pSrcPos=0x7fffcd0b67d0, pCritSect=0x2188230)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/generic/critsect-generic.cpp:291
#1  RTCritSectEnterDebug (pCritSect=0x2188230, uId=0, 
   pszFile=0x7fdd659beee0 "/home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/r3/generic/semspinmutex-r3-generic.cpp", iLine=83, 
   pszFunction=0x7fdd659bf020 <RTSemSpinMutexRequest::__PRETTY_FUNCTION__> "int RTSemSpinMutexRequest(RTSEMSPINMUTEX)")
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/generic/critsect-generic.cpp:360
#2  0x00007fdd658aba1a in RTSemSpinMutexRequest (hSpinMtx=0x2188230)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/r3/generic/semspinmutex-r3-generic.cpp:83
#3  0x00007fdd657c793a in rtlogLock (pLogger=0x21910c0) at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/log.cpp:386
#4  0x00007fdd657cd062 in RTLogLoggerExV(PRTLOGGER, unsigned int, unsigned int, const char *, typedef __va_list_tag __va_list_tag *) (pLogger=0x21910c0, 
   fFlags=0, iGroup=4294967295, pszFormat=0x7fdd659825b8 "\n!!Assertion Failed!!\nExpression: %s\nLocation  : %s(%d) %s\n", args=0x7fffcd0b6908)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/log.cpp:2900
#5  0x00007fdd657ccf9d in RTLogLoggerV(PRTLOGGER, const char *, typedef __va_list_tag __va_list_tag *) (pLogger=0x0, 
   pszFormat=0x7fdd659825b8 "\n!!Assertion Failed!!\nExpression: %s\nLocation  : %s(%d) %s\n", args=0x7fffcd0b6908)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/log.cpp:2845
#6  0x00007fdd657cd297 in RTLogPrintfV(const char *, typedef __va_list_tag __va_list_tag *) (
   pszFormat=0x7fdd659825b8 "\n!!Assertion Failed!!\nExpression: %s\nLocation  : %s(%d) %s\n", va=0x7fffcd0b6908)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/log.cpp:3114
#7  0x00007fdd657cf3e6 in RTLogPrintf (pszFormat=0x7fdd659825b8 "\n!!Assertion Failed!!\nExpression: %s\nLocation  : %s(%d) %s\n")
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/logellipsis.cpp:101
#8  0x00007fdd657de81b in RTAssertMsg1 (pszExpr=0x0, uLine=94, 
   pszFile=0x7fdd6597ef80 "/home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/logformat.cpp", 
   pszFunction=0x7fdd6597f020 <rtlogFormatStr(void*, unsigned long (*)(void*, char const*, unsigned long), void*, char const**, __va_list_tag (*) [1], int, int, unsigned int, char)::__PRETTY_FUNCTION__> "size_t rtlogFormatStr(void*, PFNRTSTROUTPUT, void*, const char**, __va_list_tag (*)[1], int, int, unsigned int, char)") at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/misc/assert.cpp:170
#9  0x00007fdd657dd746 in RTAssertMsg1Weak (pszExpr=0x0, uLine=94, 
   pszFile=0x7fdd6597ef80 "/home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/logformat.cpp", 
   pszFunction=0x7fdd6597f020 <rtlogFormatStr(void*, unsigned long (*)(void*, char const*, unsigned long), void*, char const**, __va_list_tag (*) [1], int, int, unsigned int, char)::__PRETTY_FUNCTION__> "size_t rtlogFormatStr(void*, PFNRTSTROUTPUT, void*, const char**, __va_list_tag (*)[1], int, int, unsigned int, char)") at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/misc/RTAssertMsg1Weak.cpp:39
#10 0x00007fdd657cf9b5 in rtlogFormatStr (pvArg=0x0, pfnOutput=0x7fdd657cdea5 <rtLogOutputPrefixed(void*, char const*, size_t)>, 
   pvArgOutput=0x7fffcd0b6cc0, ppszFormat=0x7fffcd0b6b38, pArgs=0x7fffcd0b6bd0, cchWidth=-1, cchPrecision=-1, fFlags=0, chArgSize=104 'h')
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/logformat.cpp:94
#11 0x00007fdd6582c8b2 in RTStrFormatV(PFNRTSTROUTPUT, void *, PFNSTRFORMAT, void *, const char *, typedef __va_list_tag __va_list_tag *) (
   pfnOutput=0x7fdd657cdea5 <rtLogOutputPrefixed(void*, char const*, size_t)>, pvArgOutput=0x7fffcd0b6cc0, 
   pfnFormat=0x7fdd657cf95a <rtlogFormatStr(void*, PFNRTSTROUTPUT, void*, char const**, va_list*, int, int, unsigned int, char)>, pvArgFormat=0x0, 
   pszFormat=0x7fdd4f015720 "c\n", InArgs=0x7fffcd0b6d58) at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/string/strformat.cpp:806
#12 0x00007fdd657cf958 in RTLogFormatV(PFNRTSTROUTPUT, void *, const char *, typedef __va_list_tag __va_list_tag *) (
   pfnOutput=0x7fdd657cdea5 <rtLogOutputPrefixed(void*, char const*, size_t)>, pvArg=0x7fffcd0b6cc0, pszFormat=0x7fdd4f015552 "{%p} %Rfn: %M", 
   args=0x7fffcd0b6d58) at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/logformat.cpp:67
#13 0x00007fdd657cf0e4 in rtlogLoggerExVLocked(PRTLOGGER, unsigned int, unsigned int, const char *, typedef __va_list_tag __va_list_tag *) (
   pLogger=0x21910c0, fFlags=2, iGroup=169, pszFormat=0x7fdd4f015552 "{%p} %Rfn: %M", args=0x7fffcd0b6d58)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/log.cpp:3932
#14 0x00007fdd657cd264 in RTLogLoggerExV(PRTLOGGER, unsigned int, unsigned int, const char *, typedef __va_list_tag __va_list_tag *) (pLogger=0x21910c0, 
   fFlags=2, iGroup=169, pszFormat=0x7fdd4f015552 "{%p} %Rfn: %M", args=0x7fffcd0b6d58)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/log.cpp:2936
#15 0x00007fdd657cf345 in RTLogLoggerEx (pLogger=0x21910c0, fFlags=2, iGroup=169, pszFormat=0x7fdd4f015552 "{%p} %Rfn: %M")
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Runtime/common/log/logellipsis.cpp:83
#16 0x00007fdd4ee86aa3 in VirtualBoxClient::init (this=0x233efd0)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Main/src-client/VirtualBoxClientImpl.cpp:147
#17 0x00007fdd4ee863be in VirtualBoxClient::FinalConstruct (this=0x233efd0)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Main/src-client/VirtualBoxClientImpl.cpp:45
#18 0x00007fdd4ef2af7d in VirtualBoxClientConstructor (aOuter=0x0, aIID=..., aResult=0x7fffcd0b6ff8)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Main/src-client/xpcom/module.cpp:111
#19 0x00007fdd64e9dada in nsGenericFactory::CreateInstance (this=0x2356850, aOuter=0x0, aIID=..., aResult=0x7fffcd0b6ff8)
   at /home/thurka/Projects/Source/VirtualBox/src/libs/xpcom18a4/xpcom/glue/nsGenericFactory.cpp:82
#20 0x00007fdd64e3c0bf in nsComponentManagerImpl::CreateInstance (this=0x23088a0, aClass=..., aDelegate=0x0, aIID=..., aResult=0x7fffcd0b6ff8)
   at /home/thurka/Projects/Source/VirtualBox/src/libs/xpcom18a4/xpcom/components/nsComponentManager.cpp:1876
#21 0x00000000005172d8 in CInterface<IVirtualBoxClient, COMBaseWithEI>::createInstance (this=0x23c39d0, aClsId=...)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Frontends/VirtualBox/src/globals/COMDefs.h:664
#22 0x0000000000510b92 in VBoxGlobal::prepare (this=0x23c39b0)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Frontends/VirtualBox/src/globals/VBoxGlobal.cpp:4015
#23 0x00000000004fd8ba in VBoxGlobal::create () at /home/thurka/Projects/Source/VirtualBox/src/VBox/Frontends/VirtualBox/src/globals/VBoxGlobal.cpp:201
#24 0x0000000000455696 in TrustedMain (argc=1, argv=0x21c34d0) at /home/thurka/Projects/Source/VirtualBox/src/VBox/Frontends/VirtualBox/src/main.cpp:512
#25 0x0000000000455d96 in main (argc=1, argv=0x21c34d0, envp=0x7fffcd0b95a8)
   at /home/thurka/Projects/Source/VirtualBox/src/VBox/Frontends/VirtualBox/src/main.cpp:649


I found out that the crash is caused by some strange formatting used at VirtualBoxClientImpl.cpp:147 (frame #16). There is the following line:
LogFlowThisFunc(("rc=%hrc\n", rc));
There are similar usages of LogFlowThisFunc, but all use "rc=%Rrc\n”, so I replaced above line with:
LogFlowThisFunc(("rc=%Rrc\n", rc));
and this fixed the crash for me.

Is this a known problem?

Bye,
--
Tomas Hurka   <mailto:tomas.hurka at oracle.com>
NetBeans Profiler http://profiler.netbeans.org
VisualVM http://visualvm.java.net
Software Developer
Oracle, Praha Czech Republic

More information about the vbox-dev mailing list