xenia/home
1
0
Fork 0
Code Issues Pull Requests Packages Projects Releases Wiki Activity

Use microseconds instead of milliseconds for built-in profiling

Browse Source
This commit is contained in:
Maxime Coste 2017-06-07 20:06:47 +01:00
parent 1c0bdd8c85
commit b81500c0e4
4 changed files with 14 additions and 13 deletions
Show Stats Download Patch File Download Diff File Expand all files Collapse all files

11
src/hash_map.cc
View File

@ -115,11 +115,12 @@ void do_profile(size_t count, StringView type)
} }
auto after_find = Clock::now(); auto after_find = Clock::now();
write_to_debug_buffer(format("{} ({}) -- inserts: {}ms, reads: {}ms, remove: {}ms, find: {}ms ({})", type, count, using namespace std::chrono;
std::chrono::duration_cast<std::chrono::milliseconds>(after_insert - start).count(), write_to_debug_buffer(format("{} ({}) -- inserts: {}us, reads: {}us, remove: {}us, find: {}us ({})", type, count,
std::chrono::duration_cast<std::chrono::milliseconds>(after_read - after_insert).count(), duration_cast<microseconds>(after_insert - start).count(),
std::chrono::duration_cast<std::chrono::milliseconds>(after_remove - after_read).count(), duration_cast<microseconds>(after_read - after_insert).count(),
std::chrono::duration_cast<std::chrono::milliseconds>(after_find - after_remove).count(), duration_cast<microseconds>(after_remove - after_read).count(),
duration_cast<microseconds>(after_find - after_remove).count(),
c)); c));
} }

4
src/hook_manager.cc
View File

@ -128,8 +128,8 @@ void HookManager::run_hook(StringView hook_name,
if (profile) if (profile)
{ {
auto end_time = Clock::now(); auto end_time = Clock::now();
auto full = std::chrono::duration_cast<std::chrono::milliseconds>(end_time - start_time); auto full = std::chrono::duration_cast<std::chrono::microseconds>(end_time - start_time);
write_to_debug_buffer(format("hook '{}({})' took {} ms", hook_name, param, (size_t)full.count())); write_to_debug_buffer(format("hook '{}({})' took {} us", hook_name, param, (size_t)full.count()));
} }
} }

8
src/shell_manager.cc
View File

@ -280,10 +280,10 @@ std::pair<String, int> ShellManager::eval(
if (profile) if (profile)
{ {
auto end_time = Clock::now(); auto end_time = Clock::now();
auto full = duration_cast<milliseconds>(end_time - start_time); auto full = duration_cast<microseconds>(end_time - start_time);
auto spawn = duration_cast<milliseconds>(wait_time - spawn_time); auto spawn = duration_cast<microseconds>(wait_time - spawn_time);
auto wait = duration_cast<milliseconds>(end_time - wait_time); auto wait = duration_cast<microseconds>(end_time - wait_time);
write_to_debug_buffer(format("shell execution took {} ms (spawn: {}, wait: {})", write_to_debug_buffer(format("shell execution took {} us (spawn: {}, wait: {})",
(size_t)full.count(), (size_t)spawn.count(), (size_t)wait.count())); (size_t)full.count(), (size_t)spawn.count(), (size_t)wait.count()));
} }

4
src/window.cc
View File

@ -147,8 +147,8 @@ const DisplayBuffer& Window::update_display_buffer(const Context& context)
if (profile and not (buffer().flags() & Buffer::Flags::Debug)) if (profile and not (buffer().flags() & Buffer::Flags::Debug))
{ {
using namespace std::chrono; using namespace std::chrono;
auto duration = duration_cast<milliseconds>(Clock::now() - start_time); auto duration = duration_cast<microseconds>(Clock::now() - start_time);
write_to_debug_buffer(format("window display update for '{}' took {} ms", write_to_debug_buffer(format("window display update for '{}' took {} us",
buffer().display_name(), (size_t)duration.count())); buffer().display_name(), (size_t)duration.count()));
} }