{"id":1360,"date":"2024-02-03T15:11:37","date_gmt":"2024-02-03T15:11:37","guid":{"rendered":"https:\/\/mvermeulen.org\/perf\/?page_id=1360"},"modified":"2024-02-03T20:21:33","modified_gmt":"2024-02-03T20:21:33","slug":"sudokut","status":"publish","type":"page","link":"https:\/\/mvermeulen.org\/perf\/workloads\/phoronix\/sudokut\/","title":{"rendered":"sudokut"},"content":{"rendered":"\n<p>A sudoku puzzle solver in TCL. This is a quick running, single-threaded program that runs in less than a minute.<\/p>\n\n\n\n<figure class=\"wp-block-image size-full\"><img loading=\"lazy\" decoding=\"async\" width=\"1280\" height=\"960\" src=\"https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/systemtime-19.png\" alt=\"\" class=\"wp-image-1391\" srcset=\"https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/systemtime-19.png 1280w, https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/systemtime-19-1024x768.png 1024w, https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/systemtime-19-768x576.png 768w\" sizes=\"auto, (max-width: 1280px) 100vw, 1280px\" \/><\/figure>\n\n\n\n<p>Topdown profile has few data popints but overall a high retirement rate with some frontend stalls.<\/p>\n\n\n\n<figure class=\"wp-block-image size-full\"><img loading=\"lazy\" decoding=\"async\" width=\"1280\" height=\"960\" src=\"https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/amdtopdown-19.png\" alt=\"\" class=\"wp-image-1393\" srcset=\"https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/amdtopdown-19.png 1280w, https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/amdtopdown-19-1024x768.png 1024w, https:\/\/mvermeulen.org\/perf\/wp-content\/uploads\/sites\/7\/2024\/02\/amdtopdown-19-768x576.png 768w\" sizes=\"auto, (max-width: 1280px) 100vw, 1280px\" \/><\/figure>\n\n\n\n<p>AMD metrics show very little cache activity beyond L1, little floating point and running on one core.<\/p>\n\n\n\n<pre class=\"wp-block-code\"><code>elapsed              38.166\non_cpu               0.040          # 0.63 \/ 16 cores\nutime                22.743\nstime                1.407\nnvcsw                2922           # 84.65%\nnivcsw               530            # 15.35%\ninblock              152            # 3.98\/sec\nonblock              14176          # 371.43\/sec\ncpu-clock            24150225570    # 24.150 seconds\ntask-clock           24152726708    # 24.153 seconds\npage faults          401138         # 16608.394\/sec\ncontext switches     3186           # 131.911\/sec\ncpu migrations       261            # 10.806\/sec\nmajor page faults    2              # 0.083\/sec\nminor page faults    401136         # 16608.311\/sec\nalignment faults     0              # 0.000\/sec\nemulation faults     0              # 0.000\/sec\nbranches             75383328542    # 218.131 branches per 1000 inst\nbranch misses        389478134      # 0.52% branch miss\nconditional          51967656139    # 150.375 conditional branches per 1000 inst\nindirect             3230390546     # 9.348 indirect branches per 1000 inst\ncpu-cycles           101190461945   # 0.16 GHz\ninstructions         339002025886   # 3.35 IPC high\nslots                209160030252   #\nretiring             110152621743   # 52.7% (52.7%)\n-- ucode             127985441      #     0.1%\n-- fastpath          110024636302   #    52.6%\nfrontend             77355240137    # 37.0% (37.0%)\n-- latency           36416197752    #    17.4%\n-- bandwidth         40939042385    #    19.6%\nbackend              13683290991    #  6.5% ( 6.5%) low\n-- cpu               2035995902     #     1.0%\n-- memory            11647295089    #     5.6%\nspeculation          7890471651     #  3.8% ( 3.8%)\n-- branch mispredict 7494793595     #     3.6%\n-- pipeline restart  395678056      #     0.2%\nsmt-contention       78120930       #  0.0% ( 0.0%)\ncpu-cycles           101260142102   # 0.16 GHz\ninstructions         338687934281   # 3.34 IPC high\ninstructions         115602851732   # 12.126 l2 access per 1000 inst\nl2 hit from l1       1343316541     # 4.72% l2 miss\nl2 miss from l1      42538855       #\nl2 hit from l2 pf    34831655       #\nl3 hit from l2 pf    16515857       #\nl3 miss from l2 pf   7142269        #\ninstructions         113219580291   # 3.214 float per 1000 inst\nfloat 512            419            # 0.000 AVX-512 per 1000 inst\nfloat 256            968            # 0.000 AVX-256 per 1000 inst\nfloat 128            363932696      # 3.214 AVX-128 per 1000 inst\nfloat MMX            0              # 0.000 MMX per 1000 inst\nfloat scalar         0              # 0.000 scalar per 1000 inst\ninstructions         2661793        #\nopcache              996496         # 374.370 opcache per 1000 inst\nopcache miss         539348         # 54.1% opcache miss rate\nl1 dTLB miss         5126           # 1.926 L1 dTLB per 1000 inst\nl2 dTLB miss         1046           # 0.393 L2 dTLB per 1000 inst\ninstructions         2686411        #\nicache               1319260        # 491.086 icache per 1000 inst\nicache miss          112450         #  8.5% icache miss rate\nl1 iTLB miss         12             # 0.004 L1 iTLB per 1000 inst\nl2 iTLB miss         0              # 0.000 L2 iTLB per 1000 inst\ntlb flush            19             # 0.007 TLB flush per 1000 inst\n<\/code><\/pre>\n\n\n\n<p>Intel metrics show more memory-bound stalls but still memory latency is mostly L1.<\/p>\n\n\n\n<pre class=\"wp-block-code\"><code>elapsed              41.931\non_cpu               0.042          # 0.67 \/ 16 cores\nutime                27.211\nstime                0.875\nnvcsw                2756           # 84.46%\nnivcsw               507            # 15.54%\ninblock              3600           # 85.86\/sec\nonblock              2568           # 61.24\/sec\ncpu-clock            28048645629    # 28.049 seconds\ntask-clock           28052864188    # 28.053 seconds\npage faults          395778         # 14108.292\/sec\ncontext switches     3005           # 107.119\/sec\ncpu migrations       284            # 10.124\/sec\nmajor page faults    25             # 0.891\/sec\nminor page faults    395753         # 14107.401\/sec\nalignment faults     0              # 0.000\/sec\nemulation faults     0              # 0.000\/sec\nbranches             75156469514    # 217.736 branches per 1000 inst\nbranch misses        299780840      # 0.40% branch miss\nconditional          75156490922    # 217.736 conditional branches per 1000 inst\nindirect             3261592661     # 9.449 indirect branches per 1000 inst\nslots                628764512336   #\nretiring             316419294072   # 50.3% (50.3%)\n-- ucode             15015144716    #     2.4%\n-- fastpath          301404149356   #    47.9%\nfrontend             247522760902   # 39.4% (39.4%)\n-- latency           85144205322    #    13.5%\n-- bandwidth         162378555580   #    25.8%\nbackend              18617959489    #  3.0% ( 3.0%) low\n-- cpu               12751496330    #     2.0%\n-- memory            5866463159     #     0.9%\nspeculation          46502216731    #  7.4% ( 7.4%)\n-- branch mispredict 40575038937    #     6.5%\n-- pipeline restart  5927177794     #     0.9%\nsmt-contention       0              #  0.0% ( 0.0%)\ncpu-cycles           105011813182   # 0.15 GHz\ninstructions         345057177086   # 3.29 IPC high\nl2 access            3870769220     # 11.228 l2 access per 1000 inst\nl2 miss              235887627      # 6.09% l2 miss\ncpu-cycles           104834415246   #  7.6% memory latency\nload stalls          7767265463     #  0.6% l1 bound\nl1 miss              7116364268     #  6.2% l2 bound\nl2 miss              570919695      #  0.3% l3 bound\nl3 miss              265312061      #  0.3% dram bound\nstore_stalls         193339180      #  0.2% store bound\n<\/code><\/pre>\n\n\n\n<p>Process summary shows test overhead as almost as high as the workload.<\/p>\n\n\n\n<pre class=\"wp-block-code\"><code>651 processes\n\t300 tclsh                   20.13     0.01\n\t 68 clinfo                  16.88     5.65\n\t 38 vulkaninfo               0.75     1.52\n\t  6 glxinfo:gdrv0            0.09     0.07\n\t  6 glxinfo:gl0              0.09     0.07\n\t  4 vulkani:disk$0           0.08     0.16\n\t  6 php                      0.06     0.07\n\t  6 clang                    0.06     0.06\n\t  2 glxinfo                  0.05     0.03\n\t  2 glxinfo:cs0              0.05     0.03\n\t  2 glxinfo:disk$0           0.05     0.03\n\t  2 glxinfo:sh0              0.05     0.03\n\t  2 glxinfo:shlo0            0.05     0.03\n\t  2 llvmpipe-0               0.04     0.08\n\t  2 llvmpipe-1               0.04     0.08\n\t  2 llvmpipe-10              0.04     0.08\n\t  2 llvmpipe-11              0.04     0.08\n\t  2 llvmpipe-12              0.04     0.08\n\t  2 llvmpipe-13              0.04     0.08\n\t  2 llvmpipe-14              0.04     0.08\n\t  2 llvmpipe-15              0.04     0.08\n\t  2 llvmpipe-2               0.04     0.08\n\t  2 llvmpipe-3               0.04     0.08\n\t  2 llvmpipe-4               0.04     0.08\n\t  2 llvmpipe-5               0.04     0.08\n\t  2 llvmpipe-6               0.04     0.08\n\t  2 llvmpipe-7               0.04     0.08\n\t  2 llvmpipe-8               0.04     0.08\n\t  2 llvmpipe-9               0.04     0.08\n\t  3 rocminfo                 0.03     0.00\n\t  3 sudokut-100-run          0.01     0.01\n\t  1 lspci                    0.00     0.02\n\t  1 ps                       0.00     0.01\n\t 81 sh                       0.00     0.00\n\t 13 gsettings                0.00     0.00\n\t 12 gcc                      0.00     0.00\n\t  8 stat                     0.00     0.00\n\t  8 systemd-detect-          0.00     0.00\n\t  6 llvm-link                0.00     0.00\n\t  5 phoronix-test-s          0.00     0.00\n\t  3 sudokut                  0.00     0.00\n\t  2 gmain                    0.00     0.00\n\t  2 lscpu                    0.00     0.00\n\t  2 uname                    0.00     0.00\n\t  2 which                    0.00     0.00\n\t  2 xset                     0.00     0.00\n\t  1 cc                       0.00     0.00\n\t  1 date                     0.00     0.00\n\t  1 dconf worker             0.00     0.00\n\t  1 dirname                  0.00     0.00\n\t  1 dmesg                    0.00     0.00\n\t  1 dmidecode                0.00     0.00\n\t  1 grep                     0.00     0.00\n\t  1 ifconfig                 0.00     0.00\n\t  1 ip                       0.00     0.00\n\t  1 lsmod                    0.00     0.00\n\t  1 mktemp                   0.00     0.00\n\t  1 qdbus                    0.00     0.00\n\t  1 readlink                 0.00     0.00\n\t  1 realpath                 0.00     0.00\n\t  1 sed                      0.00     0.00\n\t  1 sort                     0.00     0.00\n\t  1 stty                     0.00     0.00\n\t  1 systemctl                0.00     0.00\n\t  1 template.sh              0.00     0.00\n\t  1 wc                       0.00     0.00\n\t  1 xrandr                   0.00     0.00\n0 processes running\n47 maximum processes\n<\/code><\/pre>\n\n\n\n<p>Process summary is as follows with the &#8220;sudokut-100-run having not quite 100 children but  each runs in 0.05 seconds or so&#8230;<\/p>\n\n\n\n<pre class=\"wp-block-code\"><code>      330575) sudokut          cpu=11 start=5.50  finish=13.08\n        330576) sudokut-100-run  cpu=11 start=5.50  finish=13.08\n          330577) tclsh            cpu=12 start=5.51  finish=5.59 \n          330578) tclsh            cpu=2 start=5.59  finish=5.71 \n          330579) tclsh            cpu=12 start=5.71  finish=5.79 \n          330580) tclsh            cpu=10 start=5.79  finish=5.98 \n          330581) tclsh            cpu=11 start=5.99  finish=6.15 \n          330582) tclsh            cpu=10 start=6.15  finish=6.44 \n          330583) tclsh            cpu=4 start=6.44  finish=6.56 \n          330584) tclsh            cpu=10 start=6.56  finish=6.62 \n          330585) tclsh            cpu=11 start=6.62  finish=6.71 \n          330586) tclsh            cpu=2 start=6.71  finish=6.77 \n          330587) tclsh            cpu=11 start=6.77  finish=6.84 \n          330588) tclsh            cpu=2 start=6.84  finish=6.94 \n          330589) tclsh            cpu=11 start=6.94  finish=7.06 \n          330590) tclsh            cpu=2 start=7.06  finish=7.12 \n          330591) tclsh            cpu=11 start=7.12  finish=7.22 \n          330592) tclsh            cpu=2 start=7.22  finish=7.31 \n          330593) tclsh            cpu=3 start=7.31  finish=7.34 \n          330594) tclsh            cpu=12 start=7.34  finish=7.42 \n          330595) tclsh            cpu=10 start=7.42  finish=7.48 \n          330596) tclsh            cpu=11 start=7.48  finish=7.53 \n          330597) tclsh            cpu=10 start=7.53  finish=7.64 \n          330598) tclsh            cpu=11 start=7.64  finish=7.68 \n          330599) tclsh            cpu=10 start=7.68  finish=7.74 \n          330600) tclsh            cpu=11 start=7.75  finish=7.81 \n          330601) tclsh            cpu=10 start=7.81  finish=7.91 \n          330602) tclsh            cpu=11 start=7.91  finish=8.00 \n          330603) tclsh            cpu=10 start=8.00  finish=8.12 \n          330604) tclsh            cpu=11 start=8.12  finish=8.17 \n          330605) tclsh            cpu=10 start=8.17  finish=8.21 \n          330606) tclsh            cpu=11 start=8.21  finish=8.29 \n          330607) tclsh            cpu=12 start=8.29  finish=8.37 \n          330608) tclsh            cpu=10 start=8.37  finish=8.41 \n          330609) tclsh            cpu=11 start=8.41  finish=8.46 \n          330610) tclsh            cpu=10 start=8.46  finish=8.52 \n          330611) tclsh            cpu=11 start=8.53  finish=8.57 \n          330612) tclsh            cpu=12 start=8.57  finish=8.62 \n          330613) tclsh            cpu=10 start=8.62  finish=8.71 \n          330614) tclsh            cpu=11 start=8.72  finish=8.81 \n          330615) tclsh            cpu=10 start=8.81  finish=8.86 \n          330616) tclsh            cpu=11 start=8.86  finish=8.91 \n          330617) tclsh            cpu=10 start=8.91  finish=8.96 \n          330618) tclsh            cpu=11 start=8.97  finish=9.07 \n          330619) tclsh            cpu=10 start=9.07  finish=9.16 \n          330620) tclsh            cpu=11 start=9.16  finish=9.20 \n          330621) tclsh            cpu=10 start=9.21  finish=9.27 \n          330622) tclsh            cpu=11 start=9.27  finish=9.32 \n          330623) tclsh            cpu=10 start=9.32  finish=9.37 \n          330624) tclsh            cpu=11 start=9.37  finish=9.43 \n          330625) tclsh            cpu=10 start=9.43  finish=9.48 \n          330626) tclsh            cpu=11 start=9.48  finish=9.65 \n          330627) tclsh            cpu=10 start=9.65  finish=9.71 \n          330628) tclsh            cpu=11 start=9.71  finish=9.76 \n          330629) tclsh            cpu=10 start=9.76  finish=9.82 \n          330630) tclsh            cpu=11 start=9.82  finish=9.87 \n          330631) tclsh            cpu=10 start=9.87  finish=9.94 \n          330632) tclsh            cpu=11 start=9.94  finish=10.04\n          330633) tclsh            cpu=10 start=10.04 finish=10.10\n          330634) tclsh            cpu=11 start=10.10 finish=10.19\n          330635) tclsh            cpu=10 start=10.19 finish=10.26\n          330636) tclsh            cpu=11 start=10.27 finish=10.33\n          330637) tclsh            cpu=10 start=10.33 finish=10.39\n          330638) tclsh            cpu=11 start=10.39 finish=10.46\n          330639) tclsh            cpu=10 start=10.46 finish=10.51\n          330640) tclsh            cpu=11 start=10.51 finish=10.59\n          330642) tclsh            cpu=10 start=10.59 finish=10.64\n          330643) tclsh            cpu=11 start=10.64 finish=10.73\n          330644) tclsh            cpu=10 start=10.73 finish=10.79\n          330645) tclsh            cpu=11 start=10.79 finish=10.89\n          330646) tclsh            cpu=10 start=10.90 finish=10.96\n          330647) tclsh            cpu=11 start=10.96 finish=11.10\n          330648) tclsh            cpu=10 start=11.10 finish=11.16\n          330649) tclsh            cpu=11 start=11.16 finish=11.21\n          330650) tclsh            cpu=10 start=11.21 finish=11.36\n          330651) tclsh            cpu=11 start=11.36 finish=11.41\n          330652) tclsh            cpu=10 start=11.42 finish=11.50\n          330653) tclsh            cpu=11 start=11.50 finish=11.57\n          330654) tclsh            cpu=10 start=11.57 finish=11.62\n          330655) tclsh            cpu=11 start=11.63 finish=11.70\n          330656) tclsh            cpu=10 start=11.70 finish=11.74\n          330657) tclsh            cpu=11 start=11.74 finish=11.82\n          330658) tclsh            cpu=10 start=11.82 finish=11.91\n          330659) tclsh            cpu=11 start=11.91 finish=11.95\n          330660) tclsh            cpu=2 start=11.95 finish=11.99\n          330661) tclsh            cpu=11 start=11.99 finish=12.04\n          330662) tclsh            cpu=2 start=12.04 finish=12.08\n          330663) tclsh            cpu=11 start=12.08 finish=12.15\n          330664) tclsh            cpu=2 start=12.16 finish=12.22\n          330665) tclsh            cpu=11 start=12.22 finish=12.26\n          330666) tclsh            cpu=10 start=12.26 finish=12.31\n          330667) tclsh            cpu=3 start=12.31 finish=12.40\n          330668) tclsh            cpu=10 start=12.40 finish=12.44\n          330669) tclsh            cpu=12 start=12.44 finish=12.59\n          330670) tclsh            cpu=13 start=12.59 finish=12.64\n          330671) tclsh            cpu=12 start=12.64 finish=12.71\n          330672) tclsh            cpu=13 start=12.72 finish=12.76\n          330673) tclsh            cpu=12 start=12.76 finish=12.80\n          330674) tclsh            cpu=13 start=12.80 finish=12.86\n          330675) tclsh            cpu=12 start=12.86 finish=12.91\n          330676) tclsh            cpu=13 start=12.91 finish=12.96\n          330677) tclsh            cpu=4 start=12.96 finish=13.08\n<\/code><\/pre>\n","protected":false},"excerpt":{"rendered":"<p>A sudoku puzzle solver in TCL. This is a quick running, single-threaded program that runs in less than a minute. Topdown profile has few data popints but overall a high retirement rate with some frontend stalls. AMD metrics show very <span class=\"excerpt-dots\">&hellip;<\/span> <a class=\"more-link\" href=\"https:\/\/mvermeulen.org\/perf\/workloads\/phoronix\/sudokut\/\"><span class=\"more-msg\">Continue reading &rarr;<\/span><\/a><\/p>\n","protected":false},"author":1,"featured_media":0,"parent":58,"menu_order":0,"comment_status":"closed","ping_status":"closed","template":"","meta":{"footnotes":""},"class_list":["post-1360","page","type-page","status-publish","hentry"],"_links":{"self":[{"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/pages\/1360","targetHints":{"allow":["GET"]}}],"collection":[{"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/pages"}],"about":[{"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/types\/page"}],"author":[{"embeddable":true,"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/users\/1"}],"replies":[{"embeddable":true,"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/comments?post=1360"}],"version-history":[{"count":2,"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/pages\/1360\/revisions"}],"predecessor-version":[{"id":1394,"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/pages\/1360\/revisions\/1394"}],"up":[{"embeddable":true,"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/pages\/58"}],"wp:attachment":[{"href":"https:\/\/mvermeulen.org\/perf\/wp-json\/wp\/v2\/media?parent=1360"}],"curies":[{"name":"wp","href":"https:\/\/api.w.org\/{rel}","templated":true}]}}