Sampling process 18989 for 3 seconds with 1 millisecond of run time between samples 2008-06-26 21:11:40.318 sample[19052:1103] unable to read PC for current frame pointer; truncating stack 2008-06-26 21:11:40.615 sample[19052:1103] unable to read PC for current frame pointer; truncating stack 2008-06-26 21:11:41.465 sample[19052:1103] unable to read PC for current frame pointer; truncating stack Sampling completed, processing symbols... Analysis of sampling VirtualBoxVM (pid 18989) every 1 millisecond Call graph: 1440 Thread_2503 1440 start 1440 _start 1440 main 1440 QEventLoop::exec() 1440 QEventLoop::enterLoop() 1440 QEventLoop::processEvents(unsigned int) 1440 CFRunLoopRunInMode 1440 CFRunLoopRunSpecific 1437 mach_msg 1437 mach_msg_trap 1437 mach_msg_trap 2 __CFRunLoopTimerRescheduleWithAllModes 2 mk_timer_arm 2 mk_timer_arm 1 __CFRunLoopDoObservers 1 FlushAllBuffers(__CFRunLoopObserver*, unsigned long, void*) 1 FlushWindowObject(WindowData*, void**, unsigned char) 1 FlushPlatformWindow 1 QDFlushPortBuffer 1 QDPlatformFlushWindow 1 CGSWindowAddRegionToDirtyShape 1 _CGSWindowByID 1 _CGSWindowByID 1440 Thread_2603 1440 thread_start 1440 _pthread_start 1440 _pt_root 1440 ConnThread(void*) 1440 PR_Poll 1440 _pr_poll_with_poll 1440 poll 1440 select$DARWIN_EXTSN$NOCANCEL 1440 select$DARWIN_EXTSN$NOCANCEL 1440 Thread_2703 1440 thread_start 1440 _pthread_start 1440 _pt_root 1440 nsThread::Main(void*) 1440 DConnectWorker::Run() 1440 PR_Wait 1440 PR_WaitCondVar 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap 1440 Thread_2803 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 hgcmWorkerThreadFunc(RTTHREADINT*, void*) 1440 hgcmThread(unsigned int, void*) 1440 hgcmMsgGet(unsigned int, HGCMMsgCore**) 1440 HGCMThread::MsgGet(HGCMMsgCore**) 1440 rtSemEventMultiWait(RTSEMEVENTMULTIINTERNAL*, unsigned int, bool) 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap 1440 Thread_2903 1424 thread_start 1424 _pthread_start 1424 rtThreadNativeMain(void*) 1424 rtThreadMain 1424 vmR3EmulationThread(RTTHREADINT*, void*) 1424 EMR3ExecuteVM 1354 VMMR3RawRunGC 1350 ioctl 1350 ioctl 2 VMMR3RawRunGC 1 CPUMSetHyperESP 1 CPUMSetHyperESP 1 suplibOsIOCtlFast 1 __error 1 __error 33 emR3RawHandleRC(VM*, CPUMCTX*, int) 31 emR3RawPrivileged(VM*) 22 emR3RawExecuteInstructionWorker(VM*, int) 22 REMR3EmulateInstruction 17 REMR3State 12 nocrt_memset 12 nocrt_memset 4 tlb_flush_page 4 tlb_flush_page 1 CPUMGetAndClearChangedFlagsREM 1 CPUMGetAndClearChangedFlagsREM 2 REMR3EmulateInstruction 1 dyld_stub_remR3FlushPage 1 dyld_stub_remR3FlushPage 1 restore_raw_fp_state 1 restore_raw_fp_state 1 tlb_flush_page 1 tlb_flush_page 6 CPUMR3DisasmInstrCPU 4 DISInstr 4 DISInstrEx 3 ParseInstruction 3 ParseTwoByteEsc 2 ParseInstruction 1 ParseGrp7 1 ParseInstruction 1 ParseModRM 1 UseModRM 1 UseModRM 1 ParseInstruction 1 DISReadByte 1 DISReadByte 1 DISReadByte 1 cpumR3DisasInstrRead(unsigned int, unsigned char*, unsigned int, void*) 1 PGMPhysGCPtr2CCPtrReadOnly 1 PGMPhysGCPhys2HCPtr 1 PGMPhysGCPhys2HCPtr 2 SELMR3GetShadowSelectorInfo 2 SELMR3GetShadowSelectorInfo 2 PATMR3InstallPatch 1 CSAMR3CheckCode 1 csamAnalyseCodeStream(VM*, unsigned int, unsigned int, bool, int (*)(VM*, _DISCPUSTATE*, unsigned int, unsigned int, CSAMP2GLOOKUPREC*, void*), void*, CSAMP2GLOOKUPREC*) 1 csamR3CheckPageRecord(VM*, unsigned int) 1 csamR3CalcPageHash(VM*, unsigned int) 1 PGMPhysReadGCPtr 1 __memcpy 1 __memcpy 1 PATMR3InstallPatch 1 PATMR3IsInsidePatchJump 1 PATMFindActivePatchByEntrypoint 1 RTAvloGCPtrGetBestFit 1 RTAvloGCPtrGetBestFit 1 emR3RawExecuteInstructionWorker(VM*, int) 1 REMR3EmulateInstruction 1 REMR3State 1 nocrt_memset 1 nocrt_memset 1 emR3RawHandleRC(VM*, CPUMCTX*, int) 17 emR3ForcedActions(VM*, int) 9 TMR3TimerQueuesDo 5 tmR3TimerQueueRun(VM*, TMTIMERQUEUE*) 4 vmmR3YieldEMT(VM*, TMTIMER*, void*) 4 RTThreadYield 4 sched_yield 4 swtch_pri 4 swtch_pri 1 TMVirtualGet 1 TMVirtualGetEx 1 RTTimeNanoTSLFenceSync 1 RTTimeNanoTSLFenceSync 3 pit_irq_timer_update 2 TMTimerSet 2 tmTimerQueueSchedule 2 tmTimerQueueSchedule 1 pit_irq_timer_update 1 TMR3TimerQueuesDo 4 PDMR3Poll 4 drvNATPoller(PDMDRVINS*) 4 select$DARWIN_EXTSN$NOCANCEL 4 select$DARWIN_EXTSN$NOCANCEL 3 TRPMR3InjectEvent 3 TRPMForwardTrap 1 PGMPhysGCPtr2CCPtr 1 PGMPhysGCPhys2HCPtr 1 PGMPhysGCPhys2HCPtr 1 SELMValidateAndConvertCSAddr 1 SELMValidateAndConvertCSAddr 1 TRPMForwardTrap 1 emR3ForcedActions(VM*, int) 8 CSAMR3CheckCode 8 csamAnalyseCodeStream(VM*, unsigned int, unsigned int, bool, int (*)(VM*, _DISCPUSTATE*, unsigned int, unsigned int, CSAMP2GLOOKUPREC*, void*), void*, CSAMP2GLOOKUPREC*) 8 csamR3CheckPageRecord(VM*, unsigned int) 7 csamR3CalcPageHash(VM*, unsigned int) 6 PGMPhysReadGCPtr 3 PGMPhysGCPtr2HCPtr 1 PGMPhysGCPhys2HCPtr 1 PGMPhysGCPhys2HCPtr 1 PGMPhysGCPtr2HCPtr 1 __i686.get_pc_thunk.bx 1 __i686.get_pc_thunk.bx 2 __memcpy 2 __memcpy 1 PGMPhysReadGCPtr 1 __udivdi3 1 __udivdi3 1 RTAvlPVGet 1 RTAvlPVGet 4 RTCritSectEnter 4 RTCritSectEnter 3 EMR3ExecuteVM 2 CPUMRawLeave 2 CPUMRawLeave 1 CPUMRawEnter 1 CPUMRawEnter 1 RTCritSectLeave 1 RTCritSectLeave 1 __i686.get_pc_thunk.bx 1 __i686.get_pc_thunk.bx 5 cpu_x86_gen_code 4 gen_intermediate_code 4 disas_insn 2 disas_insn 2 ldub_code_raw 1 remR3GetOpcode 1 PATMR3QueryOpcode 1 RTAvloGCPtrGetBestFit 1 RTAvloGCPtrGetBestFit 1 remR3PhysReadU8 1 PGMR3PhysReadU8 1 PGMR3PhysReadU8 1 nocrt_memcpy 1 nocrt_memcpy 4 TMTimerPoll 3 TMVirtualGet 3 TMVirtualGetEx 3 RTTimeNanoTSLFenceSync 3 RTTimeNanoTSLFenceSync 1 TMTimerPoll 1 0xcb4d0000 1 0x7c0007 1 0x7c0007 1 0xcb550000 1 0x7c0007 1 0x7c0007 1 0xcb570000 1 helper_invlpg 1 cpu_x86_flush_tlb 1 cpu_x86_flush_tlb 1 RTCritSectLeave 1 RTCritSectLeave 1 dyld_stub_gen_intermediate_code 1 dyld_stub_gen_intermediate_code 1 nocrt_memset 1 nocrt_memset 1 remR3FlushPage 1 PGMInvalidatePage 1 csamFlushPage(VM*, unsigned int, bool) 1 RTAvlPVGet 1 RTAvlPVGet 1440 Thread_2a03 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 hgcmWorkerThreadFunc(RTTHREADINT*, void*) 1440 hgcmServiceThread(unsigned int, void*) 1440 hgcmMsgGet(unsigned int, HGCMMsgCore**) 1440 HGCMThread::MsgGet(HGCMMsgCore**) 1440 rtSemEventMultiWait(RTSEMEVENTMULTIINTERNAL*, unsigned int, bool) 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap 1440 Thread_2b03 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 vboxClipboardThread(RTTHREADINT*, void*) 1440 RTThreadUserWait 1440 rtSemEventMultiWait(RTSEMEVENTMULTIINTERNAL*, unsigned int, bool) 1440 pthread_cond_timedwait 1440 _pthread_cond_wait 1438 semaphore_timedwait_signal_trap 1438 semaphore_timedwait_signal_trap 2 _pthread_cond_wait 1440 Thread_2c03 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 rtTimerThread(RTTHREADINT*, void*) 1440 rtSemEventWait(RTSEMEVENTINTERNAL*, unsigned int, bool) 1439 pthread_cond_timedwait 1439 _pthread_cond_wait 1425 semaphore_timedwait_signal_trap 1425 semaphore_timedwait_signal_trap 11 _pthread_cond_wait 3 pthread_mutex_lock 3 semaphore_wait_trap 3 semaphore_wait_trap 1 pthread_mutex_unlock 1 pthread_mutex_unlock 1440 Thread_2d03 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 hgcmWorkerThreadFunc(RTTHREADINT*, void*) 1440 hgcmServiceThread(unsigned int, void*) 1440 hgcmMsgGet(unsigned int, HGCMMsgCore**) 1440 HGCMThread::MsgGet(HGCMMsgCore**) 1440 rtSemEventMultiWait(RTSEMEVENTMULTIINTERNAL*, unsigned int, bool) 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap 1440 Thread_2e03 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 ataAsyncIOLoop(RTTHREADINT*, void*) 1440 rtSemEventWait(RTSEMEVENTINTERNAL*, unsigned int, bool) 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap 1440 Thread_2f03 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 ataAsyncIOLoop(RTTHREADINT*, void*) 1440 rtSemEventWait(RTSEMEVENTINTERNAL*, unsigned int, bool) 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap 1440 Thread_3003 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 drvHostBaseMediaThread(RTTHREADINT*, void*) 1439 rtSemEventWait(RTSEMEVENTINTERNAL*, unsigned int, bool) 1439 pthread_cond_timedwait 1439 _pthread_cond_wait 1439 semaphore_timedwait_signal_trap 1439 semaphore_timedwait_signal_trap 1 drvHostDvdPoll(DRVHOSTBASE*) 1 DRVHostBaseScsiCmd 1 SCSITaskClass::ExecuteTaskSync(SCSI_Sense_Data*, SCSITaskStatus*, unsigned long long*) 1 SCSITaskClass::ExecuteTask() 1 IOConnectCallStructMethod 1 IOConnectCallMethod 1 io_connect_method 1 mach_msg 1 mach_msg_trap 1 mach_msg_trap 1440 Thread_3103 1440 thread_start 1440 _pthread_start 1440 rtThreadNativeMain(void*) 1440 rtThreadMain 1440 pdmR3ThreadMain(RTTHREADINT*, void*) 1440 pcnetAsyncSendThread(PDMDEVINS*, PDMTHREAD*) 1440 rtSemEventWait(RTSEMEVENTINTERNAL*, unsigned int, bool) 1440 pthread_cond_wait 1440 _pthread_cond_wait 1440 semaphore_wait_signal_trap 1440 semaphore_wait_signal_trap Total number in stack (recursive counted multiple, when >=5): 12 _pthread_start 12 thread_start 10 _pthread_cond_wait 10 rtThreadMain 10 rtThreadNativeMain(void*) 7 pthread_cond_wait 7 semaphore_wait_signal_trap 5 rtSemEventWait(RTSEMEVENTINTERNAL*, unsigned int, bool) Sort by top of stack, same collapsed (when >= 5): semaphore_wait_signal_trap 10080 semaphore_timedwait_signal_trap 4302 select$DARWIN_EXTSN$NOCANCEL 1444 mach_msg_trap 1438 ioctl 1350 nocrt_memset 14 _pthread_cond_wait 13 tlb_flush_page 5 Sample analysis of process 18989 written to file /dev/stdout