Hangs for several minutes with 100% CPU use

CodeLite installation/troubleshooting forum
mattday
CodeLite Enthusiast
Posts: 21
Joined: Mon Jan 09, 2012 11:29 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by mattday »

Attached gdb to codelite process, reproduced the issue, sent a SIGINT and checked trace. Not sure how helpful this will be though.

Code: Select all

#0  0x00007f88dcaa5da4 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#1  0x00007f88dcaa5e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#2  0x00007f88dcaa5e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#3  0x00007f88dcaa5e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#4  0x00007f88dcaa5e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#5  0x00007f88dcaa5e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#6  0x00007f88dcaa759a in TagsManager::TagsByScopeAndName(wxString const&, wxString const&, std::vector<SmartPtr<TagEntry>, std::allocator<SmartPtr<TagEntry> > >&, unsigned long) ()
   from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#7  0x00007f88dcab0207 in TagsManager::WordCompletionCandidates(wxFileName const&, int, wxString const&, wxString const&, wxString const&, std::vector<SmartPtr<TagEntry>, std::allocator<SmartPtr<TagEntry> > >&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#8  0x000000000070fb7c in CodeCompletionManager::DoCtagsWordCompletion(LEditor*, wxString const&, wxString const&)
    ()
#9  0x000000000070fd54 in CodeCompletionManager::WordCompletion(LEditor*, wxString const&, wxString const&) ()
#10 0x00000000008326ab in ContextCpp::CompleteWord() ()
#11 0x0000000000654a2c in LEditor::CompleteWord(bool) ()
#12 0x000000000065581b in LEditor::OnCharAdded(wxStyledTextEvent&) ()
#13 0x00007f88ddc3ea5e in wxAppConsoleBase::CallEventHandler(wxEvtHandler*, wxEventFunctor&, wxEvent&) const ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#14 0x00007f88dddb38f2 in wxEvtHandler::ProcessEventIfMatchesId(wxEventTableEntryBase const&, wxEvtHandler*, wxEvent&) () from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#15 0x00007f88dddb39a3 in wxEventHashTable::HandleEvent(wxEvent&, wxEvtHandler*) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#16 0x00007f88dddb412a in wxEvtHandler::TryHereOnly(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#17 0x00007f88dddb41a3 in wxEvtHandler::ProcessEventLocally(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#18 0x00007f88dddb4205 in wxEvtHandler::ProcessEvent(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#19 0x00007f88df7a3af1 in wxStyledTextCtrl::NotifyParent(SCNotification*) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_stc-3.1.so.0
#20 0x00007f88df7af925 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_stc-3.1.so.0
#21 0x00007f88df7c4084 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_stc-3.1.so.0
#22 0x00007f88df7d1c5d in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_stc-3.1.so.0
#23 0x00007f88df7ea952 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_stc-3.1.so.0
#24 0x00007f88df7b1cdf in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_stc-3.1.so.0
#25 0x00007f88ddc3ea5e in wxAppConsoleBase::CallEventHandler(wxEvtHandler*, wxEventFunctor&, wxEvent&) const ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#26 0x00007f88dddb38f2 in wxEvtHandler::ProcessEventIfMatchesId(wxEventTableEntryBase const&, wxEvtHandler*, wxEvent&) () from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#27 0x00007f88dddb39a3 in wxEventHashTable::HandleEvent(wxEvent&, wxEvtHandler*) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#28 0x00007f88dddb412a in wxEvtHandler::TryHereOnly(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#29 0x00007f88dddb41a3 in wxEvtHandler::ProcessEventLocally(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#30 0x00007f88dddb4205 in wxEvtHandler::ProcessEvent(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#31 0x00007f88dddb5a2c in wxEvtHandler::SafelyProcessEvent(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#32 0x00007f88de79899f in wxWindow::GTKDoInsertTextFromIM(char const*) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#33 0x00007f88d966ef29 in g_cclosure_marshal_VOID__STRINGv () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#34 0x00007f88d966c5e7 in ?? () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#35 0x00007f88d9685088 in g_signal_emit_valist () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#36 0x00007f88d9686212 in g_signal_emit_by_name () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#37 0x00007f88d966ef29 in g_cclosure_marshal_VOID__STRINGv () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#38 0x00007f88d966c5e7 in ?? () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#39 0x00007f88d9685088 in g_signal_emit_valist () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#40 0x00007f88d9686212 in g_signal_emit_by_name () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#41 0x00007f88e0285d30 in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#42 0x00007f88e028652f in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#43 0x00007f88e0288293 in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#44 0x00007f88de79adb3 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#45 0x00007f88e029e815 in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#46 0x00007f88d966c3b8 in g_closure_invoke () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#47 0x00007f88d967dd3d in ?? () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#48 0x00007f88d96856f9 in g_signal_emit_valist () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#49 0x00007f88d9685ce2 in g_signal_emit () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#50 0x00007f88e03ae684 in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#51 0x00007f88e03c1efb in gtk_window_propagate_key_event () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#52 0x00007f88de78a308 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#53 0x00007f88e029e815 in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#54 0x00007f88d966c3b8 in g_closure_invoke () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#55 0x00007f88d967dd3d in ?? () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#56 0x00007f88d96856f9 in g_signal_emit_valist () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#57 0x00007f88d9685ce2 in g_signal_emit () from /usr/lib/x86_64-linux-gnu/libgobject-2.0.so.0
#58 0x00007f88e03ae684 in ?? () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#59 0x00007f88e029d097 in gtk_propagate_event () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#60 0x00007f88e029d37b in gtk_main_do_event () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#61 0x00007f88db3b13ec in ?? () from /usr/lib/x86_64-linux-gnu/libgdk-x11-2.0.so.0
#62 0x00007f88d939ce04 in g_main_context_dispatch () from /lib/x86_64-linux-gnu/libglib-2.0.so.0
#63 0x00007f88d939d048 in ?? () from /lib/x86_64-linux-gnu/libglib-2.0.so.0
#64 0x00007f88d939d30a in g_main_loop_run () from /lib/x86_64-linux-gnu/libglib-2.0.so.0
#65 0x00007f88e029c447 in gtk_main () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#66 0x00007f88de778fe5 in wxGUIEventLoop::DoRun() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#67 0x00007f88ddc81350 in wxEventLoopBase::Run() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#68 0x00007f88ddc40b5d in wxAppConsoleBase::MainLoop() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#69 0x00007f88ddccd10d in wxEntry(int&, wchar_t**) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#70 0x000000000054d94e in main ()
On a different occasion:

Code: Select all

#0  0x00007fbe9d5d5deb in towlower () from /lib/x86_64-linux-gnu/libc.so.6
#1  0x00007fbe9d586bcd in wcscasecmp () from /lib/x86_64-linux-gnu/libc.so.6
#2  0x00007fbe9fbd393c in wxString::CmpNoCase(wxString const&) const ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#3  0x00007fbe9e972d12 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#4  0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#5  0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#6  0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#7  0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#8  0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#9  0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#10 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#11 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#12 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#13 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#14 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#15 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#16 0x00007fbe9e972e18 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#17 0x00007fbe9e97459a in TagsManager::TagsByScopeAndName(wxString const&, wxString const&, std::vector<SmartPtr<TagEntry>, std::allocator<SmartPtr<TagEntry> > >&, unsigned long) ()
   from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#18 0x00007fbe9e97d207 in TagsManager::WordCompletionCandidates(wxFileName const&, int, wxString const&, wxString const&, wxString const&, std::vector<SmartPtr<TagEntry>, std::allocator<SmartPtr<TagEntry> > >&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/liblibcodelite.so
#19 0x000000000070fb7c in CodeCompletionManager::DoCtagsWordCompletion(LEditor*, wxString const&, wxString const&)
    ()
#20 0x000000000070fd54 in CodeCompletionManager::WordCompletion(LEditor*, wxString const&, wxString const&) ()
#21 0x00000000008326ab in ContextCpp::CompleteWord() ()
#22 0x0000000000654a2c in LEditor::CompleteWord(bool) ()
#23 0x00007fbe9fb0ba5e in wxAppConsoleBase::CallEventHandler(wxEvtHandler*, wxEventFunctor&, wxEvent&) const ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#24 0x00007fbe9fc808f2 in wxEvtHandler::ProcessEventIfMatchesId(wxEventTableEntryBase const&, wxEvtHandler*, wxEvent&) () from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#25 0x00007fbe9fc809a3 in wxEventHashTable::HandleEvent(wxEvent&, wxEvtHandler*) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#26 0x00007fbe9fc8112a in wxEvtHandler::TryHereOnly(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#27 0x00007fbe9fc811a3 in wxEvtHandler::ProcessEventLocally(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#28 0x00007fbe9fc81205 in wxEvtHandler::ProcessEvent(wxEvent&) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#29 0x00007fbe9fc82353 in wxEvtHandler::ProcessPendingEvents() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#30 0x00007fbe9fb0f667 in wxAppConsoleBase::ProcessPendingEvents() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#31 0x00007fbea0632e34 in wxApp::DoIdle() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#32 0x00007fbea0632f83 in ?? () from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#33 0x00007fbe9b269ce5 in g_main_context_dispatch () from /lib/x86_64-linux-gnu/libglib-2.0.so.0
#34 0x00007fbe9b26a048 in ?? () from /lib/x86_64-linux-gnu/libglib-2.0.so.0
#35 0x00007fbe9b26a30a in g_main_loop_run () from /lib/x86_64-linux-gnu/libglib-2.0.so.0
#36 0x00007fbea2169447 in gtk_main () from /usr/lib/x86_64-linux-gnu/libgtk-x11-2.0.so.0
#37 0x00007fbea0645fe5 in wxGUIEventLoop::DoRun() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_gtk2u_unofficial_core-3.1.so.0
#38 0x00007fbe9fb4e350 in wxEventLoopBase::Run() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#39 0x00007fbe9fb0db5d in wxAppConsoleBase::MainLoop() ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#40 0x00007fbe9fb9a10d in wxEntry(int&, wchar_t**) ()
   from /usr/lib/x86_64-linux-gnu/codelite/libwx_baseu_unofficial-3.1.so.0
#41 0x000000000054d94e in main ()
User avatar
eranif
CodeLite Plugin
Posts: 6367
Joined: Wed Feb 06, 2008 9:29 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by eranif »

Strange, queries ran from: TagsByScopeAndName should be limited with the "LIMIT N" which makes the performance quite fast.
Can you enable CodeLite logging and see which query takes time to execute?

To enable the log: Settings->Preferences->Misc->Log->Log file verbosity->Developer

Next, try to code complete the slow completion and in the log file you should be able to view which query CodeLite is running
The log file is located at ~/.codelite/codelite.log

Eran
Make sure you have read the HOW TO POST thread
mattday
CodeLite Enthusiast
Posts: 21
Joined: Mon Jan 09, 2012 11:29 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by mattday »

The full log follows. Code completion is set to trigger on the default of three characters. Similar entries continue to be appended until CodeLite is killed.

Code: Select all

[ 13:41:51:422 SYS ] Log verbosity is now set to Developer
[ 13:42:04:452 DVL ] Entry not found in cache: select * from tags where file='/home/matt/project/utils/source.cpp' and kind in ('function','prototype')order by line DESC
[ 13:42:04:452 DVL ] Fetching from disk...
[ 13:42:04:453 DVL ] Fetching from disk...done
[ 13:42:04:454 DVL ] Testing cache for: select * from tags where path='<global>' LIMIT 500
[ 13:42:04:454 DVL ] Fetching from disk
[ 13:42:04:454 DVL ] Fetching from disk...done
[ 13:42:04:455 DVL ] updating cache
[ 13:42:04:455 DVL ] updating cache...done
[ 13:42:04:455 DVL ] Testing cache for: select * from tags where ID IN (select tag_id from global_tags where  name LIKE 'wri%%' ESCAPE '^'  )  LIMIT 500
[ 13:42:04:455 DVL ] Entry not found in cache: select * from tags where ID IN (select tag_id from global_tags where  name LIKE 'wri%%' ESCAPE '^'  )  LIMIT 500
[ 13:42:04:455 DVL ] Fetching from disk...
[ 13:42:04:473 DVL ] Fetching from disk...done
[ 13:42:04:473 DVL ] Updating cache
[ 13:42:04:473 DVL ] Updating cache...done
[ 13:42:04:476 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:476 DVL ] Fetching from disk
[ 13:42:04:477 DVL ] Fetching from disk...done
[ 13:42:04:477 DVL ] updating cache
[ 13:42:04:477 DVL ] updating cache...done
[ 13:42:04:477 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 497
[ 13:42:04:477 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 497
[ 13:42:04:477 DVL ] Fetching from disk...
[ 13:42:04:483 DVL ] Fetching from disk...done
[ 13:42:04:483 DVL ] Updating cache
[ 13:42:04:483 DVL ] Updating cache...done
[ 13:42:04:483 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:483 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:483 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 497
[ 13:42:04:483 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 497
[ 13:42:04:483 DVL ] Testing cache for: select * from tags where path='unicode_helpers' LIMIT 500
[ 13:42:04:483 DVL ] Fetching from disk
[ 13:42:04:483 DVL ] Fetching from disk...done
[ 13:42:04:483 DVL ] updating cache
[ 13:42:04:483 DVL ] updating cache...done
[ 13:42:04:483 DVL ] Testing cache for: select * from tags where scope in('unicode_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 494
[ 13:42:04:483 DVL ] Entry not found in cache: select * from tags where scope in('unicode_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 494
[ 13:42:04:483 DVL ] Fetching from disk...
[ 13:42:04:483 DVL ] Fetching from disk...done
[ 13:42:04:483 DVL ] Updating cache
[ 13:42:04:483 DVL ] Updating cache...done
[ 13:42:04:483 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:483 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:484 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 494
[ 13:42:04:484 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 494
[ 13:42:04:484 DVL ] Fetching from disk...
[ 13:42:04:486 DVL ] Fetching from disk...done
[ 13:42:04:486 DVL ] Updating cache
[ 13:42:04:486 DVL ] Updating cache...done
[ 13:42:04:487 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:487 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:487 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 494
[ 13:42:04:487 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 494
[ 13:42:04:487 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:487 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:487 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 488
[ 13:42:04:487 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 488
[ 13:42:04:487 DVL ] Fetching from disk...
[ 13:42:04:490 DVL ] Fetching from disk...done
[ 13:42:04:490 DVL ] Updating cache
[ 13:42:04:490 DVL ] Updating cache...done
[ 13:42:04:490 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:490 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:490 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 488
[ 13:42:04:490 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 488
[ 13:42:04:490 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:490 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:490 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 476
[ 13:42:04:490 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 476
[ 13:42:04:490 DVL ] Fetching from disk...
[ 13:42:04:495 DVL ] Fetching from disk...done
[ 13:42:04:495 DVL ] Updating cache
[ 13:42:04:495 DVL ] Updating cache...done
[ 13:42:04:495 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:495 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:495 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 476
[ 13:42:04:495 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 476
[ 13:42:04:495 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:495 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:495 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 452
[ 13:42:04:495 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 452
[ 13:42:04:495 DVL ] Fetching from disk...
[ 13:42:04:498 DVL ] Fetching from disk...done
[ 13:42:04:498 DVL ] Updating cache
[ 13:42:04:498 DVL ] Updating cache...done
[ 13:42:04:498 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:498 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:498 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 452
[ 13:42:04:498 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 452
[ 13:42:04:498 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:498 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:498 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 404
[ 13:42:04:498 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 404
[ 13:42:04:498 DVL ] Fetching from disk...
[ 13:42:04:501 DVL ] Fetching from disk...done
[ 13:42:04:501 DVL ] Updating cache
[ 13:42:04:501 DVL ] Updating cache...done
[ 13:42:04:501 DVL ] Testing cache for: select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:502 DVL ] Fetching from disk
[ 13:42:04:502 DVL ] Fetching from disk...done
[ 13:42:04:502 DVL ] updating cache
[ 13:42:04:502 DVL ] updating cache...done
[ 13:42:04:502 DVL ] Testing cache for: select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 404
[ 13:42:04:502 DVL ] Entry not found in cache: select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 404
[ 13:42:04:502 DVL ] Fetching from disk...
[ 13:42:04:502 DVL ] Fetching from disk...done
[ 13:42:04:502 DVL ] Updating cache
[ 13:42:04:502 DVL ] Updating cache...done
[ 13:42:04:502 DVL ] Testing cache for: select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:502 DVL ] [CACHED ITEMS] select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:502 DVL ] Testing cache for: select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 404
[ 13:42:04:502 DVL ] [CACHED ITEMS] select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 404
[ 13:42:04:503 DVL ] Testing cache for: select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:503 DVL ] [CACHED ITEMS] select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:503 DVL ] Testing cache for: select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 308
[ 13:42:04:503 DVL ] Entry not found in cache: select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 308
[ 13:42:04:503 DVL ] Fetching from disk...
[ 13:42:04:503 DVL ] Fetching from disk...done
[ 13:42:04:503 DVL ] Updating cache
[ 13:42:04:503 DVL ] Updating cache...done
[ 13:42:04:503 DVL ] Testing cache for: select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:503 DVL ] [CACHED ITEMS] select * from tags where path='sort_helpers' LIMIT 500
[ 13:42:04:503 DVL ] Testing cache for: select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 308
[ 13:42:04:503 DVL ] [CACHED ITEMS] select * from tags where scope in('sort_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 308
[ 13:42:04:503 DVL ] Testing cache for: select * from tags where path='impl' LIMIT 500
[ 13:42:04:503 DVL ] Fetching from disk
[ 13:42:04:504 DVL ] Fetching from disk...done
[ 13:42:04:504 DVL ] updating cache
[ 13:42:04:504 DVL ] updating cache...done
[ 13:42:04:504 DVL ] Testing cache for: select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:504 DVL ] Entry not found in cache: select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:504 DVL ] Fetching from disk...
[ 13:42:04:504 DVL ] Fetching from disk...done
[ 13:42:04:504 DVL ] Updating cache
[ 13:42:04:504 DVL ] Updating cache...done
[ 13:42:04:504 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:504 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:504 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:504 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:504 DVL ] Fetching from disk...
[ 13:42:04:507 DVL ] Fetching from disk...done
[ 13:42:04:507 DVL ] Updating cache
[ 13:42:04:507 DVL ] Updating cache...done
[ 13:42:04:507 DVL ] Testing cache for: select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:507 DVL ] Fetching from disk
[ 13:42:04:508 DVL ] Fetching from disk...done
[ 13:42:04:508 DVL ] updating cache
[ 13:42:04:508 DVL ] updating cache...done
[ 13:42:04:508 DVL ] Testing cache for: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:508 DVL ] Entry not found in cache: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:508 DVL ] Fetching from disk...
[ 13:42:04:508 DVL ] Fetching from disk...done
[ 13:42:04:508 DVL ] Updating cache
[ 13:42:04:508 DVL ] Updating cache...done
[ 13:42:04:508 DVL ] Testing cache for: select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:508 DVL ] [CACHED ITEMS] select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:508 DVL ] Testing cache for: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:508 DVL ] [CACHED ITEMS] select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 116
[ 13:42:04:509 DVL ] Testing cache for: select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:509 DVL ] [CACHED ITEMS] select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:509 DVL ] Testing cache for: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:509 DVL ] Entry not found in cache: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:509 DVL ] Fetching from disk...
[ 13:42:04:509 DVL ] Fetching from disk...done
[ 13:42:04:509 DVL ] Updating cache
[ 13:42:04:509 DVL ] Updating cache...done
[ 13:42:04:509 DVL ] Testing cache for: select * from tags where path='nric' LIMIT 500
[ 13:42:04:509 DVL ] Fetching from disk
[ 13:42:04:509 DVL ] Fetching from disk...done
[ 13:42:04:510 DVL ] updating cache
[ 13:42:04:510 DVL ] updating cache...done
[ 13:42:04:510 DVL ] Testing cache for: select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:510 DVL ] Entry not found in cache: select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:510 DVL ] Fetching from disk...
[ 13:42:04:510 DVL ] Fetching from disk...done
[ 13:42:04:510 DVL ] Updating cache
[ 13:42:04:510 DVL ] Updating cache...done
[ 13:42:04:510 DVL ] Testing cache for: select * from tags where path='nric' LIMIT 500
[ 13:42:04:510 DVL ] [CACHED ITEMS] select * from tags where path='nric' LIMIT 500
[ 13:42:04:510 DVL ] Testing cache for: select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:510 DVL ] [CACHED ITEMS] select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:511 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:511 DVL ] Fetching from disk
[ 13:42:04:511 DVL ] Fetching from disk...done
[ 13:42:04:511 DVL ] updating cache
[ 13:42:04:511 DVL ] updating cache...done
[ 13:42:04:511 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:511 DVL ] Entry not found in cache: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:511 DVL ] Fetching from disk...
[ 13:42:04:511 DVL ] Fetching from disk...done
[ 13:42:04:511 DVL ] Updating cache
[ 13:42:04:511 DVL ] Updating cache...done
[ 13:42:04:511 DVL ] Testing cache for: select * from tags where path='nric' LIMIT 500
[ 13:42:04:511 DVL ] [CACHED ITEMS] select * from tags where path='nric' LIMIT 500
[ 13:42:04:511 DVL ] Testing cache for: select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:512 DVL ] [CACHED ITEMS] select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:512 DVL ] Testing cache for: select * from tags where path='nric' LIMIT 500
[ 13:42:04:512 DVL ] [CACHED ITEMS] select * from tags where path='nric' LIMIT 500
[ 13:42:04:512 DVL ] Testing cache for: select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:512 DVL ] [CACHED ITEMS] select * from tags where scope in('nric')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:513 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:513 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:513 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:513 DVL ] Entry not found in cache: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:513 DVL ] Fetching from disk...
[ 13:42:04:516 DVL ] Fetching from disk...done
[ 13:42:04:516 DVL ] Updating cache
[ 13:42:04:516 DVL ] Updating cache...done
[ 13:42:04:517 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:517 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:517 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:517 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:519 DVL ] Testing cache for: select * from tags where path='impl_proj' LIMIT 500
[ 13:42:04:519 DVL ] Fetching from disk
[ 13:42:04:519 DVL ] Fetching from disk...done
[ 13:42:04:519 DVL ] updating cache
[ 13:42:04:519 DVL ] updating cache...done
[ 13:42:04:519 DVL ] Testing cache for: select * from tags where scope in('impl_proj')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:519 DVL ] Entry not found in cache: select * from tags where scope in('impl_proj')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:519 DVL ] Fetching from disk...
[ 13:42:04:519 DVL ] Fetching from disk...done
[ 13:42:04:519 DVL ] Updating cache
[ 13:42:04:519 DVL ] Updating cache...done
[ 13:42:04:522 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:522 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:522 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:522 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:524 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:524 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:524 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:524 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:528 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:04:528 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:04:528 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:528 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:532 DVL ] Testing cache for: select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:532 DVL ] [CACHED ITEMS] select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:532 DVL ] Testing cache for: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:532 DVL ] [CACHED ITEMS] select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:538 DVL ] Testing cache for: select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:538 DVL ] [CACHED ITEMS] select * from tags where path='blas_bindings' LIMIT 500
[ 13:42:04:538 DVL ] Testing cache for: select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:538 DVL ] [CACHED ITEMS] select * from tags where scope in('blas_bindings')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:544 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:544 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:544 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:544 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:549 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:549 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:549 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:549 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:556 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:556 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:556 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:556 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:563 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:563 DVL ] Fetching from disk
[ 13:42:04:563 DVL ] Fetching from disk...done
[ 13:42:04:563 DVL ] updating cache
[ 13:42:04:563 DVL ] updating cache...done
[ 13:42:04:563 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:563 DVL ] Entry not found in cache: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:563 DVL ] Fetching from disk...
[ 13:42:04:563 DVL ] Fetching from disk...done
[ 13:42:04:563 DVL ] Updating cache
[ 13:42:04:564 DVL ] Updating cache...done
[ 13:42:04:571 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:571 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:571 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:571 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:585 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:585 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:585 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:586 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:610 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:610 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:610 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:610 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:642 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:642 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:642 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:643 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:678 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:678 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:678 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:678 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:721 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:721 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:721 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:721 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:773 DVL ] Testing cache for: select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:773 DVL ] [CACHED ITEMS] select * from tags where path='morphological_operations_helpers' LIMIT 500
[ 13:42:04:773 DVL ] Testing cache for: select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:773 DVL ] [CACHED ITEMS] select * from tags where scope in('morphological_operations_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:835 DVL ] Testing cache for: select * from tags where path='impl' LIMIT 500
[ 13:42:04:835 DVL ] [CACHED ITEMS] select * from tags where path='impl' LIMIT 500
[ 13:42:04:835 DVL ] Testing cache for: select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:835 DVL ] Entry not found in cache: select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:835 DVL ] Fetching from disk...
[ 13:42:04:835 DVL ] Fetching from disk...done
[ 13:42:04:835 DVL ] Updating cache
[ 13:42:04:836 DVL ] Updating cache...done
[ 13:42:04:900 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:900 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:900 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:901 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:970 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:970 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:04:970 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:04:970 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:035 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:05:035 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:05:035 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:035 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:100 DVL ] Testing cache for: select * from tags where path='impl' LIMIT 500
[ 13:42:05:100 DVL ] [CACHED ITEMS] select * from tags where path='impl' LIMIT 500
[ 13:42:05:100 DVL ] Testing cache for: select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:107 DVL ] [CACHED ITEMS] select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:246 DVL ] Testing cache for: select * from tags where path='impl' LIMIT 500
[ 13:42:05:246 DVL ] [CACHED ITEMS] select * from tags where path='impl' LIMIT 500
[ 13:42:05:246 DVL ] Testing cache for: select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:250 DVL ] [CACHED ITEMS] select * from tags where scope in('impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:465 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:05:465 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:05:465 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:465 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:705 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:05:705 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:05:705 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:705 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:932 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:05:932 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:05:932 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:05:932 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:149 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:06:149 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:06:149 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:149 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:374 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:06:374 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:06:374 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:375 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:590 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:06:590 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:06:590 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:590 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:809 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:06:809 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:06:809 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:06:809 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:031 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:07:031 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:07:031 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:031 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:256 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:07:256 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:07:256 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:256 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:478 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:07:478 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:07:478 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:478 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:701 DVL ] Testing cache for: select * from tags where path='red_impl' LIMIT 500
[ 13:42:07:701 DVL ] Fetching from disk
[ 13:42:07:701 DVL ] Fetching from disk...done
[ 13:42:07:701 DVL ] updating cache
[ 13:42:07:701 DVL ] updating cache...done
[ 13:42:07:701 DVL ] Testing cache for: select * from tags where scope in('red_impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:701 DVL ] Entry not found in cache: select * from tags where scope in('red_impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:701 DVL ] Fetching from disk...
[ 13:42:07:702 DVL ] Fetching from disk...done
[ 13:42:07:702 DVL ] Updating cache
[ 13:42:07:705 DVL ] Updating cache...done
[ 13:42:07:933 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:07:933 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:07:933 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:07:933 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:171 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:08:171 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:08:171 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:171 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:398 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:08:398 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:08:398 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:398 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:625 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:08:625 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:08:625 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:625 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:857 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:08:857 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:08:857 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:08:857 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:101 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:09:101 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:09:101 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:101 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:337 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:09:337 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:09:337 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:337 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:574 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:09:574 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:09:574 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:574 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:808 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:09:808 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:09:808 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:09:808 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:045 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:10:045 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:10:045 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:045 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:284 DVL ] Testing cache for: select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:10:284 DVL ] [CACHED ITEMS] select * from tags where path='dlib::impl' LIMIT 500
[ 13:42:10:284 DVL ] Testing cache for: select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:285 DVL ] [CACHED ITEMS] select * from tags where scope in('dlib::impl')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:519 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:10:519 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:10:519 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:519 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:753 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:10:753 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:10:753 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:753 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:999 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:10:999 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:10:999 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:10:999 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:11:237 DVL ] Testing cache for: select * from tags where path='hessian_pyramid_helpers' LIMIT 500
[ 13:42:11:237 DVL ] Fetching from disk
[ 13:42:11:237 DVL ] Fetching from disk...done
[ 13:42:11:237 DVL ] updating cache
[ 13:42:11:237 DVL ] updating cache...done
[ 13:42:11:237 DVL ] Testing cache for: select * from tags where scope in('hessian_pyramid_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:11:237 DVL ] Entry not found in cache: select * from tags where scope in('hessian_pyramid_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:11:237 DVL ] Fetching from disk...
[ 13:42:11:237 DVL ] Fetching from disk...done
[ 13:42:11:237 DVL ] Updating cache
[ 13:42:11:241 DVL ] Updating cache...done
[ 13:42:11:495 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:11:495 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:11:495 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:11:495 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:11:749 DVL ] Testing cache for: select * from tags where path='hessian_pyramid_helpers' LIMIT 500
[ 13:42:11:750 DVL ] [CACHED ITEMS] select * from tags where path='hessian_pyramid_helpers' LIMIT 500
[ 13:42:11:750 DVL ] Testing cache for: select * from tags where scope in('hessian_pyramid_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:11:760 DVL ] [CACHED ITEMS] select * from tags where scope in('hessian_pyramid_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:12:245 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:42:12:245 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:42:12:245 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:12:245 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:12:756 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:12:756 DVL ] Fetching from disk
[ 13:42:12:756 DVL ] Fetching from disk...done
[ 13:42:12:756 DVL ] updating cache
[ 13:42:12:756 DVL ] updating cache...done
[ 13:42:12:756 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:12:757 DVL ] Entry not found in cache: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:12:757 DVL ] Fetching from disk...
[ 13:42:12:757 DVL ] Fetching from disk...done
[ 13:42:12:757 DVL ] Updating cache
[ 13:42:12:764 DVL ] Updating cache...done
[ 13:42:13:269 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:13:269 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:13:269 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:13:288 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:14:268 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:14:268 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:14:268 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:14:307 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:16:010 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:16:010 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:16:010 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:16:018 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:18:218 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:18:218 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:18:218 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:18:405 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:20:965 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:20:965 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:20:965 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:20:974 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:24:151 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:24:151 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:24:151 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:24:159 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:28:025 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:28:025 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:28:025 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:28:032 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:32:456 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:32:456 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:32:456 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:32:853 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:37:795 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:37:795 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:37:795 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:37:803 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:43:456 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:43:457 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:43:457 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:43:464 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:49:513 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:49:513 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:49:514 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:49:521 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:56:485 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:56:486 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:42:56:486 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:42:56:494 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:04:091 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:04:091 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:04:091 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:04:099 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:12:417 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:12:417 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:12:417 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:12:424 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:21:705 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:21:706 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:21:706 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:21:716 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:31:278 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:31:278 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:31:278 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:32:060 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:41:710 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:41:710 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:41:710 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:41:718 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:52:918 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:52:918 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:43:52:919 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:43:52:926 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:44:04:359 DVL ] Testing cache for: select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:44:04:359 DVL ] [CACHED ITEMS] select * from tags where path='bfp1_helpers' LIMIT 500
[ 13:44:04:359 DVL ] Testing cache for: select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:44:04:366 DVL ] [CACHED ITEMS] select * from tags where scope in('bfp1_helpers')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:44:16:400 DVL ] Testing cache for: select * from tags where path='std' LIMIT 500
[ 13:44:16:401 DVL ] [CACHED ITEMS] select * from tags where path='std' LIMIT 500
[ 13:44:16:401 DVL ] Testing cache for: select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
[ 13:44:16:401 DVL ] [CACHED ITEMS] select * from tags where scope in('std')  AND  name LIKE 'wri%%' ESCAPE '^'  LIMIT 1
User avatar
eranif
CodeLite Plugin
Posts: 6367
Joined: Wed Feb 06, 2008 9:29 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by eranif »

Is it possible to have a minimal workspace that reproduces this error?
Make sure you have read the HOW TO POST thread
mattday
CodeLite Enthusiast
Posts: 21
Joined: Mon Jan 09, 2012 11:29 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by mattday »

I'll have another go at a minimal workspace when I get some time, but haven't been successful in the past. The issue doesn't seem to occur when things are minimal. I have a suspicion it is related to including the dlib library, which is done by adding a source file (dlib/all/source.cpp) to the project, which in turn includes loads of other source files.

Are there any clues from the developer log output?
hlucid
CodeLite Curious
Posts: 2
Joined: Sun Oct 22, 2017 10:51 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by hlucid »

Was this issue ever resolved? I am having the exact same issue. I stopped using Codelite back at version 9 because of this. Today I tried using Codelite again (version 11) and the issue still comes up. This bug is crippling.
User avatar
eranif
CodeLite Plugin
Posts: 6367
Joined: Wed Feb 06, 2008 9:29 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by eranif »

Like I mentioned in the post: we need a reproduction environment, this is not reproducible on my side.
I am using CodeLite on daily basis, if I have seen this - I would have fixed it. So I really need you help here to reproduce this
Make sure you have read the HOW TO POST thread
hlucid
CodeLite Curious
Posts: 2
Joined: Sun Oct 22, 2017 10:51 pm
Genuine User: Yes
IDE Question: C++
Contact:

Re: Hangs for several minutes with 100% CPU use

Post by hlucid »

Thank you for responding. I will try to recreate the issue on a new project and get back to you.
Post Reply