Rubymine debugger cannot connect to dispatcher

I currently have a remote sdk setup where rubymine is running on windows 7 and the rails is running on ubuntu.

It runs fine in non-debug mode but when I try to run with debug it cannot connect:

Exception: Connection refused - connect(2) for "192.168.1.4" port 45480

 

 

I am running ruby 2.2.6p396 and rails 3.2.22.5

The debug gems I am use are: ruby-debug-ide 0.6.1.beta4, debase 0.2.2.beta9

I've tried a bunch of different versions of ruby/rails and the debug gems with the same result.

 

11 comments
Comment actions Permalink

Hello, could you please check if there is ssh connection between your host and ubuntu? If everything OK please attach idea.log (Show Log in Explorer).

0
Comment actions Permalink

Hi Anna,  I took a look at the log and didn't see anything suspicious but you may know better.  Here is the most recent lines from an attempted connection:

 

2017-07-17 10:08:46,510 [430776827] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @1cb71d82 to pwhitt@192.168.1.4:22
2017-07-17 10:08:46,521 [430776838] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/pwhitt/.rvm/rubies/ruby-2.2.6/bin/ruby -e "require 'socket'; puts (1..2).map{|i| TCPServer.open('127.0.0.1', 0)}.map{|server| port = server.addr[1]; server.close; port}" within SSH session @1cb71d82 to pwhitt@192.168.1.4:22
2017-07-17 10:08:46,636 [430776953] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @1cb71d82 to pwhitt@192.168.1.4:22
2017-07-17 10:08:46,667 [430776984] INFO - transport.SshConnectionService - Executing SSH command: cd /home/pwhitt/git/testapp/sn; env "ANSICON"="" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "RM_INFO"="RM-171.4694.62" "gemset"="testapp3" "GEM_PATH"="/home/pwhitt/.rvm/gems/ruby-2.2.6@testapp3:/home/pwhitt/.rvm/gems/ruby-2.2.6@global" "JETBRAINS_REMOTE_RUN"="1" "IRBRC"="/home/pwhitt/.rvm/rubies/ruby-2.2.6/.irbrc" "RUBYLIB"="/home/pwhitt/.rubymine_helpers/rb/testing/patch/common:/home/pwhitt/.rubymine_helpers/rb/testing/patch/testunit:/home/pwhitt/.rvm/gems/ruby-2.2.6@testapp3/gems/debase-0.2.2.beta9/lib:/home/pwhitt/.rvm/gems/ruby-2.2.6@testapp3/gems/ruby-debug-ide-0.6.1.beta4/lib" "GEM_HOME"="/home/pwhitt/.rvm/gems/ruby-2.2.6@testapp3" "RUBYMINE_TESTUNIT_REPORTER"="/home/pwhitt/.rubymine_helpers/rb/testing/patch/testunit" "MY_RUBY_HOME"="/home/pwhitt/.rvm/rubies/ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "PATH"="/home/pwhitt/.rvm/rubies/ruby-2.2.6/bin:/home/pwhitt/.rvm/gems/ruby-2.2.6@testapp3/bin:/home/pwhitt/.rvm/gems/ruby-2.2.6@global/bin:/home/pwhitt/.rvm/bin:$PATH" /home/pwhitt/.rvm/rubies/ruby-2.2.6/bin/ruby -e 'at_exit{sleep(1)};$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/pwhitt/.rvm/gems/ruby-2.2.6@testapp3/gems/ruby-debug-ide-0.6.1.beta4/bin/rdebug-ide --debug --disable-int-handler --evaluation-timeout 10 --rubymine-protocol-extensions --port 45464 --host 0.0.0.0 --dispatcher-port 42195 -- /home/pwhitt/git/testapp/sn/script/rails server -b 192.168.1.4 -p 3001 -e development within SSH session @1cb71d82 to pwhitt@192.168.1.4:22
2017-07-17 10:08:46,688 [430777005] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from remote server localhost:45464 to local localhost:58215]
2017-07-17 10:08:46,688 [430777005] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from local server localhost:58216 to remote localhost:42195]
2017-07-17 10:08:46,688 [430777005] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from remote server 192.168.1.4:3001 to local localhost:3001]

 

sudo netstat -tnpa | grep 'ESTABLISHED.*sshd'
tcp 0 0 192.168.1.4:22 192.168.1.50:58505 ESTABLISHED 1765/sshd: pwhitt // THIS ONE IS MY CONNECTION TO RUN THIS COMMAND
tcp 0 36 192.168.1.4:22 192.168.1.50:58493 ESTABLISHED 1185/sshd: pwhitt // THIS IS RUBYMINE

 

0
Comment actions Permalink

another odd thing is that rubymine 7.0 works with an older project but this one (rubymine 2017.1.5) does not...

0
Comment actions Permalink

Thanks for the quick answer. Please attach the screenshot of Run/Debug Configuration, which you are running?

0
Comment actions Permalink

I'm running the development configuration.  It works if I just try to run the app (without debugging) but it fails to connect in debug mode:

0
Comment actions Permalink

Philip, sorry for delay, we're trying to figure out the problem. Firstly I just want to make sure that you are using the correct configuration. I.e. If you want to attach to remote Rails server in debug mode, please use this tutorial, section for Rails server https://confluence.jetbrains.com/display/RUBYDEV/How+to+setup+and+run+ruby+remote+debug+session.

And if you are using this tutorial please attach command you are running on the Ubuntu? 

If you are trying to run with remote SDK please describe how did you add it and attach all idea.log(Help | Show Log in...) please.

 

0
Comment actions Permalink

Ok, so I have two projects.  One that works and one that does't.  I deleted the rvm gemsets for both and re-added them (so the rubymine debug gems would have to be reinstalled).  I also deleted the gemsets from rubymine and the logs so i was starting fresh.  Then I setup each one at a time to get the logs for each so maybe you will see what is different.  They both are the same rubymine connecting to the same server, just different projects.

The process I followed for both was:

- Open the project

- Goto Settings->Ruby SDK and Gems

- Clicked Add->New Remote

- Selected SSH Credentials and filled out with same host/login

- Chose the ~/.rvm/gems/<ruby-version>@<project> folder for the path

- Pressed OK and it downloaded the gems

- Selected the new gemset to be used as the project SDK

- Apply and Close

- Clicked Debug button on toolbar and said yes to install debug gems

- Either worked or didn't

- Then pressed stop and closed rubymine and made copy of log

* I'll post the working then the not working logs after this

 

0
Comment actions Permalink

WORKING LOGS

2017-07-24 13:12:52,863 [ 0] INFO - #com.intellij.idea.Main - ------------------------------------------------------ IDE STARTED ------------------------------------------------------
2017-07-24 13:12:52,873 [ 10] INFO - #com.intellij.util.ui.JBUI - User scale factor: 1.0
2017-07-24 13:12:52,873 [ 10] INFO - #com.intellij.util.ui.JBUI - System scale factor: 1.0 (IDE-managed HiDPI)
2017-07-24 13:12:52,880 [ 17] INFO - #com.intellij.idea.Main - IDE: RubyMine (build #RM-171.4694.62, 27 Jun 2017 19:52)
2017-07-24 13:12:52,880 [ 17] INFO - #com.intellij.idea.Main - OS: Windows 7 (6.1, amd64)
2017-07-24 13:12:52,880 [ 17] INFO - #com.intellij.idea.Main - JRE: 1.8.0_112-release-736-b21 (JetBrains s.r.o)
2017-07-24 13:12:52,880 [ 17] INFO - #com.intellij.idea.Main - JVM: 25.112-b21 (OpenJDK 64-Bit Server VM)
2017-07-24 13:12:52,882 [ 19] INFO - #com.intellij.idea.Main - JVM Args: -Xms128m -Xmx750m -XX:ReservedCodeCacheSize=240m -XX:+UseConcMarkSweepGC -XX:SoftRefLRUPolicyMSPerMB=50 -ea -Dsun.io.useCanonCaches=false -Djava.net.preferIPv4Stack=true -XX:+HeapDumpOnOutOfMemoryError -XX:-OmitStackTraceInFastThrow -Djb.vmOptionsFile=C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\rubymine64.exe.vmoptions -Xbootclasspath/a:C:\Program Files\JetBrains\RubyMine 2017.1.5\lib\boot.jar -Didea.platform.prefix=Ruby -Didea.paths.selector=RubyMine2017.1 -XX:ErrorFile=C:\Users\SynLogek\java_error_in_rubymine_%p.log -XX:HeapDumpPath=C:\Users\SynLogek\java_error_in_rubymine.hprof
2017-07-24 13:12:52,882 [ 19] INFO - #com.intellij.idea.Main - ext: C:\Program Files\JetBrains\RubyMine 2017.1.5\jre64\lib\ext: [access-bridge-64.jar, cldrdata.jar, dnsns.jar, jaccess.jar, jfxrt.jar, localedata.jar, meta-index, nashorn.jar, sunec.jar, sunjce_provider.jar, sunmscapi.jar, sunpkcs11.jar, zipfs.jar]
2017-07-24 13:12:52,882 [ 19] INFO - #com.intellij.idea.Main - JNU charset: Cp1252
2017-07-24 13:12:52,896 [ 33] INFO - #com.intellij.idea.Main - JNA library (64-bit) loaded in 14 ms
2017-07-24 13:12:52,897 [ 34] INFO - penapi.util.io.win32.IdeaWin32 - Native filesystem for Windows is operational
2017-07-24 13:12:52,898 [ 35] INFO - #com.intellij.idea.Main - Using "FocusKiller" library to prevent focus stealing.
2017-07-24 13:12:54,801 [ 1938] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor js-nashorn-support.xml
2017-07-24 13:12:54,816 [ 1953] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor plugin-intelliLang.xml
2017-07-24 13:12:54,883 [ 2020] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor uml-java-support.xml
2017-07-24 13:12:54,884 [ 2021] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor uml-properties-support.xml
2017-07-24 13:12:55,032 [ 2169] INFO - llij.ide.plugins.PluginManager - Plugin 'Ruby Docker' can't be loaded because: Plugin is disabled
2017-07-24 13:12:55,119 [ 2256] INFO - llij.ide.plugins.PluginManager - 50 plugins initialized in 465 ms
2017-07-24 13:12:55,123 [ 2260] INFO - llij.ide.plugins.PluginManager - Loaded bundled plugins: CSS Support (171.4694.62), CVS Integration (11), CoffeeScript (2.0), Database Tools and SQL (1.0), Docker integration (2.5.3.1), EditorConfig (171.4694.62), Gherkin (999.999), Git Integration (8.1), GitHub (171.4694.62), HTML Tools (2.0), Haml (171.4694.62), IDEA CORE (171.4694.62), IntelliLang (8.0), JavaScript Debugger (1.0), JavaScript Intention Power Pack (0.9.4), JavaScript Support (1.0), Less support (171.4694.62), Markdown support (VERSION), Perforce Integration (2.0), Puppet Support (VERSION), REST Client (171.4694.62), Remote Hosts Access (0.1), Ruby CSS Support Integration (171.4694.62), Ruby Code Coverage (171.4694.62), Ruby Cucumber support (171.4694.62), Ruby Database Support Integration (171.4694.62), Ruby Haml Support Integration (171.4694.62), Ruby I18n Support Integration (171.4694.62), Ruby JavaScript Debugger Integration (171.4694.62), Ruby Puppet Support Integration (171.4694.62), Ruby Remote SDK Support (0.1), Ruby Slim Support Integration (171.4694.62), Ruby UML Support Integration (171.4694.62), RubyMotion support (171.4694.62), SSH Remote Run (0.1), Sass support (171.4694.62), Settings Repository (171.4694.62), Slim (VERSION), Stylus support (999.999), Subversion Integration (1.1), Task Management (1.0), Terminal (0.1), TextMate bundles support (VERSION), Time Tracking (1.0), UML Support (1.0), Vagrant (171.4694.62), YAML (171.4694.62), hg4idea (10.0), tslint (171.4694.62)
2017-07-24 13:12:55,123 [ 2260] INFO - llij.ide.plugins.PluginManager - Disabled plugins: Ruby Docker (VERSION)
2017-07-24 13:12:55,418 [ 2555] INFO - ellij.util.io.PagedFileStorage - lower=100; upper=500; buffer=10; max=705
2017-07-24 13:12:55,443 [ 2580] INFO - pl.local.NativeFileWatcherImpl - Starting file watcher: C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\fsnotifier64.exe
2017-07-24 13:12:55,450 [ 2587] INFO - pl.local.NativeFileWatcherImpl - Native file watcher is operational.
2017-07-24 13:12:55,470 [ 2607] INFO - pi.util.registry.RegistryState - Registry values changed by user:
2017-07-24 13:12:55,470 [ 2607] INFO - pi.util.registry.RegistryState - dumb.aware.run.configurations = true
2017-07-24 13:12:55,779 [ 2916] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=0.0; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:56,017 [ 3154] INFO - til.net.ssl.CertificateManager - Default SSL context initialized
2017-07-24 13:12:56,024 [ 3161] INFO - figurations.GeneralCommandLine - Cannot run program "C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\java.exe": CreateProcess error=2, The system cannot find the file specified
java.io.IOException: Cannot run program "C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\java.exe": CreateProcess error=2, The system cannot find the file specified
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1048)
at com.intellij.execution.configurations.GeneralCommandLine.startProcess(GeneralCommandLine.java:405)
at com.intellij.execution.configurations.GeneralCommandLine.createProcess(GeneralCommandLine.java:375)
at com.intellij.execution.process.OSProcessHandler.<init>(OSProcessHandler.java:45)
at com.intellij.execution.process.CapturingProcessHandler.<init>(CapturingProcessHandler.java:38)
at com.intellij.execution.util.ExecUtil.execAndGetOutput(ExecUtil.java:100)
at com.intellij.util.JdkBundle.a(JdkBundle.java:221)
at com.intellij.util.JdkBundle.createBundle(JdkBundle.java:92)
at com.intellij.util.JdkBundle.createBoot(JdkBundle.java:125)
at com.intellij.util.JdkBundle.createBoot(JdkBundle.java:113)
at com.intellij.ide.SystemHealthMonitor.a(SystemHealthMonitor.java:86)
at com.intellij.ide.SystemHealthMonitor.initComponent(SystemHealthMonitor.java:75)
at com.intellij.openapi.components.impl.ComponentManagerImpl$ComponentConfigComponentAdapter.getComponentInstance(ComponentManagerImpl.java:491)
at com.intellij.openapi.components.impl.ComponentManagerImpl.createComponents(ComponentManagerImpl.java:120)
at com.intellij.openapi.application.impl.ApplicationImpl.a(ApplicationImpl.java:466)
at com.intellij.openapi.progress.impl.CoreProgressManager.a(CoreProgressManager.java:176)
at com.intellij.openapi.progress.impl.CoreProgressManager.a(CoreProgressManager.java:556)
at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:501)
at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:66)
at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:163)
at com.intellij.openapi.application.impl.ApplicationImpl.createComponents(ApplicationImpl.java:473)
at com.intellij.openapi.components.impl.ComponentManagerImpl.init(ComponentManagerImpl.java:104)
at com.intellij.openapi.application.impl.ApplicationImpl.load(ApplicationImpl.java:425)
at com.intellij.openapi.application.impl.ApplicationImpl.load(ApplicationImpl.java:411)
at com.intellij.idea.IdeaApplication.run(IdeaApplication.java:203)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.intellij.idea.MainImpl$1$1.a(MainImpl.java:63)
at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311)
at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:762)
at java.awt.EventQueue.access$500(EventQueue.java:98)
at java.awt.EventQueue$3.run(EventQueue.java:715)
at java.awt.EventQueue$3.run(EventQueue.java:709)
at java.security.AccessController.doPrivileged(Native Method)
at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:80)
at java.awt.EventQueue.dispatchEvent(EventQueue.java:732)
at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:343)
at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:201)
at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)
Caused by: java.io.IOException: CreateProcess error=2, The system cannot find the file specified
at java.lang.ProcessImpl.create(Native Method)
at java.lang.ProcessImpl.<init>(ProcessImpl.java:386)
at java.lang.ProcessImpl.start(ProcessImpl.java:137)
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
... 44 more
2017-07-24 13:12:56,036 [ 3173] INFO - rains.ide.BuiltInServerManager - built-in server started, port 63342
2017-07-24 13:12:56,057 [ 3194] INFO - gs.impl.UpdateCheckerComponent - channel: release
2017-07-24 13:12:56,131 [ 3268] INFO - il.indexing.FileBasedIndexImpl - Index exts enumerated:23
2017-07-24 13:12:56,134 [ 3271] INFO - il.indexing.FileBasedIndexImpl - Index scheduled:2
2017-07-24 13:12:56,150 [ 3287] INFO - tellij.psi.stubs.StubIndexImpl - All stub exts enumerated:12
2017-07-24 13:12:56,150 [ 3287] INFO - tellij.psi.stubs.StubIndexImpl - stub exts update scheduled:0
2017-07-24 13:12:56,172 [ 3309] INFO - j.ide.script.IdeStartupScripts - 0 startup script(s) found
2017-07-24 13:12:56,505 [ 3642] INFO - pl$FileIndexDataInitialization - Initialization done:371
2017-07-24 13:12:56,613 [ 3750] INFO - plication.impl.ApplicationImpl - 82 application components initialized in 1961ms
2017-07-24 13:12:56,617 [ 3754] INFO - .intellij.idea.IdeaApplication - App initialization took 4237 ms
2017-07-24 13:12:56,623 [ 3760] INFO - exImpl$StubIndexInitialization - Initialization done:118
2017-07-24 13:12:57,323 [ 4460] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,328 [ 4465] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,333 [ 4470] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,337 [ 4474] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,341 [ 4478] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,345 [ 4482] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,349 [ 4486] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,352 [ 4489] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:12:57,523 [ 4660] INFO - .openapi.application.Preloader - Finished preloading com.intellij.openapi.actionSystem.impl.ActionPreloader@35bb41ee
2017-07-24 13:12:57,714 [ 4851] INFO - management.pik.PikSdkRefresher - Refreshing sdks
2017-07-24 13:12:57,714 [ 4851] INFO - management.pik.PikSdkRefresher - rubies folder doesn't exist
2017-07-24 13:12:57,718 [ 4855] INFO - gement.rbenv.RbenvSdkRefresher - Refreshing sdks
2017-07-24 13:12:57,718 [ 4855] INFO - gement.rbenv.RbenvSdkRefresher - rbenv isn't supported by OS
2017-07-24 13:12:57,836 [ 4973] INFO - ellij.project.impl.ProjectImpl - 107 project components initialized in 894 ms
2017-07-24 13:12:57,931 [ 5068] INFO - .openapi.application.Preloader - Finished preloading com.intellij.ide.ui.search.SearchableOptionPreloader@4c3cccda
2017-07-24 13:12:58,087 [ 5224] INFO - .openapi.application.Preloader - Finished preloading com.intellij.codeInsight.completion.CompletionPreloader@18f94448
2017-07-24 13:12:58,094 [ 5231] INFO - le.impl.ModuleManagerComponent - 1 module(s) loaded in 258 ms
2017-07-24 13:12:58,174 [ 5311] WARN - om.intellij.util.ProfilingUtil - Profiling agent is not enabled. Add -agentlib:yjpagent to idea.vmoptions if necessary to profile IDEA.
2017-07-24 13:12:58,756 [ 5893] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 307ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:12:58,838 [ 5975] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 80ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:12:59,508 [ 6645] INFO - tartup.impl.StartupManagerImpl - OpenFilesActivity run in 1099
2017-07-24 13:13:00,194 [ 7331] INFO - tartup.impl.StartupManagerImpl - W:/git/projects/chess2/.idea case-sensitivity: expected=false actual=false
2017-07-24 13:13:00,195 [ 7332] WARN - api.vfs.impl.local.FileWatcher - Project files cannot be watched (are they under network mount?)
2017-07-24 13:13:00,195 [ 7332] INFO - tartup.impl.StartupManagerImpl - unwatched roots: [W:/git/projects/chess2]
2017-07-24 13:13:00,195 [ 7332] INFO - tartup.impl.StartupManagerImpl - manual watches: [W:\]
2017-07-24 13:13:00,904 [ 8041] INFO - tor.impl.FileEditorManagerImpl - Project opening took 4059 ms
2017-07-24 13:13:00,921 [ 8058] INFO - e.GemRequirementsChangeWatcher - Initial scan started
2017-07-24 13:13:00,995 [ 8132] INFO - e.GemRequirementsChangeWatcher - Initial scan took: 73 ms
2017-07-24 13:13:00,996 [ 8133] WARN - e.GemRequirementsChangeWatcher - No Sdk selected for Module: 'chess2'
2017-07-24 13:15:17,719 [ 144856] INFO - byRemoteInterpreterManagerImpl - Uploading helpers for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) at Thread[ApplicationImpl pooled thread 2,4,main]
2017-07-24 13:15:17,719 [ 144856] INFO - byRemoteInterpreterManagerImpl - Uploading helpers for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) at Thread[ApplicationImpl pooled thread 4,4,Idea Thread Group]
2017-07-24 13:15:17,726 [ 144863] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:15:17,726 [ 144863] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:15:17,727 [ 144864] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:15:17,727 [ 144864] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:15:17,793 [ 144930] INFO - ellij.project.impl.ProjectImpl - 15 project components initialized in 15 ms
2017-07-24 13:15:17,826 [ 144963] INFO - s.webDeployment.PublishUtils$1 - Using "C:\Users\SynLogek\AppData\Local\Temp\vfs_cache" as temporary files store.
2017-07-24 13:15:18,129 [ 145266] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:15:18,129 [ 145266] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:15:18,129 [ 145266] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:15:18,129 [ 145266] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:15:18,136 [ 145273] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:15:18,136 [ 145273] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:15:18,136 [ 145273] INFO - byRemoteInterpreterManagerImpl - Helpers uploaded for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) at Thread[ApplicationImpl pooled thread 2,4,main]
2017-07-24 13:15:18,136 [ 145273] INFO - byRemoteInterpreterManagerImpl - Helpers uploaded for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) at Thread[ApplicationImpl pooled thread 4,4,Idea Thread Group]
2017-07-24 13:15:18,136 [ 145273] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby)
2017-07-24 13:15:18,136 [ 145273] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby)
2017-07-24 13:15:18,136 [ 145273] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:15:18,136 [ 145273] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:15:29,496 [ 156633] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:15:29,497 [ 156634] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:15:29,497 [ 156634] WARN - ruby.remote.RubyRemoteTransfer - Gems copying has been canceled
2017-07-24 13:15:30,605 [ 157742] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) updated
2017-07-24 13:15:30,604 [ 157741] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.6-p396 [wrecker2]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) updated
2017-07-24 13:16:19,616 [ 206753] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @7b519628
2017-07-24 13:16:25,908 [ 213045] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:25,931 [ 213068] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:25,944 [ 213081] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -v within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:25,966 [ 213103] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:25,977 [ 213114] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -v within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:26,091 [ 213228] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:26,102 [ 213239] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e "print(defined?(RUBY_PATCHLEVEL) ? RUBY_PATCHLEVEL : 0)" within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:26,220 [ 213357] INFO - yCreateRemoteInterpreterDialog - initializing Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:16:26,220 [ 213357] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:16:26,220 [ 213357] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:16:26,460 [ 213597] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:16:26,461 [ 213598] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:16:26,461 [ 213598] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:16:26,461 [ 213598] INFO - yCreateRemoteInterpreterDialog - localGemsBinPath=C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842, remoteGemsBinPath=/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin
2017-07-24 13:16:26,464 [ 213601] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\bin, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin
2017-07-24 13:16:26,464 [ 213601] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\gems, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/gems
2017-07-24 13:16:26,464 [ 213601] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\specifications, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/specifications
2017-07-24 13:16:26,464 [ 213601] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\bundler, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bundler
2017-07-24 13:16:26,465 [ 213602] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-1929643563\bin, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin
2017-07-24 13:16:26,465 [ 213602] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-1929643563\gems, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/gems
2017-07-24 13:16:26,465 [ 213602] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-1929643563\specifications, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/specifications
2017-07-24 13:16:26,465 [ 213602] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-1929643563\bundler, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bundler
2017-07-24 13:16:26,465 [ 213602] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:16:30,181 [ 217318] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\gems\nokogiri-1.6.8.1\ext\nokogiri\nokogiri.so is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:16:30,585 [ 217722] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\gems\nokogiri-1.6.8.1\test\files\slow-xpath.xml is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:16:30,906 [ 218043] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\gems\nokogiri-1.6.8.1\lib\nokogiri\nokogiri.so is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:16:36,815 [ 223952] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1898828105\-2048135324\gems\babel-source-5.8.35\lib\babel.js is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:16:48,175 [ 235312] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:16:48,181 [ 235318] INFO - yCreateRemoteInterpreterDialog - Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) initialized
2017-07-24 13:16:48,204 [ 235341] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:48,215 [ 235352] INFO - byRemoteInterpreterManagerImpl - Uploading helpers for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) at Thread[ApplicationImpl pooled thread 2,4,main]
2017-07-24 13:16:48,215 [ 235352] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:16:48,215 [ 235352] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:16:48,238 [ 235375] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:16:48,238 [ 235375] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:16:48,240 [ 235377] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:16:48,240 [ 235377] INFO - byRemoteInterpreterManagerImpl - Helpers uploaded for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) at Thread[ApplicationImpl pooled thread 2,4,main]
2017-07-24 13:16:48,240 [ 235377] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:16:48,240 [ 235377] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:16:53,401 [ 240538] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:16:53,454 [ 240591] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 3ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:16:53,457 [ 240594] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 2ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:16:53,460 [ 240597] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) updated
2017-07-24 13:16:53,474 [ 240611] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:53,485 [ 240622] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/projects/chess2; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby W:/git/projects/chess2/bin/rails generate within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:16:53,599 [ 240736] INFO - ions.generators.GeneratorsUtil - bash: line 0: cd: W:/git/projects/chess2: No such file or directory
/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby: No such file or directory -- W:/git/projects/chess2/bin/rails (LoadError)

Exit code 1
2017-07-24 13:16:54,010 [ 241147] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:16:54,050 [ 241187] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,055 [ 241192] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,060 [ 241197] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,065 [ 241202] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,069 [ 241206] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,074 [ 241211] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,131 [ 241268] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,136 [ 241273] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,141 [ 241278] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,146 [ 241283] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,152 [ 241289] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,159 [ 241296] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,164 [ 241301] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,170 [ 241307] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,174 [ 241311] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,180 [ 241317] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,185 [ 241322] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,190 [ 241327] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,195 [ 241332] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,199 [ 241336] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,203 [ 241340] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,207 [ 241344] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,212 [ 241349] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,216 [ 241353] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,221 [ 241358] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,227 [ 241364] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,232 [ 241369] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,237 [ 241374] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,242 [ 241379] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,247 [ 241384] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,253 [ 241390] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,258 [ 241395] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,264 [ 241401] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,270 [ 241407] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,275 [ 241412] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,279 [ 241416] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,285 [ 241422] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,290 [ 241427] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,295 [ 241432] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,300 [ 241437] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,305 [ 241442] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,310 [ 241447] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,315 [ 241452] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,320 [ 241457] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,326 [ 241463] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,331 [ 241468] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,335 [ 241472] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,340 [ 241477] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,344 [ 241481] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,348 [ 241485] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,353 [ 241490] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,358 [ 241495] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,364 [ 241501] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,370 [ 241507] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,375 [ 241512] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,380 [ 241517] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,386 [ 241523] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,398 [ 241535] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,404 [ 241541] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,410 [ 241547] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,415 [ 241552] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,421 [ 241558] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,427 [ 241564] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,431 [ 241568] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,437 [ 241574] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,442 [ 241579] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,448 [ 241585] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,453 [ 241590] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,458 [ 241595] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,465 [ 241602] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,470 [ 241607] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,476 [ 241613] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,481 [ 241618] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,488 [ 241625] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,496 [ 241633] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:16:54,556 [ 241693] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:16:54,641 [ 241778] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 5ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:16:54,973 [ 242110] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 332ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:16:54,974 [ 242111] INFO - indexing.UnindexedFilesUpdater - Unindexed files update started: 3714 files to update
2017-07-24 13:17:04,839 [ 251976] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:17:04,873 [ 252010] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:17:04,894 [ 252031] INFO - indexing.UnindexedFilesUpdater - Unindexed files update canceled
2017-07-24 13:17:04,897 [ 252034] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 2ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:17:04,941 [ 252078] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 44ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:17:04,942 [ 252079] INFO - indexing.UnindexedFilesUpdater - Unindexed files update started: 2440 files to update
2017-07-24 13:17:06,897 [ 254034] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:06,914 [ 254051] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:17:06,931 [ 254068] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/projects/chess2; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' W:/git/projects/chess2/bin/bundle check within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:06,954 [ 254091] INFO - indexing.UnindexedFilesUpdater - Unindexed files update canceled
2017-07-24 13:17:06,965 [ 254102] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 1ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:17:06,987 [ 254124] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 22ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:17:06,988 [ 254125] INFO - indexing.UnindexedFilesUpdater - Unindexed files update started: 1853 files to update
2017-07-24 13:17:07,199 [ 254336] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:07,217 [ 254354] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/projects/chess2; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' W:/git/projects/chess2/bin/bundle check within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:18,421 [ 265558] INFO - .diagnostic.PerformanceWatcher - Unindexed files update took 11433ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:17:18,454 [ 265591] INFO - ctView.v2.RailsProjectViewPane - module updated (Module: 'chess2')
2017-07-24 13:17:32,728 [ 279865] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:32,740 [ 279877] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/gem --version within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:32,953 [ 280090] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:32,964 [ 280101] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rubymine_helpers/rb/gems; env "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "JETBRAINS_REMOTE_RUN"="1" "rvm_ruby_string"="ruby-2.2.2" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/gem install ruby-debug-ide-0.6.1.beta4.gem --no-rdoc --no-ri within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:33,675 [ 280812] INFO - lugins.ruby.gem.GemInstallUtil - Installed gem: C:\Program Files\JetBrains\RubyMine 2017.1.5\rb\gems\ruby-debug-ide-0.6.1.beta4.gem
2017-07-24 13:17:33,676 [ 280813] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:33,688 [ 280825] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/gem --version within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:33,899 [ 281036] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:33,911 [ 281048] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rubymine_helpers/rb/gems; env "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "JETBRAINS_REMOTE_RUN"="1" "rvm_ruby_string"="ruby-2.2.2" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/gem install debase-0.2.2.beta9.gem --no-rdoc --no-ri within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:36,421 [ 283558] INFO - lugins.ruby.gem.GemInstallUtil - Installed gem: C:\Program Files\JetBrains\RubyMine 2017.1.5\rb\gems\debase-0.2.2.beta9.gem
2017-07-24 13:17:36,421 [ 283558] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:17:36,422 [ 283559] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:17:46,917 [ 294054] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:17:46,918 [ 294055] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) updated
2017-07-24 13:17:46,958 [ 294095] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:17:46,968 [ 294105] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:47,004 [ 294141] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e "require 'socket'; puts (1..2).map{|i| TCPServer.open('127.0.0.1', 0)}.map{|server| port = server.addr[1]; server.close; port}" within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:47,121 [ 294258] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:47,132 [ 294269] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/git/projects/chess2; env "ANSICON"="" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "JETBRAINS_REMOTE_RUN"="1" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/gems/debase-0.2.2.beta9/lib:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/gems/ruby-debug-ide-0.6.1.beta4/lib" "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e 'at_exit{sleep(1)};$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/gems/ruby-debug-ide-0.6.1.beta4/bin/rdebug-ide --debug --disable-int-handler --evaluation-timeout 10 --rubymine-protocol-extensions --port 38307 --host 0.0.0.0 --dispatcher-port 45875 -- /home/philip.whittingham/git/projects/chess2/bin/rails server -b 0.0.0.0 -p 3011 -e development within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:47,152 [ 294289] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from remote server localhost:38307 to local localhost:49946]
2017-07-24 13:17:47,153 [ 294290] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from local server localhost:49947 to remote localhost:45875]
2017-07-24 13:17:47,154 [ 294291] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from remote server 0.0.0.0:3011 to local localhost:3011]
2017-07-24 13:17:47,518 [ 294655] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:17:47,529 [ 294666] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/projects/chess2; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' W:/git/projects/chess2/bin/bundle check within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:18:50,118 [ 357255] INFO - emote.RubyRemoteProcessHandler - processWillTerminate: removing port forwarding - Port forwarding[from remote server localhost:38307 to local localhost:49946]
2017-07-24 13:18:50,118 [ 357255] INFO - emote.RubyRemoteProcessHandler - processWillTerminate: removing port forwarding - Port forwarding[from remote server 0.0.0.0:3011 to local localhost:3011]
2017-07-24 13:19:02,746 [ 369883] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @6d0d5b96
2017-07-24 13:19:02,746 [ 369883] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @4cfb5a52
2017-07-24 13:19:02,763 [ 369900] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:19:02,773 [ 369910] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/projects/chess2; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/gems/bundler-1.15.3/lib:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYOPT"="-rbundler/setup" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" "BUNDLE_BIN_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/gems/bundler-1.15.3/bin" "BUNDLE_GEMFILE"="W:/git/projects/chess2/Gemfile" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby W:/git/projects/chess2/bin/spring stop within SSH session @42b83cda to philip.whittingham@192.168.1.4:22
2017-07-24 13:19:02,792 [ 369929] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @42cb1693
2017-07-24 13:19:02,793 [ 369930] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @7e70e33a
2017-07-24 13:19:02,793 [ 369930] INFO - s.webDeployment.PublishUtils$1 - Using "C:\Users\SynLogek\AppData\Local\Temp\vfs_cache" as temporary files store.
2017-07-24 13:19:02,811 [ 369948] INFO - stubs.SerializationManagerImpl - START StubSerializationManager SHUTDOWN
2017-07-24 13:19:02,811 [ 369948] INFO - stubs.SerializationManagerImpl - END StubSerializationManager SHUTDOWN
2017-07-24 13:19:02,815 [ 369952] INFO - org.jetbrains.io.BuiltInServer - web server stopped
2017-07-24 13:19:02,819 [ 369956] INFO - Types.impl.FileTypeManagerImpl - FileTypeManager: 505 auto-detected files
Elapsed time on auto-detect: 99 ms
2017-07-24 13:19:02,822 [ 369959] INFO - pl.local.NativeFileWatcherImpl - Watcher terminated with exit code 0
2017-07-24 13:19:02,838 [ 369975] INFO - il.indexing.FileBasedIndexImpl - START INDEX SHUTDOWN
2017-07-24 13:19:02,889 [ 370026] INFO - il.indexing.FileBasedIndexImpl - END INDEX SHUTDOWN
2017-07-24 13:19:02,891 [ 370028] INFO - newvfs.persistent.PersistentFS - VFS dispose started
2017-07-24 13:19:02,893 [ 370030] INFO - newvfs.persistent.PersistentFS - VFS dispose completed
2017-07-24 13:19:02,895 [ 370032] INFO - #com.intellij.idea.Main - ------------------------------------------------------ IDE SHUTDOWN ------------------------------------------------------
2017-07-24 13:19:02,895 [ 370032] INFO - org.jetbrains.io.BuiltInServer - web server stopped

0
Comment actions Permalink

NOT WORKING LOGS

2017-07-24 13:29:17,865 [ 0] INFO - #com.intellij.idea.Main - ------------------------------------------------------ IDE STARTED ------------------------------------------------------
2017-07-24 13:29:17,875 [ 10] INFO - #com.intellij.util.ui.JBUI - User scale factor: 1.0
2017-07-24 13:29:17,875 [ 10] INFO - #com.intellij.util.ui.JBUI - System scale factor: 1.0 (IDE-managed HiDPI)
2017-07-24 13:29:17,881 [ 16] INFO - #com.intellij.idea.Main - IDE: RubyMine (build #RM-171.4694.62, 27 Jun 2017 19:17)
2017-07-24 13:29:17,881 [ 16] INFO - #com.intellij.idea.Main - OS: Windows 7 (6.1, amd64)
2017-07-24 13:29:17,881 [ 16] INFO - #com.intellij.idea.Main - JRE: 1.8.0_112-release-736-b21 (JetBrains s.r.o)
2017-07-24 13:29:17,882 [ 17] INFO - #com.intellij.idea.Main - JVM: 25.112-b21 (OpenJDK 64-Bit Server VM)
2017-07-24 13:29:17,883 [ 18] INFO - #com.intellij.idea.Main - JVM Args: -Xms128m -Xmx750m -XX:ReservedCodeCacheSize=240m -XX:+UseConcMarkSweepGC -XX:SoftRefLRUPolicyMSPerMB=50 -ea -Dsun.io.useCanonCaches=false -Djava.net.preferIPv4Stack=true -XX:+HeapDumpOnOutOfMemoryError -XX:-OmitStackTraceInFastThrow -Djb.vmOptionsFile=C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\rubymine64.exe.vmoptions -Xbootclasspath/a:C:\Program Files\JetBrains\RubyMine 2017.1.5\lib\boot.jar -Didea.platform.prefix=Ruby -Didea.paths.selector=RubyMine2017.1 -XX:ErrorFile=C:\Users\SynLogek\java_error_in_rubymine_%p.log -XX:HeapDumpPath=C:\Users\SynLogek\java_error_in_rubymine.hprof
2017-07-24 13:29:17,883 [ 18] INFO - #com.intellij.idea.Main - ext: C:\Program Files\JetBrains\RubyMine 2017.1.5\jre64\lib\ext: [access-bridge-64.jar, cldrdata.jar, dnsns.jar, jaccess.jar, jfxrt.jar, localedata.jar, meta-index, nashorn.jar, sunec.jar, sunjce_provider.jar, sunmscapi.jar, sunpkcs11.jar, zipfs.jar]
2017-07-24 13:29:17,883 [ 18] INFO - #com.intellij.idea.Main - JNU charset: Cp1252
2017-07-24 13:29:17,897 [ 32] INFO - #com.intellij.idea.Main - JNA library (64-bit) loaded in 13 ms
2017-07-24 13:29:17,899 [ 34] INFO - penapi.util.io.win32.IdeaWin32 - Native filesystem for Windows is operational
2017-07-24 13:29:17,899 [ 34] INFO - #com.intellij.idea.Main - Using "FocusKiller" library to prevent focus stealing.
2017-07-24 13:29:19,756 [ 1891] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor js-nashorn-support.xml
2017-07-24 13:29:19,764 [ 1899] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor plugin-intelliLang.xml
2017-07-24 13:29:19,825 [ 1960] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor uml-properties-support.xml
2017-07-24 13:29:19,826 [ 1961] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor uml-java-support.xml
2017-07-24 13:29:19,953 [ 2088] INFO - llij.ide.plugins.PluginManager - Plugin 'Ruby Docker' can't be loaded because: Plugin is disabled
2017-07-24 13:29:20,021 [ 2156] INFO - llij.ide.plugins.PluginManager - 50 plugins initialized in 412 ms
2017-07-24 13:29:20,023 [ 2158] INFO - llij.ide.plugins.PluginManager - Loaded bundled plugins: CSS Support (171.4694.62), CVS Integration (11), CoffeeScript (2.0), Database Tools and SQL (1.0), Docker integration (2.5.3.1), EditorConfig (171.4694.62), Gherkin (999.999), Git Integration (8.1), GitHub (171.4694.62), HTML Tools (2.0), Haml (171.4694.62), IDEA CORE (171.4694.62), IntelliLang (8.0), JavaScript Debugger (1.0), JavaScript Intention Power Pack (0.9.4), JavaScript Support (1.0), Less support (171.4694.62), Markdown support (VERSION), Perforce Integration (2.0), Puppet Support (VERSION), REST Client (171.4694.62), Remote Hosts Access (0.1), Ruby CSS Support Integration (171.4694.62), Ruby Code Coverage (171.4694.62), Ruby Cucumber support (171.4694.62), Ruby Database Support Integration (171.4694.62), Ruby Haml Support Integration (171.4694.62), Ruby I18n Support Integration (171.4694.62), Ruby JavaScript Debugger Integration (171.4694.62), Ruby Puppet Support Integration (171.4694.62), Ruby Remote SDK Support (0.1), Ruby Slim Support Integration (171.4694.62), Ruby UML Support Integration (171.4694.62), RubyMotion support (171.4694.62), SSH Remote Run (0.1), Sass support (171.4694.62), Settings Repository (171.4694.62), Slim (VERSION), Stylus support (999.999), Subversion Integration (1.1), Task Management (1.0), Terminal (0.1), TextMate bundles support (VERSION), Time Tracking (1.0), UML Support (1.0), Vagrant (171.4694.62), YAML (171.4694.62), hg4idea (10.0), tslint (171.4694.62)
2017-07-24 13:29:20,023 [ 2158] INFO - llij.ide.plugins.PluginManager - Disabled plugins: Ruby Docker (VERSION)
2017-07-24 13:29:20,293 [ 2428] INFO - ellij.util.io.PagedFileStorage - lower=100; upper=500; buffer=10; max=705
2017-07-24 13:29:20,317 [ 2452] INFO - pl.local.NativeFileWatcherImpl - Starting file watcher: C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\fsnotifier64.exe
2017-07-24 13:29:20,323 [ 2458] INFO - pl.local.NativeFileWatcherImpl - Native file watcher is operational.
2017-07-24 13:29:20,343 [ 2478] INFO - pi.util.registry.RegistryState - Registry values changed by user:
2017-07-24 13:29:20,343 [ 2478] INFO - pi.util.registry.RegistryState - dumb.aware.run.configurations = true
2017-07-24 13:29:20,630 [ 2765] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=0.0; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:20,911 [ 3046] INFO - til.net.ssl.CertificateManager - Default SSL context initialized
2017-07-24 13:29:20,921 [ 3056] INFO - figurations.GeneralCommandLine - Cannot run program "C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\java.exe": CreateProcess error=2, The system cannot find the file specified
java.io.IOException: Cannot run program "C:\Program Files\JetBrains\RubyMine 2017.1.5\bin\java.exe": CreateProcess error=2, The system cannot find the file specified
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1048)
at com.intellij.execution.configurations.GeneralCommandLine.startProcess(GeneralCommandLine.java:405)
at com.intellij.execution.configurations.GeneralCommandLine.createProcess(GeneralCommandLine.java:375)
at com.intellij.execution.process.OSProcessHandler.<init>(OSProcessHandler.java:45)
at com.intellij.execution.process.CapturingProcessHandler.<init>(CapturingProcessHandler.java:38)
at com.intellij.execution.util.ExecUtil.execAndGetOutput(ExecUtil.java:100)
at com.intellij.util.JdkBundle.a(JdkBundle.java:221)
at com.intellij.util.JdkBundle.createBundle(JdkBundle.java:92)
at com.intellij.util.JdkBundle.createBoot(JdkBundle.java:125)
at com.intellij.util.JdkBundle.createBoot(JdkBundle.java:113)
at com.intellij.ide.SystemHealthMonitor.a(SystemHealthMonitor.java:86)
at com.intellij.ide.SystemHealthMonitor.initComponent(SystemHealthMonitor.java:75)
at com.intellij.openapi.components.impl.ComponentManagerImpl$ComponentConfigComponentAdapter.getComponentInstance(ComponentManagerImpl.java:491)
at com.intellij.openapi.components.impl.ComponentManagerImpl.createComponents(ComponentManagerImpl.java:120)
at com.intellij.openapi.application.impl.ApplicationImpl.a(ApplicationImpl.java:466)
at com.intellij.openapi.progress.impl.CoreProgressManager.a(CoreProgressManager.java:176)
at com.intellij.openapi.progress.impl.CoreProgressManager.a(CoreProgressManager.java:556)
at com.intellij.openapi.progress.impl.CoreProgressManager.executeProcessUnderProgress(CoreProgressManager.java:501)
at com.intellij.openapi.progress.impl.ProgressManagerImpl.executeProcessUnderProgress(ProgressManagerImpl.java:66)
at com.intellij.openapi.progress.impl.CoreProgressManager.runProcess(CoreProgressManager.java:163)
at com.intellij.openapi.application.impl.ApplicationImpl.createComponents(ApplicationImpl.java:473)
at com.intellij.openapi.components.impl.ComponentManagerImpl.init(ComponentManagerImpl.java:104)
at com.intellij.openapi.application.impl.ApplicationImpl.load(ApplicationImpl.java:425)
at com.intellij.openapi.application.impl.ApplicationImpl.load(ApplicationImpl.java:411)
at com.intellij.idea.IdeaApplication.run(IdeaApplication.java:203)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.intellij.idea.MainImpl$1$1.a(MainImpl.java:63)
at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311)
at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:762)
at java.awt.EventQueue.access$500(EventQueue.java:98)
at java.awt.EventQueue$3.run(EventQueue.java:715)
at java.awt.EventQueue$3.run(EventQueue.java:709)
at java.security.AccessController.doPrivileged(Native Method)
at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:80)
at java.awt.EventQueue.dispatchEvent(EventQueue.java:732)
at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:343)
at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:201)
at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)
Caused by: java.io.IOException: CreateProcess error=2, The system cannot find the file specified
at java.lang.ProcessImpl.create(Native Method)
at java.lang.ProcessImpl.<init>(ProcessImpl.java:386)
at java.lang.ProcessImpl.start(ProcessImpl.java:137)
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
... 44 more
2017-07-24 13:29:20,943 [ 3078] INFO - rains.ide.BuiltInServerManager - built-in server started, port 63342
2017-07-24 13:29:20,967 [ 3102] INFO - gs.impl.UpdateCheckerComponent - channel: release
2017-07-24 13:29:21,052 [ 3187] INFO - il.indexing.FileBasedIndexImpl - Index exts enumerated:22
2017-07-24 13:29:21,054 [ 3189] INFO - il.indexing.FileBasedIndexImpl - Index scheduled:2
2017-07-24 13:29:21,072 [ 3207] INFO - tellij.psi.stubs.StubIndexImpl - All stub exts enumerated:13
2017-07-24 13:29:21,073 [ 3208] INFO - tellij.psi.stubs.StubIndexImpl - stub exts update scheduled:0
2017-07-24 13:29:21,096 [ 3231] INFO - j.ide.script.IdeStartupScripts - 0 startup script(s) found
2017-07-24 13:29:21,450 [ 3585] INFO - pl$FileIndexDataInitialization - Initialization done:395
2017-07-24 13:29:21,530 [ 3665] INFO - plication.impl.ApplicationImpl - 82 application components initialized in 1924ms
2017-07-24 13:29:21,534 [ 3669] INFO - .intellij.idea.IdeaApplication - App initialization took 4140 ms
2017-07-24 13:29:21,590 [ 3725] INFO - exImpl$StubIndexInitialization - Initialization done:139
2017-07-24 13:29:22,402 [ 4537] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,407 [ 4542] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,411 [ 4546] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,415 [ 4550] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,419 [ 4554] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,423 [ 4558] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,427 [ 4562] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,445 [ 4580] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-07-24 13:29:22,580 [ 4715] INFO - .openapi.application.Preloader - Finished preloading com.intellij.openapi.actionSystem.impl.ActionPreloader@747b0cab
2017-07-24 13:29:22,963 [ 5098] INFO - management.pik.PikSdkRefresher - Refreshing sdks
2017-07-24 13:29:22,963 [ 5098] INFO - management.pik.PikSdkRefresher - rubies folder doesn't exist
2017-07-24 13:29:22,968 [ 5103] INFO - gement.rbenv.RbenvSdkRefresher - Refreshing sdks
2017-07-24 13:29:22,968 [ 5103] INFO - gement.rbenv.RbenvSdkRefresher - rbenv isn't supported by OS
2017-07-24 13:29:22,984 [ 5119] INFO - .openapi.application.Preloader - Finished preloading com.intellij.ide.ui.search.SearchableOptionPreloader@76c90b35
2017-07-24 13:29:23,133 [ 5268] INFO - ellij.project.impl.ProjectImpl - 107 project components initialized in 1072 ms
2017-07-24 13:29:23,215 [ 5350] INFO - .openapi.application.Preloader - Finished preloading com.intellij.codeInsight.completion.CompletionPreloader@50b03bf8
2017-07-24 13:29:23,506 [ 5641] INFO - le.impl.ModuleManagerComponent - 1 module(s) loaded in 372 ms
2017-07-24 13:29:23,619 [ 5754] WARN - om.intellij.util.ProfilingUtil - Profiling agent is not enabled. Add -agentlib:yjpagent to idea.vmoptions if necessary to profile IDEA.
2017-07-24 13:29:24,448 [ 6583] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 520ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:29:24,654 [ 6789] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 204ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:29:25,259 [ 7394] INFO - tartup.impl.StartupManagerImpl - OpenFilesActivity run in 1413
2017-07-24 13:29:25,981 [ 8116] INFO - tartup.impl.StartupManagerImpl - W:/git/scenarios/sn/.idea case-sensitivity: expected=false actual=false
2017-07-24 13:29:25,982 [ 8117] WARN - api.vfs.impl.local.FileWatcher - Project files cannot be watched (are they under network mount?)
2017-07-24 13:29:25,982 [ 8117] INFO - tartup.impl.StartupManagerImpl - unwatched roots: [W:/git/scenarios/sn]
2017-07-24 13:29:25,982 [ 8117] INFO - tartup.impl.StartupManagerImpl - manual watches: [W:\]
2017-07-24 13:29:26,007 [ 8142] INFO - byRemoteInterpreterManagerImpl - Uploading helpers for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) at Thread[ApplicationImpl pooled thread 2,4,main]
2017-07-24 13:29:26,025 [ 8160] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:29:26,027 [ 8162] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:29:26,124 [ 8259] INFO - ellij.project.impl.ProjectImpl - 15 project components initialized in 30 ms
2017-07-24 13:29:26,161 [ 8296] INFO - s.webDeployment.PublishUtils$1 - Using "C:\Users\SynLogek\AppData\Local\Temp\vfs_cache" as temporary files store.
2017-07-24 13:29:26,858 [ 8993] INFO - tor.impl.FileEditorManagerImpl - Project opening took 4969 ms
2017-07-24 13:29:26,911 [ 9046] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:29:26,911 [ 9046] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:29:26,912 [ 9047] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:29:26,912 [ 9047] INFO - byRemoteInterpreterManagerImpl - Helpers uploaded for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) at Thread[ApplicationImpl pooled thread 2,4,main]
2017-07-24 13:29:26,912 [ 9047] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:29:26,913 [ 9048] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:29:35,906 [ 18041] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:29:35,906 [ 18041] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby) updated
2017-07-24 13:29:35,922 [ 18057] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:29:35,931 [ 18066] INFO - e.GemRequirementsChangeWatcher - Initial scan started
2017-07-24 13:29:36,066 [ 18201] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:29:36,235 [ 18370] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/scenarios/sn; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin/bundle check within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:29:37,447 [ 19582] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:29:37,520 [ 19655] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:30:13,916 [ 56051] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @2c695bc
2017-07-24 13:30:17,217 [ 59352] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,238 [ 59373] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,250 [ 59385] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -v within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,365 [ 59500] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,376 [ 59511] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -v within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,490 [ 59625] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,502 [ 59637] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -e "print(defined?(RUBY_PATCHLEVEL) ? RUBY_PATCHLEVEL : 0)" within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:30:17,622 [ 59757] INFO - yCreateRemoteInterpreterDialog - initializing Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby)
2017-07-24 13:30:17,622 [ 59757] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:30:17,622 [ 59757] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:30:17,870 [ 60005] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:30:17,870 [ 60005] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:30:17,871 [ 60006] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:30:17,871 [ 60006] INFO - yCreateRemoteInterpreterDialog - localGemsBinPath=C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1112774085\1134787926, remoteGemsBinPath=/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\bin, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/gems
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\specifications, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/specifications
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\bundler, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bundler
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\746630481\bin, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\746630481\gems, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/gems
2017-07-24 13:30:17,874 [ 60009] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\746630481\specifications, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/specifications
2017-07-24 13:30:17,875 [ 60010] INFO - yCreateRemoteInterpreterDialog - localPath=C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\746630481\bundler, remotePath=/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bundler
2017-07-24 13:30:17,875 [ 60010] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:30:28,896 [ 71031] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems\ruby-oci8-2.1.8\ext\oci8\oci8lib_220.so is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:29,117 [ 71252] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems\ruby-oci8-2.1.8\lib\oci8lib_220.so is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:45,913 [ 88048] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems\capybara-webkit-1.1.1\src\build\webkit_server.gch\c++ is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:49,809 [ 91944] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems\therubyracer-0.12.1\ext\v8\init.so is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:50,016 [ 92151] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems\therubyracer-0.12.1\lib\v8\init.so is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:50,404 [ 92539] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\gems\libv8-3.16.14.7-x86_64-linux\vendor\v8\out\x64.release\obj.target\tools\gyp\libv8_base.a is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:52,732 [ 94867] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\bundler\gems\oracle-enhanced-dd9b5ca84ddc\.git\objects\pack\pack-9b8ffb6493bbcb00cc5370ca23c19b3452f0a716.pack is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:53,364 [ 95499] WARN - ebDeployment.TransferOperation - File C:\Users\SynLogek\.RubyMine2017.1\system\ruby_stubs\1112774085\543555678\bundler\gems\axlsx-7ff47a1d277f\.git\objects\pack\pack-f363b117326e3bdb8bcd78579fa541f41e82f926.pack is larger than 1048576 bytes, its base revision will not be stored
2017-07-24 13:30:53,986 [ 96121] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:30:53,994 [ 96129] INFO - yCreateRemoteInterpreterDialog - Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) initialized
2017-07-24 13:30:54,014 [ 96149] INFO - byRemoteInterpreterManagerImpl - Uploading helpers for Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) at Thread[ApplicationImpl pooled thread 83,4,main]
2017-07-24 13:30:54,014 [ 96149] INFO - ruby.remote.RubyRemoteTransfer - Uploading helpers
2017-07-24 13:30:54,014 [ 96149] INFO - ugins.remotesdk.RemoteTransfer - Uploading helpers to /home/philip.whittingham/.rubymine_helpers/rb
2017-07-24 13:30:54,042 [ 96177] INFO - ugins.remotesdk.RemoteTransfer - Remote helpers version is RM-171.4694.62, local helpers version is RM-171.4694.62
2017-07-24 13:30:54,042 [ 96177] INFO - ugins.remotesdk.RemoteTransfer - Helpers upload successfully completed
2017-07-24 13:30:54,042 [ 96177] INFO - ruby.remote.RubyRemoteTransfer - Helpers uploaded
2017-07-24 13:30:54,042 [ 96177] INFO - byRemoteInterpreterManagerImpl - Helpers uploaded for Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) at Thread[ApplicationImpl pooled thread 83,4,main]
2017-07-24 13:30:54,042 [ 96177] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby)
2017-07-24 13:30:54,042 [ 96177] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:30:54,047 [ 96182] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:31:01,693 [ 103828] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:31:01,697 [ 103832] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:31:01,701 [ 103836] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:31:01,705 [ 103840] WARN - brains.plugins.ruby.gem.GemBox - Unable to find lib directory C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1898828105\1131093842/../lib/ruby/2.2.0for Remote-rvm:ruby-2.2.2-p95 [chess2]: ver.2.2.2p95 ( revision 50295) p95 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby)
2017-07-24 13:31:01,708 [ 103843] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) updated
2017-07-24 13:31:02,751 [ 104886] INFO - management.rvm.RvmSdkRefresher - It looks like rvm config file (C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1112774085/543555678/gems/docile-1.1.2/.ruby-gemset) has been changed (or created), let's update sdk for the project (Project 'W:\git\scenarios\sn' sn)
2017-07-24 13:31:02,752 [ 104887] INFO - gement.rbenv.RbenvSdkRefresher - It looks like .ruby-version (C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1112774085/543555678/gems/docile-1.1.2/.ruby-version) has been changed (or created), let's update sdk for the project (Project 'W:\git\scenarios\sn' sn)
2017-07-24 13:31:02,752 [ 104887] INFO - management.rvm.RvmSdkRefresher - It looks like rvm config file (C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1112774085/543555678/gems/docile-1.1.2/.ruby-version) has been changed (or created), let's update sdk for the project (Project 'W:\git\scenarios\sn' sn)
2017-07-24 13:31:02,783 [ 104918] INFO - gement.rbenv.RbenvSdkRefresher - It looks like .ruby-version (C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1112774085/543555678/gems/shoulda-context-1.1.6/.ruby-version) has been changed (or created), let's update sdk for the project (Project 'W:\git\scenarios\sn' sn)
2017-07-24 13:31:02,783 [ 104918] INFO - management.rvm.RvmSdkRefresher - It looks like rvm config file (C:/Users/SynLogek/.RubyMine2017.1/system/ruby_stubs/1112774085/543555678/gems/shoulda-context-1.1.6/.ruby-version) has been changed (or created), let's update sdk for the project (Project 'W:\git\scenarios\sn' sn)
2017-07-24 13:31:22,742 [ 124877] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:31:22,778 [ 124913] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:31:22,806 [ 124941] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:22,814 [ 124949] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 19ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:31:22,824 [ 124959] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/git/scenarios/sn; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby /home/philip.whittingham/git/scenarios/sn/script/rails generate within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:22,977 [ 125112] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 163ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:31:22,977 [ 125112] INFO - indexing.UnindexedFilesUpdater - Unindexed files update started: 820 files to update
2017-07-24 13:31:24,621 [ 126756] INFO - e.GemRequirementsChangeWatcher - Initial scan took: 108690 ms
2017-07-24 13:31:24,730 [ 126865] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:31:24,778 [ 126913] INFO - pl.ProjectRootManagerComponent - project roots have changed
2017-07-24 13:31:24,796 [ 126931] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:24,799 [ 126934] INFO - indexing.UnindexedFilesUpdater - Unindexed files update canceled
2017-07-24 13:31:24,809 [ 126944] INFO - transport.SshConnectionService - Executing SSH command: cd W:/git/scenarios/sn; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="chess2" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.2@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.2" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.2" /home/philip.whittingham/.rvm/rubies/ruby-2.2.2/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.2@chess2/bin/bundle check within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:24,815 [ 126950] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 14ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:31:24,924 [ 127059] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 108ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:31:24,924 [ 127059] INFO - indexing.UnindexedFilesUpdater - Unindexed files update started: 677 files to update
2017-07-24 13:31:25,612 [ 127747] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:25,643 [ 127778] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/git/scenarios/sn; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin/bundle check within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:27,924 [ 130059] INFO - .diagnostic.PerformanceWatcher - Unindexed files update took 3000ms; general responsiveness: ok; EDT responsiveness: ok
2017-07-24 13:31:29,574 [ 131709] WARN - ions.generators.GeneratorsUtil - Get available generators script executes with errors:
/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/gems/htmlentities-4.3.2/lib/htmlentities/mappings/expanded.rb:465: warning: duplicated key at line 466 ignored: "inodot"
Warning: NLS_LANG is not set. fallback to US7ASCII.
Expected string default value for '--helper'; got true (boolean)
Expected boolean default value for '--markerb'; got :erb (string)
Expected string default value for '--helper'; got true (boolean)
Expected string default value for '--assets'; got true (boolean)

2017-07-24 13:31:29,836 [ 131971] INFO - ctView.v2.RailsProjectViewPane - module updated (Module: 'sn')
2017-07-24 13:31:46,660 [ 148795] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:46,671 [ 148806] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/gem --version within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:46,884 [ 149019] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:46,895 [ 149030] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rubymine_helpers/rb/gems; env "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "JETBRAINS_REMOTE_RUN"="1" "rvm_ruby_string"="ruby-2.2.6" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/gem install ruby-debug-ide-0.6.1.beta4.gem --no-rdoc --no-ri within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:47,807 [ 149942] INFO - lugins.ruby.gem.GemInstallUtil - Installed gem: C:\Program Files\JetBrains\RubyMine 2017.1.5\rb\gems\ruby-debug-ide-0.6.1.beta4.gem
2017-07-24 13:31:47,808 [ 149943] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:47,819 [ 149954] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/gem --version within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:48,030 [ 150165] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:48,041 [ 150176] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/.rubymine_helpers/rb/gems; env "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "JETBRAINS_REMOTE_RUN"="1" "rvm_ruby_string"="ruby-2.2.6" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -x /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/gem install debase-0.2.2.beta9.gem --no-rdoc --no-ri within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:31:50,951 [ 153086] INFO - lugins.ruby.gem.GemInstallUtil - Installed gem: C:\Program Files\JetBrains\RubyMine 2017.1.5\rb\gems\debase-0.2.2.beta9.gem
2017-07-24 13:31:50,951 [ 153086] INFO - byRemoteInterpreterManagerImpl - Updating gems for Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby)
2017-07-24 13:31:50,952 [ 153087] INFO - ruby.remote.RubyRemoteTransfer - Updating gems' local copy
2017-07-24 13:32:04,439 [ 166574] INFO - ruby.remote.RubyRemoteTransfer - Gems copied.
2017-07-24 13:32:04,439 [ 166574] INFO - byRemoteInterpreterManagerImpl - Gems for Remote-rvm:ruby-2.2.6-p396 [scenarios3]: ver.2.2.6p396 ( revision 56800) p396 (ssh://philip.whittingham@192.168.1.4:22/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby) updated
2017-07-24 13:32:05,537 [ 167672] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:05,566 [ 167701] INFO - transport.SshConnectionService - Executing SSH command: env "JETBRAINS_REMOTE_RUN"="1" "RM_INFO"="RM-171.4694.62" "ANSICON"="" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -e "require 'socket'; puts (1..2).map{|i| TCPServer.open('127.0.0.1', 0)}.map{|server| port = server.addr[1]; server.close; port}" within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:05,697 [ 167832] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:05,723 [ 167858] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/git/scenarios/sn; env "ANSICON"="" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "JETBRAINS_REMOTE_RUN"="1" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/gems/debase-0.2.2.beta9/lib:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/gems/ruby-debug-ide-0.6.1.beta4/lib" "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -e 'at_exit{sleep(1)};$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/gems/ruby-debug-ide-0.6.1.beta4/bin/rdebug-ide --debug --disable-int-handler --evaluation-timeout 10 --rubymine-protocol-extensions --port 34837 --host 0.0.0.0 --dispatcher-port 45494 -- /home/philip.whittingham/git/scenarios/sn/script/rails server -b 192.168.1.4 -p 3001 -e development within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:05,755 [ 167890] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from remote server localhost:34837 to local localhost:50459]
2017-07-24 13:32:05,757 [ 167892] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from local server localhost:50460 to remote localhost:45494]
2017-07-24 13:32:05,758 [ 167893] INFO - emote.RubyRemoteProcessHandler - Adding new tunnel - Port forwarding[from remote server 192.168.1.4:3001 to local localhost:3001]
2017-07-24 13:32:06,490 [ 168625] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:06,550 [ 168685] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/git/scenarios/sn; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin/bundle check within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:07,406 [ 169541] INFO - transport.SshConnectionService - Creating sftp channel within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:07,547 [ 169682] INFO - transport.SshConnectionService - Executing SSH command: cd /home/philip.whittingham/git/scenarios/sn; env "GEM_HOME"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3" "IRBRC"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/.irbrc" "RM_INFO"="RM-171.4694.62" "gemset"="scenarios3" "TEAMCITY_RAKE_RUNNER_USED_FRAMEWORKS"=":test_unit :shoulda " "JETBRAINS_REMOTE_RUN"="1" "ANSICON"="" "GEM_PATH"="/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global" "PATH"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin:/home/philip.whittingham/.rvm/gems/ruby-2.2.6@global/bin:/home/philip.whittingham/.rvm/bin:$PATH" "RUBYMINE_TESTUNIT_REPORTER"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "RUBYLIB"="/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/common:/home/philip.whittingham/.rubymine_helpers/rb/testing/patch/testunit" "rvm_ruby_string"="ruby-2.2.6" "TEAMCITY_RAKE_RUNNER_MODE"="idea" "MY_RUBY_HOME"="/home/philip.whittingham/.rvm/rubies/ruby-2.2.6" /home/philip.whittingham/.rvm/rubies/ruby-2.2.6/bin/ruby -e '$stdout.sync=true;$stderr.sync=true;load($0=ARGV.shift)' /home/philip.whittingham/.rvm/gems/ruby-2.2.6@scenarios3/bin/bundle check within SSH session @382453ff to philip.whittingham@192.168.1.4:22
2017-07-24 13:32:26,591 [ 188726] INFO - emote.RubyRemoteProcessHandler - processWillTerminate: removing port forwarding - Port forwarding[from remote server localhost:34837 to local localhost:50459]
2017-07-24 13:32:26,591 [ 188726] INFO - emote.RubyRemoteProcessHandler - processWillTerminate: removing port forwarding - Port forwarding[from remote server 192.168.1.4:3001 to local localhost:3001]
2017-07-24 13:32:32,491 [ 194626] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @44bc62d3
2017-07-24 13:32:32,491 [ 194626] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @516bdd0b
2017-07-24 13:32:32,532 [ 194667] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @213b12c1
2017-07-24 13:32:32,532 [ 194667] WARN - t.connections.RemoteConnection - Forced closing file system of sftp://192.168.1.4:22/ in @480a0753
2017-07-24 13:32:32,533 [ 194668] INFO - s.webDeployment.PublishUtils$1 - Using "C:\Users\SynLogek\AppData\Local\Temp\vfs_cache" as temporary files store.
2017-07-24 13:32:32,548 [ 194683] INFO - stubs.SerializationManagerImpl - START StubSerializationManager SHUTDOWN
2017-07-24 13:32:32,549 [ 194684] INFO - stubs.SerializationManagerImpl - END StubSerializationManager SHUTDOWN
2017-07-24 13:32:32,550 [ 194685] INFO - org.jetbrains.io.BuiltInServer - web server stopped
2017-07-24 13:32:32,563 [ 194698] INFO - Types.impl.FileTypeManagerImpl - FileTypeManager: 971 auto-detected files
Elapsed time on auto-detect: 192 ms
2017-07-24 13:32:32,576 [ 194711] INFO - pl.local.NativeFileWatcherImpl - Watcher terminated with exit code 0
2017-07-24 13:32:32,592 [ 194727] INFO - il.indexing.FileBasedIndexImpl - START INDEX SHUTDOWN
2017-07-24 13:32:32,818 [ 194953] INFO - il.indexing.FileBasedIndexImpl - END INDEX SHUTDOWN
2017-07-24 13:32:32,820 [ 194955] INFO - newvfs.persistent.PersistentFS - VFS dispose started
2017-07-24 13:32:32,823 [ 194958] INFO - newvfs.persistent.PersistentFS - VFS dispose completed
2017-07-24 13:32:32,825 [ 194960] INFO - #com.intellij.idea.Main - ------------------------------------------------------ IDE SHUTDOWN ------------------------------------------------------
2017-07-24 13:32:32,827 [ 194962] INFO - org.jetbrains.io.BuiltInServer - web server stopped

0
Comment actions Permalink

I haven't had a chance to try that other method of debugging yet. If I get a chance later I'll give it a try.

0

Please sign in to leave a comment.