rofi timings

❯ G_MESSAGES_DEBUG=Timings rofi -show drun
(process:95196): Timings-DEBUG: 03:04:49.471: 0.000001 (0.000000): Started
(process:95196): Timings-DEBUG: 03:04:49.471: 0.000135 (0.000135): ../source/rofi.c:main:902
(process:95196): Timings-DEBUG: 03:04:49.472: 0.000756 (0.000621): ../source/rofi.c:main:953
(process:95196): Timings-DEBUG: 03:04:49.472: 0.000874 (0.000118): ../source/rofi.c:main:960 Setup Locale
(process:95196): Timings-DEBUG: 03:04:49.473: 0.001704 (0.000830): ../source/rofi.c:main:962 Collect MODES
(process:95196): Timings-DEBUG: 03:04:49.473: 0.001736 (0.000032): ../source/rofi.c:main:964 Setup MODES
(process:95196): Timings-DEBUG: 03:04:49.473: 0.001763 (0.000027): ../source/rofi.c:main:968 Setup mainloop
(process:95196): Timings-DEBUG: 03:04:49.473: 0.001920 (0.000157): ../source/rofi.c:main:971 NK Bindings
(process:95196): Timings-DEBUG: 03:04:49.473: 0.002187 (0.000267): ../source/xcb.c:display_setup:1663 Open Display
(process:95196): Timings-DEBUG: 03:04:49.474: 0.003202 (0.001015): ../source/xcb.c:display_setup:1679 Setup XCB
(process:95196): Timings-DEBUG: 03:04:49.481: 0.009681 (0.006479): ../source/rofi.c:main:978 Setup Display
(process:95196): Timings-DEBUG: 03:04:49.481: 0.009732 (0.000051): ../source/rofi.c:main:982 Setup abe
(process:95196): Timings-DEBUG: 03:04:49.486: 0.015120 (0.005388): ../source/rofi.c:main:1042 Load cmd config
(process:95196): Timings-DEBUG: 03:04:49.486: 0.015163 (0.000043): ../source/rofi.c:main:1064 Setup Modes
(process:95196): Timings-DEBUG: 03:04:49.486: 0.015349 (0.000186): ../source/view.c:rofi_view_workers_initialize:2448 Setup Threadpool,start
(process:95196): Timings-DEBUG: 03:04:49.486: 0.015411 (0.000062): ../source/view.c:rofi_view_workers_initialize:2472 Setup Threadpool,done
(process:95196): Timings-DEBUG: 03:04:49.487: 0.015442 (0.000031): ../source/rofi.c:main:1132 Workers initialize
(process:95196): Timings-DEBUG: 03:04:49.505: 0.033584 (0.018142): ../source/rofi.c:main:1134 Icon fetcher initialize
(process:95196): Timings-DEBUG: 03:04:49.505: 0.033643 (0.000059): ../source/rofi.c:main:1142 Pid file created
(process:95196): Timings-DEBUG: 03:04:49.505: 0.033668 (0.000025): ../source/rofi.c:main:1148 Text box setup
(process:95196): Timings-DEBUG: 03:04:49.512: 0.040642 (0.006974): ../source/rofi.c:main:1155 Setup late Display
(process:95196): Timings-DEBUG: 03:04:49.512: 0.040780 (0.000138): ../source/rofi.c:main:1159 Theme setup
(process:95196): Timings-DEBUG: 03:04:49.512: 0.040814 (0.000034): ../source/rofi.c:startup:731 Startup
(process:95196): Timings-DEBUG: 03:04:49.512: 0.040828 (0.000014): ../source/rofi.c:startup:740 Grab keyboard
(process:95196): Timings-DEBUG: 03:04:49.512: 0.040907 (0.000079): ../source/view.c:__create_window:924 xcb create window
(process:95196): Timings-DEBUG: 03:04:49.512: 0.040925 (0.000018): ../source/view.c:__create_window:928 xcb create gc
(process:95196): Timings-DEBUG: 03:04:49.512: 0.041269 (0.000344): ../source/view.c:__create_window:938 create cairo surface
(process:95196): Timings-DEBUG: 03:04:49.512: 0.041434 (0.000165): ../source/view.c:__create_window:947 pango cairo font setup
(process:95196): Timings-DEBUG: 03:04:49.513: 0.041483 (0.000049): ../source/view.c:__create_window:991 configure font
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063831 (0.022348): ../source/view.c:__create_window:999 textbox setup
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063871 (0.000040): ../source/view.c:__create_window:1013 setup window attributes
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063890 (0.000019): ../source/view.c:__create_window:1028 setup window fullscreen
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063905 (0.000015): ../source/view.c:__create_window:1035 setup window name and class
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063929 (0.000024): ../source/view.c:__create_window:1044 setup startup notification
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063950 (0.000021): ../source/view.c:__create_window:1046 done
(process:95196): Timings-DEBUG: 03:04:49.535: 0.063980 (0.000030): ../source/rofi.c:startup:742 Create Window
(process:95196): Timings-DEBUG: 03:04:49.535: 0.064004 (0.000024): ../source/rofi.c:startup:744 Parse ABE
(process:95196): Timings-DEBUG: 03:04:49.535: 0.064026 (0.000022): ../source/rofi.c:startup:747 Config sanity check
(process:95196): Timings-DEBUG: 03:04:49.535: 0.064058 (0.000032): ../source/modes/drun.c:get_apps:1006 Get Desktop apps (start)
(process:95196): Timings-DEBUG: 03:04:49.541: 0.069966 (0.005908): ../source/modes/drun.c:get_apps:1018 Get Desktop apps (user dir)
(process:95196): Timings-DEBUG: 03:04:49.552: 0.080537 (0.010571): ../source/modes/drun.c:get_apps:1041 Get Desktop apps (system dirs)
(process:95196): Timings-DEBUG: 03:04:49.552: 0.080558 (0.000021): ../source/modes/drun.c:get_apps_history:784 Start drun history
(process:95196): Timings-DEBUG: 03:04:49.552: 0.080627 (0.000069): ../source/modes/drun.c:get_apps_history:803 Stop drun history
(process:95196): Timings-DEBUG: 03:04:49.553: 0.081514 (0.000887): ../source/modes/drun.c:get_apps:1048 Sorting done.
(process:95196): Timings-DEBUG: 03:04:49.553: 0.081535 (0.000021): ../source/modes/run.c:get_apps:239 start
(process:95196): Timings-DEBUG: 03:04:49.576: 0.105206 (0.023671): ../source/modes/run.c:get_apps:378 stop
(process:95196): Timings-DEBUG: 03:04:49.579: 0.107567 (0.002361): ../source/view.c:rofi_view_create:2264
(process:95196): Timings-DEBUG: 03:04:49.579: 0.107595 (0.000028): ../source/view.c:rofi_view_create:2289 Startup notification
(process:95196): Timings-DEBUG: 03:04:49.579: 0.107608 (0.000013): ../source/view.c:rofi_view_create:2292 Get active monitor
(process:95196): Timings-DEBUG: 03:04:49.581: 0.109726 (0.002118): ../source/view.c:rofi_view_refilter_real:1296 Filter start
(process:95196): Timings-DEBUG: 03:04:49.581: 0.109749 (0.000023): ../source/view.c:rofi_view_refilter_real:1301 Filter reload rows
(process:95196): Timings-DEBUG: 03:04:49.581: 0.109762 (0.000013): ../source/view.c:rofi_view_refilter_real:1306 Filter tokenize
(process:95196): Timings-DEBUG: 03:04:49.581: 0.109772 (0.000010): ../source/view.c:rofi_view_refilter_real:1385 Filter matching done
(process:95196): Timings-DEBUG: 03:04:49.581: 0.109782 (0.000010): ../source/widgets/listview.c:listview_set_num_elements:566 listview_set_num_elements
(process:95196): Timings-DEBUG: 03:04:49.581: 0.109793 (0.000011): ../source/widgets/listview.c:listview_set_num_elements:572 Set selected
(process:95196): Timings-DEBUG: 03:04:49.581: 0.110252 (0.000459): ../source/widgets/listview.c:listview_set_num_elements:574 recomputeelements
(process:95196): Timings-DEBUG: 03:04:49.581: 0.110272 (0.000020): ../source/widgets/listview.c:listview_set_num_elements:576 queue redraw
(process:95196): Timings-DEBUG: 03:04:49.582: 0.110998 (0.000726): ../source/view.c:rofi_view_refilter_real:1398 Update filter lines
(process:95196): Timings-DEBUG: 03:04:49.582: 0.111132 (0.000134): ../source/view.c:rofi_view_refilter_real:1416 Filter resize window based on window
(process:95196): Timings-DEBUG: 03:04:49.582: 0.111163 (0.000031): ../source/view.c:rofi_view_refilter_real:1418 Filter done
(process:95196): Timings-DEBUG: 03:04:49.582: 0.111180 (0.000017): ../source/view.c:rofi_view_update:1240
(process:95196): Timings-DEBUG: 03:04:49.582: 0.111205 (0.000025): ../source/view.c:rofi_view_update:1258 Background
(process:95196): Timings-DEBUG: 03:04:49.604: 0.133026 (0.021821): ../source/view.c:rofi_view_update:1272 widgets
(process:95196): Timings-DEBUG: 03:04:49.634: 0.162992 (0.029966): ../source/view.c:rofi_view_repaint:304 Update start
(process:95196): Timings-DEBUG: 03:04:49.634: 0.163038 (0.000046): ../source/view.c:rofi_view_update:1240
(process:95196): Timings-DEBUG: 03:04:49.634: 0.163067 (0.000029): ../source/view.c:rofi_view_update:1258 Background
(process:95196): Timings-DEBUG: 03:04:49.636: 0.165296 (0.002229): ../source/view.c:rofi_view_update:1272 widgets
(process:95196): Timings-DEBUG: 03:04:49.636: 0.165332 (0.000036): ../source/view.c:rofi_view_repaint:307 Expose
(process:95196): Timings-DEBUG: 03:04:49.636: 0.165366 (0.000034): ../source/view.c:rofi_view_repaint:312 flush
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165507 (0.000141): ../source/view.c:rofi_view_refilter_real:1296 Filter start
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165536 (0.000029): ../source/view.c:rofi_view_refilter_real:1301 Filter reload rows
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165555 (0.000019): ../source/view.c:rofi_view_refilter_real:1306 Filter tokenize
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165573 (0.000018): ../source/view.c:rofi_view_refilter_real:1385 Filter matching done
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165592 (0.000019): ../source/widgets/listview.c:listview_set_num_elements:566 listview_set_num_elements
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165611 (0.000019): ../source/widgets/listview.c:listview_set_num_elements:572 Set selected
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165629 (0.000018): ../source/widgets/listview.c:listview_set_num_elements:574 recomputeelements
(process:95196): Timings-DEBUG: 03:04:49.637: 0.165646 (0.000017): ../source/widgets/listview.c:listview_set_num_elements:576 queue redraw
(process:95196): Timings-DEBUG: 03:04:49.637: 0.166249 (0.000603): ../source/view.c:rofi_view_refilter_real:1398 Update filter lines
(process:95196): Timings-DEBUG: 03:04:49.637: 0.166388 (0.000139): ../source/view.c:rofi_view_refilter_real:1416 Filter resize window based on window
(process:95196): Timings-DEBUG: 03:04:49.637: 0.166418 (0.000030): ../source/view.c:rofi_view_refilter_real:1418 Filter done
(process:95196): Timings-DEBUG: 03:04:49.638: 0.166438 (0.000020): ../source/view.c:rofi_view_update:1240
(process:95196): Timings-DEBUG: 03:04:49.638: 0.166459 (0.000021): ../source/view.c:rofi_view_update:1258 Background
(process:95196): Timings-DEBUG: 03:04:49.640: 0.168732 (0.002273): ../source/view.c:rofi_view_update:1272 widgets
(process:95196): Timings-DEBUG: 03:04:49.640: 0.168853 (0.000121): ../source/view.c:rofi_view_repaint:304 Update start
(process:95196): Timings-DEBUG: 03:04:49.640: 0.168882 (0.000029): ../source/view.c:rofi_view_repaint:307 Expose
(process:95196): Timings-DEBUG: 03:04:49.640: 0.168911 (0.000029): ../source/view.c:rofi_view_repaint:312 flush
(process:95196): Timings-DEBUG: 03:04:51.981: 2.509829 (2.340918): ../source/view.c:rofi_view_repaint:304 Update start
(process:95196): Timings-DEBUG: 03:04:51.981: 2.509934 (0.000105): ../source/view.c:rofi_view_update:1240
(process:95196): Timings-DEBUG: 03:04:51.981: 2.509994 (0.000060): ../source/view.c:rofi_view_update:1258 Background
(process:95196): Timings-DEBUG: 03:04:51.986: 2.515151 (0.005157): ../source/view.c:rofi_view_update:1272 widgets
(process:95196): Timings-DEBUG: 03:04:51.986: 2.515257 (0.000106): ../source/view.c:rofi_view_repaint:307 Expose
(process:95196): Timings-DEBUG: 03:04:51.986: 2.515355 (0.000098): ../source/view.c:rofi_view_repaint:312 flush
(process:95196): Timings-DEBUG: 03:04:53.181: 3.710360 (1.195005): ../source/view.c:rofi_view_repaint:304 Update start
(process:95196): Timings-DEBUG: 03:04:53.182: 3.710456 (0.000096): ../source/view.c:rofi_view_update:1240
(process:95196): Timings-DEBUG: 03:04:53.182: 3.710516 (0.000060): ../source/view.c:rofi_view_update:1258 Background
(process:95196): Timings-DEBUG: 03:04:53.187: 3.715501 (0.004985): ../source/view.c:rofi_view_update:1272 widgets
(process:95196): Timings-DEBUG: 03:04:53.187: 3.715600 (0.000099): ../source/view.c:rofi_view_repaint:307 Expose
(process:95196): Timings-DEBUG: 03:04:53.187: 3.715699 (0.000099): ../source/view.c:rofi_view_repaint:312 flush
(process:95196): Timings-DEBUG: 03:04:53.268: 3.797130 (0.000000): Stopped