=> Booting WEBrick => Rails 3.2.3 application starting in development on http://0.0.0.0:3000 => Call with -d to detach => Ctrl-C to shutdown server start loading unicode db finished loading unicode db Started GET "/home/index" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:44:18 -0400 Processing by HomeController#index as HTML Rendered home/index.html.erb within layouts/application (31.0ms) Completed 200 OK in 107285ms (Views: 702.0ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:46:11 -0400 Served asset /application.css - 200 OK (26ms) Started GET "/assets/jquery.js?body=1" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:46:14 -0400 Served asset /jquery.js - 200 OK (20ms) Started GET "/assets/application.js?body=1" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:46:14 -0400 Served asset /application.js - 200 OK (62ms) Started GET "/assets/home.css?body=1" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:46:14 -0400 Served asset /home.css - 200 OK (11ms) Started GET "/assets/home.js?body=1" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:46:14 -0400 Served asset /home.js - 200 OK (11ms) Started GET "/assets/jquery_ujs.js?body=1" for 0:0:0:0:0:0:0:1 at 2012-06-05 10:46:14 -0400 Served asset /jquery_ujs.js - 200 OK (29ms) Flat profile of 30.14 secs (1947 total ticks): RubyThread-4: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/server.rb:172 Interpreted + native Method 99.5% 0 + 1937 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 0.1% 0 + 1 sun.nio.ch.WindowsSelectorImpl.setWakeupSocket0 0.1% 0 + 1 java.io.WinNTFileSystem.list 0.1% 0 + 1 java.lang.System.currentTimeMillis 0.1% 1 + 0 org.jruby.ast.ReturnNode.interpret 0.1% 1 + 0 org.jruby.RubyFixnum.op_equal 0.1% 1 + 0 org.jruby.RubyHash.alloc 0.1% 1 + 0 org.jruby.RubyFloat.op_minus 0.1% 1 + 0 java.security.Provider.getService 99.9% 5 + 1940 Total interpreted Stub + native Method 0.1% 0 + 1 java.lang.System.arraycopy 0.1% 0 + 1 Total stub Thread-local ticks: 0.1% 1 Unknown: no last frame Flat profile of 31.03 secs (2005 total ticks): RubyThread-6: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/server.rb:172 Interpreted + native Method 99.3% 0 + 1987 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 0.1% 0 + 1 sun.nio.ch.SocketDispatcher.close0 0.1% 1 + 0 org.jruby.RubyDigest$DigestBase$1.allocate 0.1% 1 + 0 org.jruby.RubyThread.getThreadLocals 0.1% 1 + 0 org.jruby.runtime.Block.yieldSpecific 0.1% 1 + 0 org.jruby.ast.ReturnNode.interpret 0.1% 1 + 0 org.joni.Region. 0.1% 1 + 0 org.jruby.RubyHash.fastASetCheckString19 0.1% 1 + 0 org.jruby.RubyArray. 0.1% 1 + 0 org.jruby.runtime.Block. 0.1% 1 + 0 org.joni.Region.clone 99.8% 9 + 1988 Total interpreted Stub + native Method 0.1% 0 + 2 java.lang.Thread.currentThread 0.1% 0 + 1 sun.misc.Unsafe.compareAndSwapInt 0.1% 0 + 3 Total stub Thread-local ticks: 0.2% 5 Blocked (of total) Flat profile of 31.09 secs (2009 total ticks): RubyThread-3: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/server.rb:172 Interpreted + native Method 99.2% 0 + 1980 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 0.2% 0 + 3 sun.nio.ch.SocketDispatcher.close0 0.1% 0 + 2 sun.nio.ch.WindowsSelectorImpl.setWakeupSocket0 0.1% 0 + 1 sun.nio.ch.ServerSocketChannelImpl.accept0 0.1% 0 + 1 sun.nio.ch.Net.bind0 0.1% 0 + 1 java.lang.Object.wait 0.1% 0 + 1 java.lang.Object.notifyAll 0.1% 1 + 0 java.lang.ClassLoader.defineClass1 0.1% 1 + 0 org.joni.Region. 0.1% 1 + 0 org.jruby.internal.runtime.methods.DynamicMethod.call 0.1% 1 + 0 java.util.HashMap. 99.8% 4 + 1989 Total interpreted Stub + native Method 0.1% 0 + 2 sun.misc.Unsafe.compareAndSwapInt 0.1% 0 + 1 java.lang.Thread.currentThread 0.2% 0 + 3 Total stub Thread-local ticks: 0.6% 13 Blocked (of total) Flat profile of 31.19 secs (2014 total ticks): RubyThread-2: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/server.rb:172 Interpreted + native Method 99.3% 0 + 1987 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 0.1% 0 + 2 sun.nio.ch.WindowsSelectorImpl.setWakeupSocket0 0.1% 2 + 0 java.nio.charset.CharsetEncoder.flush 0.0% 0 + 1 sun.nio.ch.SocketDispatcher.write0 0.0% 1 + 0 java.nio.channels.spi.AbstractSelector.end 0.0% 1 + 0 org.jruby.javasupport.util.RuntimeHelpers.breakJump 0.0% 0 + 1 sun.misc.Unsafe.freeMemory 0.0% 0 + 1 java.lang.Object.wait 0.0% 1 + 0 org.joni.Region. 0.0% 1 + 0 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll 0.0% 1 + 0 org.jruby.ast.executable.RuntimeCache.reCache 99.9% 7 + 1992 Total interpreted Stub + native Method 0.0% 0 + 1 java.io.WinNTFileSystem.getBooleanAttributes 0.0% 0 + 1 Total stub Thread-local ticks: 0.6% 13 Blocked (of total) 0.0% 1 Unknown: no last frame Flat profile of 31.19 secs (2015 total ticks): RubyThread-5: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/server.rb:172 Interpreted + native Method 99.3% 0 + 1985 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 0.1% 1 + 0 org.joni.Regex.getOptions 0.1% 0 + 1 sun.nio.ch.WindowsSelectorImpl.setWakeupSocket0 0.1% 0 + 1 java.io.WinNTFileSystem.list 0.1% 1 + 0 java.lang.String.toCharArray 0.1% 1 + 0 org.joni.ArrayCompiler.prepare 0.1% 1 + 0 java.util.HashMap$HashIterator. 0.1% 1 + 0 rubyjit.Rack::Utils::HeaderHash#\=\{\}=_792EFFF7D1A33237466D284C524CEFE1A42C1FAB.__file__ 0.1% 1 + 0 org.joni.Matcher.forwardSearchRange 99.7% 6 + 1987 Total interpreted Stub + native Method 0.1% 0 + 3 sun.misc.Unsafe.compareAndSwapInt 0.1% 0 + 2 java.lang.Thread.currentThread 0.1% 0 + 1 java.lang.System.arraycopy 0.3% 0 + 6 Total stub Thread-local ticks: 0.7% 15 Blocked (of total) 0.1% 1 Unknown: no last frame Flat profile of 151.19 secs (8264 total ticks): RubyThread-0: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/server.rb:172 Interpreted + native Method 27.4% 0 + 2261 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 2.0% 0 + 162 java.io.FileInputStream.open 0.4% 0 + 36 java.io.WinNTFileSystem.checkAccess 0.1% 0 + 10 java.io.RandomAccessFile.open 0.1% 10 + 0 org.jruby.runtime.load.LoadService.require 0.1% 0 + 8 java.io.WinNTFileSystem.canonicalize0 0.1% 0 + 7 java.lang.Throwable.getStackTraceElement 0.1% 6 + 0 org.jruby.runtime.load.LoadService.tryLoadingLibraryOrScript 0.1% 5 + 0 java.net.URI$Parser.parse 0.1% 5 + 0 java.util.Collections$SynchronizedCollection.iterator 0.1% 0 + 5 java.util.zip.ZipFile.open 0.0% 4 + 0 org.jruby.runtime.load.LoadService.tryResourceFromJarURL 0.0% 3 + 0 org.jruby.RubyArray. 0.0% 3 + 0 org.jruby.RubyModule.getAutoloadConstant 0.0% 3 + 0 org.jruby.util.JRubyFile.normalizeSeps 0.0% 0 + 3 java.lang.Throwable.fillInStackTrace 0.0% 3 + 0 org.jruby.runtime.load.LoadService$BailoutSearcher.trySearch 0.0% 2 + 0 org.jruby.RubyString. 0.0% 2 + 0 org.jruby.runtime.load.LoadService.smartLoad 0.0% 2 + 0 java.io.ExpiringCache.get 0.0% 2 + 0 java.util.Hashtable.remove 0.0% 2 + 0 org.jruby.runtime.load.LoadService$SourceBailoutSearcher.trySearch 0.0% 2 + 0 java.lang.ClassLoader.defineClass1 0.0% 1 + 0 org.jruby.parser.ParserSupport.pushLocalScope 0.0% 1 + 0 org.jruby.util.Dir$GlobPattern.next 31.8% 129 + 2501 Total interpreted (including elided) Compiled + native Method 8.2% 676 + 0 sun.nio.cs.UTF_8$Decoder.decodeArrayLoop 3.8% 308 + 10 java.nio.charset.Charset.decode 3.3% 275 + 0 sun.nio.cs.ThreadLocalCoders$Cache.forName 2.6% 214 + 0 java.io.Win32FileSystem.normalize 1.7% 138 + 0 org.jruby.RubyString.toJava 1.4% 115 + 2 org.jruby.runtime.load.LoadService.tryResourceFromLoadPathOrURL 0.8% 66 + 0 org.jruby.RubyString.decodeString 0.5% 29 + 9 org.jruby.RubyEncoding.decode 0.4% 35 + 0 org.jruby.RubyModule$Autoload.getConstant 0.4% 34 + 0 org.jruby.runtime.load.LoadService$BailoutSearcher.trySearch 0.2% 17 + 0 sun.nio.cs.UTF_8$Decoder.decodeLoop 0.1% 10 + 1 org.jruby.runtime.load.LoadService.featureAlreadyLoaded 0.0% 2 + 0 org.jruby.RubyModule.searchMethodInner 0.0% 2 + 0 org.jruby.runtime.marshal.UnmarshalStream.unmarshalObjectDirectly 0.0% 2 + 0 sun.nio.cs.UTF_8$Encoder.encodeArrayLoop 0.0% 0 + 1 org.jruby.RubyModule.addMethodAtBootTimeOnly 0.0% 1 + 0 org.jruby.lexer.yacc.ByteArrayLexerSource.skipUntil 0.0% 1 + 0 org.jruby.parser.BlockStaticScope.isDefined 0.0% 1 + 0 org.jruby.runtime.ContextAwareBlockBody.pre 0.0% 1 + 0 sun.nio.cs.US_ASCII$Decoder.decodeLoop 0.0% 1 + 0 java.io.File. 0.0% 1 + 0 java.net.URI$Parser.checkChars 0.0% 1 + 0 org.jruby.Ruby.getCurrentContext 0.0% 1 + 0 org.jruby.MetaClass.getRealClass 0.0% 1 + 0 java.util.Hashtable.get 23.8% 1946 + 24 Total compiled (including elided) Stub + native Method 43.4% 0 + 3590 java.io.WinNTFileSystem.getBooleanAttributes 0.6% 0 + 46 java.lang.String.intern 0.1% 0 + 12 java.io.WinNTFileSystem.checkAccess 0.1% 0 + 7 sun.misc.Unsafe.getInt 0.0% 0 + 3 java.lang.System.identityHashCode 0.0% 0 + 2 sun.misc.Unsafe.compareAndSwapInt 0.0% 0 + 2 java.lang.System.arraycopy 0.0% 0 + 2 java.lang.Throwable.getStackTraceElement 44.3% 0 + 3664 Total stub Exiting Flat profile of 0.01 secs (1 total ticks): SIGINT handler Thread-local ticks: 100.0% 1 Unknown: no last frame Flat profile of 213.91 secs (13888 total ticks): main Interpreted + native Method 99.0% 0 + 13744 com.kenai.jffi.Foreign.invokeArrayReturnInt 0.0% 0 + 3 java.util.zip.Inflater.inflateBytes 0.0% 3 + 0 org.joni.Region. 0.0% 0 + 3 java.io.FileInputStream.open 0.0% 3 + 0 org.jruby.javasupport.util.RuntimeHelpers.restructureBlockArgs19 0.0% 0 + 2 java .lang.Throwable.fillInStackTrace 0.0% 2 + 0 org.jruby.lexerFlat profile of 210.61 secs (11975 total ticks): main Interpreted + native Method 90.1% 0 + 10791 sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0 2.1% 0 + 249 java.io.FileInputStream.open 0.2% 0 + 20 java.io.WinNTFileSystem.getBooleanAttributes 0.1% 0 + 16 java.lang.Throwable.fillInStackTrace 0.1% 11 + 0 org.jruby.lexer.yacc.RubyYaccLexer.yylex 0.1% 9 + 0 org.jruby.runtime.scope.TwoVarDynamicScope.getArgValues 0.1% 8 + 0 org.jruby.ast.FCallThreeArgBlockPassNode..yac 0.1% 1 + 7 jac.vaRu.lbyanYag.ccClLeasxes.r.getgeDetIcldearntedifCoiensr tr 0.0% 2 + 0 uc org.jtorursby0 .p 0.1% 7 + 0 ar jseavr.a.Ruutbyil19.rPaegrsexer.P. om 0.0% 2 + 0 pi org.jleru 0.1% 7 + 0 by o.argst.j.Arurrbyay.rNoundeti.iment.Blerocprk.etit 0.1% 5 + 1 jaivvae .l 0.0% 2 + 0 an og.rgClassLo.jadruerby.d.lefexinereC.ylaass1 0.1% 6 + 0 orccg..RjrububyYy.acrucLnteximere..yCaylllexBl 0.0% 2 + 0 oocrgk..jyiruelbyd .a 0.1% 6 + 0 st o.Crgon.jstruNobyde.u.itisCl.acByheted List.ru 0.0% 5 + 0 orbyg..Rjrububy.y.inasitt.CoArrera 0.0% 0 + 2 jayNvaod.le.aning.teClrpasres.tPgeritDmiectilavere 0.0% 0 + 5 jadCvaon.lsangtr.Sucytosrts0 e 0.0% 0 m.nanoTime + 2 0.0% 5 j + 0 ava.lang.Thread.currentThread o 0.0% 0 rg.jruby.RubyHash.alloc + 1 0.0% 1 sun.misc.Unsafe.compareAndS + 4 java.lang.Class.forName0 w 0.0% 0 a + 4 java.lang.Object.getClass pInt 0.0% 4 0.0% 0 + 0 + 1 jsauvn.nio.ch.FileDispatcherImpl.read0 a 0.0% 1 . + 0 ijo.Win32FilaeSystem.vresolve a.io.FilterInputStream.read 0.0% 4 0.0% 1 + 0 java.lang.String.toCharArray + 0 0.0% 0 o + 4 java.io.WinNTFileSystem.getLength rg.j 0.0% 0 + 4 ojnava.util.zip.ZipFile.open i 0.0% 0 . + 4 Regex.matcher s 0.0% 1 un.misc.Unsafe.compareAndSwapLong + 0 0.0% 4 j + 0 ava.nio.HeapByteBuffer. or 0.0% 1 g.joni.OptExactInfo. + 0 0.0% 0 j + 4 java.io.WinNTFileSystem.canonicalize0 a 96.7% 405 va.util.regex.M + 11170 Total interpreted (including elided) Compiled + native Method 0.1% 16 + 0 sun.nio.cs.UTF_8$DecTerminate batch job (Y/N)? oder.adecodeArrayLoop t 0.1% 11 cher.getTextLength + 0 0.0% 1 org.jruby.RubyString.decodeString + 0 0.1% 5 org.joni.Option.isDontCaptureGroup + 1 jav 0.0% 1 a.nio.charset.Charset.decode + 0 0.0% 4 org.jruby.runtime.callsite.CachingCallSite.pollAndGetClass + 0 0.0% 1 org.jruby.parser.Ruby19Parser. + 0 yyparse java.lang.CharacterDataLatin1.getPropertiesEx 0.0% 0 0.0% 1 + 4 java.util.ArrayList.iterator + 0 0.0% 1 org.jruby.RubyBasicObject.isTai + 2 org.jruby.lexer.yacc.RubyYaccLexer.yylex nt 0.0% 3 0.0% 1 + 0 org. + 0 sun.niojoni.ast.Node.isSimple . 99.7% 86 cs.ThreadLocalCoders$Cache.forName + 13765 0.0% 3 Total interpreted (including elided) Compiled + native Method + 0 java.io.Win32FileSystem.normalize 0.0% 3 0.0% 2 + 0 + 1 org.jruby.runtime.load.LoadService.tryResourceFromLoadPathOrURL org. 0.0% 0 joni.Analyser. + 2 java.lang.Object. optimizeNodeLeft 0.0% 0 0.0% 2 + 2 + 0 org.jruby.parser.Ruby19Parser.org.jruby.RubyModule.addMethodAyyparse tBootTimeOnly 0.0% 0 0.0% 1 + 1 + 0 java.util.HashMap.get org.jruby.RubyArray$i$0$0$hash19.call 0.0% 0 0.0% 0 + 1 + 1 org.joni.OptMapInfo.altMerge org.jruby.RubyEncoding.decode 0.0% 0 0.0% 1 + 1 + 0 java.util.Collections$SynchronizedCollection.contains java.lang.AbstractStringBuilder.ap 0.0% 0 pend + 1 org.jruby.internal.runtime.methods.DynamicMethod. 0.0% 1 0.0% 1 + 0 + 0 org.jruby.lexer.yacc.StringTerm.parseStringIntoBuffer org.jruby.util.io.CRLFStreamWrapper.convertCRLFToLF 0.0% 0 0.0% 1 + 1 + 0 org.jruby.runtime.load.LoadService.featureAlreadyLoaded org.jruby. 0.0% 1 compiler.ASTInspector. + 0 inspect org.jruby.RubyClass.invalidateCacheDescendants 0.1% 6 0.0% 1 + 4 Total compiled Stub + native Method + 0 0.0% 0 java.util.concurrent.ConcurrentHashMap.put 0.0% 1 + 0 java.util.regex.Matcher.search 0.0% 1 + 0 org.jruby.lexer.yacc.RubyYaccLexer.getIdentifier 0.0% 0 + 1 org.joni.Matcher.search 0.0% 0 + 1 java.util.jar.JarFile.hasClassPathAttribute 0.0% 1 + 0 java.lang.String.toLowerCase 0.0% 0 + 1 org.jruby.lexer.yacc.RubyYaccL + 3 e xer.identifier java.lang.System.arraycopy 0.6% 54 0.0% 0 + 18 Total compiled (including elided) Stub + native Method + 1 java.lang.String.intern 1.9% 0 0.0% 0 + 226 java.io.WinNTFileSystem.getBooleanAttributes + 4 Total stub Thread-local ticks: 0.4% 0 0.0% 1 Blocked (of total) + 42 java.lang.String.intern 0.2% 22 Class loader 0.1% 0 + 9 java.lang.System.arraycopy 0.1% 0 Global summary of 213.91 seconds: + 9 java.lang.Thread.currentThread 100.0% 13892 0.0% 0 Received ticks + 4 sun.misc.Unsafe.getObjectVolatile 0.0% 4 Received GC ticks 0.0% 0 0.4% 60 Compilation + 2 sun.misc.Unsafe.compareAndSwapInt 0.2% 22 Class loader 0.0% 0 0.0% 1 Unknown code + 1 java.util.zip.Inflater.inflateBytes 0.0% 0 + 1 sun.misc.Unsafe.getInt 2.5% 0 + 294 Total stub Thread-local ticks: 0.3% 34 Class loader Flat profile of 0.00 secs (1 total ticks): DestroyJavaVM Thread-local ticks: 100.0% 1 Blocked (of total) Flat profile of 172.53 secs (9630 total ticks): RubyThread-1: C:/Java/jruby-1.6.7.2/lib/ruby/1.9/webrick/utils.rb:123 Interpreted + native Method 43.3% 29 + 0 org.jruby.RubyArray. 22.4% 15 + 0 org.jruby.RubyThread.sleep 11.9% 0 + 8 java.lang.Object.wait 4.5% 0 + 3 java.lang.System.currentTimeMillis 3.0% 0 + 2 java.lang.Double.doubleToRawLongBits 1.5% 1 + 0 org.jruby.RubyThread.pollThreadEvents 1.5% 0 + 1 java.lang.Double.longBitsToDouble 1.5% 1 + 0 org.jruby.RubyHash.default_value_get 1.5% 1 + 0 org.jruby.ast.FCallOneArgNode.interpret 1.5% 1 + 0 org.jruby.internal.runtime.methods.DynamicMethod.call 1.5% 1 + 0 org.jruby.Ruby.newString 1.5% 1 + 0 java.lang.StrictMath.floorOrCeil 1.5% 1 + 0 org.jruby.util.ByteList.caseInsensitiveCmp 1.5% 1 + 0 org.jruby.internal.runtime.methods.JavaMethod$JavaMethodN.call 98.5% 52 + 14 Total interpreted Compiled + native Method 1.5% 1 + 0 org.jruby.ast.CallNoArgNode.interpret 1.5% 1 + 0 Total compiled Thread-local ticks: 99.3% 9563 Blocked (of total) Global summary of 210.66 seconds: 100.0% 12053 Received ticks 0.6% 71 Received GC ticks 3.8% 453 Compilation 0.0% 3 Other VM operations 0.3% 34 Class loader 0.0% 3 Unknown code