Created

Embed URL

HTTPS clone URL

SSH clone URL

You can clone with HTTPS or SSH.

Download Gist

Rubinius Profiler Graph

View gist:3890814
1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 116 117 118 119 120 121 122 123 124 125 126 127 128 129 130 131 132 133 134 135 136 137 138 139 140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158 159 160 161 162 163 164 165 166 167 168 169 170 171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186 187 188 189 190 191 192 193 194 195 196 197 198 199 200 201 202 203 204 205 206 207 208 209 210 211 212
Jon-Staceys-iMac:Desktop jon$ time rubinius/bin/rbx -X19 -Xprofile -Xprofiler.graph ./run.rb
500000
===== Thread 1 =====
Total running time: 16.516832237000003s
index % time self children called name
----------------------------------------------------------
[1] 99.8 0.00 16.48 1 Rubinius::Loader#script [1]
0.00 16.48 1 Rubinius::CodeLoader.load_script [2]
-------------------------------------------------------
0.00 16.48 1 Rubinius::Loader#script [1]
[2] 99.8 0.00 16.48 1 Rubinius::CodeLoader.load_script [2]
0.00 16.48 1 Rubinius::CodeLoader#load_script [3]
-------------------------------------------------------
0.00 16.48 1 Rubinius::CodeLoader.load_script [2]
[3] 99.8 0.00 16.48 1 Rubinius::CodeLoader#load_script [3]
0.00 16.48 1 Rubinius.run_script [4]
-------------------------------------------------------
0.00 16.48 1 Rubinius::CodeLoader#load_script [3]
[4] 99.8 0.00 16.48 1 Rubinius.run_script [4]
0.00 16.48 1 script:/Users/jon/Desktop/run.rb [5]
-------------------------------------------------------
0.00 16.48 1 Rubinius.run_script [4]
[5] 99.8 0.00 16.48 1 script:/Users/jon/Desktop/run.rb [5]
0.42 16.05 1 IO#readlines [6]
-------------------------------------------------------
0.00 16.48 1 script:/Users/jon/Desktop/run.rb [5]
[6] 99.8 0.42 16.05 1 IO#readlines [6]
0.42 15.31 500001 IO#gets [7]
0.21 0.06 500000 Array#<< [28]
0.06 0.00 1 GC.collect_mature [38]
-------------------------------------------------------
0.42 16.05 1 IO#readlines [6]
[7] 95.2 0.42 15.31 500001 IO#gets [7]
1.41 13.90 500001 IO#each [8]
-------------------------------------------------------
0.42 15.31 500001 IO#gets [7]
[8] 92.7 1.41 13.90 500001 IO#each [8]
0.65 11.06 500001 IO::EachReader#each [9]
0.06 1.39 500001 Class#allocate [18]
0.44 0.05 500001 IO#ensure_open_and_readable [22]
0.16 0.00 500001 IO::EachReader#initialize [29]
0.04 0.00 500001 IO::InternalBuffer#exhausted? [43]
0.03 0.00 500001 Module#===
0.02 0.00 500001 Proc.__from_block__
-------------------------------------------------------
1.41 13.90 500001 IO#each [8]
[9] 70.9 0.65 11.06 500001 IO::EachReader#each [9]
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
0.05 0.00 500001 String#empty? [42]
0.02 0.00 500001 Proc.__from_block__
-------------------------------------------------------
0.65 11.06 500001 IO::EachReader#each [9]
[10] 66.6 1.80 9.19 500001 IO::EachReader#read_to_separator [10]
1.34 2.61 507814 String#<< [11]
0.41 2.50 507814 IO::InternalBuffer#fill_from [12]
0.37 1.46 507814 IO::InternalBuffer#shift [15]
0.25 0.03 507813 IO::InternalBuffer#find [26]
0.08 0.00 500000 IO#increment_lineno [35]
0.08 0.00 500000 IO::gets<496> {} [36]
0.03 0.00 507814 IO::InternalBuffer#exhausted?
0.03 0.00 500000 Kernel#taint
-------------------------------------------------------
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
[11] 23.9 1.34 2.61 507814 String#<< [11]
0.38 1.73 507814 String#modify! [14]
0.28 0.00 1015628 String#encoding [27]
0.10 0.00 507814 String#append [31]
0.08 0.00 1015628 Kernel#kind_of? [37]
0.02 0.00 507814 Rubinius::Type.infect
0.02 0.00 507814 Kernel#==
-------------------------------------------------------
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
[12] 17.6 0.41 2.50 507814 IO::InternalBuffer#fill_from [12]
1.57 0.93 507814 Rubinius.synchronize [13]
-------------------------------------------------------
0.41 2.50 507814 IO::InternalBuffer#fill_from [12]
[13] 15.1 1.57 0.93 507814 Rubinius.synchronize [13]
0.59 0.17 507814 IO::InternalBuffer::fill_from<64> {} [20]
0.08 0.00 507814 Rubinius.lock [33]
0.08 0.00 507814 Rubinius.unlock [34]
-------------------------------------------------------
1.34 2.61 507814 String#<< [11]
[14] 12.8 0.38 1.73 507814 String#modify! [14]
0.85 0.87 500001 Rubinius::ByteArray#dup [16]
-------------------------------------------------------
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
[15] 11.1 0.37 1.46 507814 IO::InternalBuffer#shift [15]
0.61 0.85 507814 Rubinius.synchronize [17]
-------------------------------------------------------
0.38 1.73 507814 String#modify! [14]
[16] 10.5 0.85 0.87 500001 Rubinius::ByteArray#dup [16]
0.39 0.08 500001 Rubinius::ByteArray.new [23]
0.33 0.04 500001 Kernel#initialize_copy [24]
0.02 0.00 500001 Kernel#__class__
0.02 0.00 500001 Rubinius::ByteArray#size
-------------------------------------------------------
0.37 1.46 507814 IO::InternalBuffer#shift [15]
[17] 8.8 0.61 0.85 507814 Rubinius.synchronize [17]
0.57 0.15 507814 IO::InternalBuffer::shift<153> {} [21]
0.08 0.00 507814 Rubinius.lock [32]
0.05 0.00 507814 Rubinius.unlock [41]
-------------------------------------------------------
1.41 13.90 500001 IO#each [8]
[18] 8.8 0.06 1.39 500001 Class#allocate [18]
1.09 0.00 202 GC.collect_young [19]
0.30 0.00 6 GC.collect_mature [25]
-------------------------------------------------------
0.06 1.39 500001 Class#allocate [18]
[19] 6.6 1.09 0.00 202 GC.collect_young [19]
-------------------------------------------------------
1.57 0.93 507814 Rubinius.synchronize [13]
[20] 4.6 0.59 0.17 507814 IO::InternalBuffer::fill_from<64> {} [20]
0.06 0.00 507814 IO::InternalBuffer#empty_to [39]
0.04 0.00 8791 IO::InternalBuffer#fill [45]
0.04 0.00 507814 IO::InternalBuffer#empty?
0.04 0.00 507814 IO::InternalBuffer#size
0.00 0.00 8791 IO::InternalBuffer#reset!
-------------------------------------------------------
0.61 0.85 507814 Rubinius.synchronize [17]
[21] 4.3 0.57 0.15 507814 IO::InternalBuffer::shift<153> {} [21]
0.12 0.00 507814 String.from_bytearray [30]
0.03 0.00 507814 IO::InternalBuffer#size
-------------------------------------------------------
1.41 13.90 500001 IO#each [8]
[22] 2.9 0.44 0.05 500001 IO#ensure_open_and_readable [22]
0.03 0.00 500001 IO#ensure_open
0.02 0.00 500001 Fixnum#&
-------------------------------------------------------
0.85 0.87 500001 Rubinius::ByteArray#dup [16]
[23] 2.8 0.39 0.08 500001 Rubinius::ByteArray.new [23]
0.04 0.00 500001 Rubinius::ByteArray.allocate_sized [44]
0.04 0.00 500001 BasicObject#initialize
-------------------------------------------------------
0.85 0.87 500001 Rubinius::ByteArray#dup [16]
[24] 2.2 0.33 0.04 500001 Kernel#initialize_copy [24]
0.02 0.00 500001 Kernel#instance_of?
0.02 0.00 500001 Kernel#__class__
-------------------------------------------------------
0.06 1.39 500001 Class#allocate [18]
[25] 1.8 0.30 0.00 6 GC.collect_mature [25]
-------------------------------------------------------
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
[26] 1.7 0.25 0.03 507813 IO::InternalBuffer#find [26]
0.03 0.00 507813 Rubinius::ByteArray#locate
-------------------------------------------------------
1.34 2.61 507814 String#<< [11]
[27] 1.7 0.28 0.00 1015628 String#encoding [27]
-------------------------------------------------------
0.42 16.05 1 IO#readlines [6]
[28] 1.6 0.21 0.06 500000 Array#<< [28]
0.06 0.00 500000 Array#set_index [40]
-------------------------------------------------------
1.41 13.90 500001 IO#each [8]
[29] 1.0 0.16 0.00 500001 IO::EachReader#initialize [29]
-------------------------------------------------------
0.57 0.15 507814 IO::InternalBuffer::shift<153> {} [21]
[30] 0.7 0.12 0.00 507814 String.from_bytearray [30]
-------------------------------------------------------
1.34 2.61 507814 String#<< [11]
[31] 0.6 0.10 0.00 507814 String#append [31]
-------------------------------------------------------
0.61 0.85 507814 Rubinius.synchronize [17]
[32] 0.5 0.08 0.00 507814 Rubinius.lock [32]
-------------------------------------------------------
1.57 0.93 507814 Rubinius.synchronize [13]
[33] 0.5 0.08 0.00 507814 Rubinius.lock [33]
-------------------------------------------------------
1.57 0.93 507814 Rubinius.synchronize [13]
[34] 0.5 0.08 0.00 507814 Rubinius.unlock [34]
-------------------------------------------------------
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
[35] 0.5 0.08 0.00 500000 IO#increment_lineno [35]
-------------------------------------------------------
1.80 9.19 500001 IO::EachReader#read_to_separator [10]
[36] 0.5 0.08 0.00 500000 IO::gets<496> {} [36]
-------------------------------------------------------
1.34 2.61 507814 String#<< [11]
[37] 0.5 0.08 0.00 1015628 Kernel#kind_of? [37]
-------------------------------------------------------
0.42 16.05 1 IO#readlines [6]
[38] 0.4 0.06 0.00 1 GC.collect_mature [38]
-------------------------------------------------------
0.59 0.17 507814 IO::InternalBuffer::fill_from<64> {} [20]
[39] 0.3 0.06 0.00 507814 IO::InternalBuffer#empty_to [39]
-------------------------------------------------------
0.21 0.06 500000 Array#<< [28]
[40] 0.3 0.06 0.00 500000 Array#set_index [40]
-------------------------------------------------------
0.61 0.85 507814 Rubinius.synchronize [17]
[41] 0.3 0.05 0.00 507814 Rubinius.unlock [41]
-------------------------------------------------------
0.65 11.06 500001 IO::EachReader#each [9]
[42] 0.3 0.05 0.00 500001 String#empty? [42]
-------------------------------------------------------
1.41 13.90 500001 IO#each [8]
[43] 0.3 0.04 0.00 500001 IO::InternalBuffer#exhausted? [43]
-------------------------------------------------------
0.39 0.08 500001 Rubinius::ByteArray.new [23]
[44] 0.3 0.04 0.00 500001 Rubinius::ByteArray.allocate_sized [44]
-------------------------------------------------------
0.59 0.17 507814 IO::InternalBuffer::fill_from<64> {} [20]
[45] 0.2 0.04 0.00 8791 IO::InternalBuffer#fill [45]
-------------------------------------------------------
 
76 methods omitted
 
121 methods called a total of 0 times
 
 
real 0m17.630s
user 0m17.778s
sys 0m0.157s
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Something went wrong with that request. Please try again.