PHPUnit Instantiating Test slow

Hi,

In the last two day I've upgrade PHPStorm to 2017.2.1 Build #PS-172.3544.41 and my MacBook Pro to MacOs Sierra. 

PHPUnit Tests now hang several second on the "Instantiating Test" phase and also it become quite slow. Ex. the entire tests suite (74 tests with 127 assertion) using the Terminal run in 4.29 seconds while on PhpStorm run in 15.58 seconds.

Any idea how to improve those performance?

Guido Bertolino

12 comments
Comment actions Permalink

You might check the timing in idea.log file (Help > Show log in ...)  or share the log with us to take a look

0
Comment actions Permalink

This is the log of the last opening. I've run the suite test but seems there isn't in the log:

------------------------------------------------------ IDE STARTED ------------------------------------------------------
2017-08-05 09:26:32,193 [ 14] INFO - #com.intellij.util.ui.JBUI - User scale factor: 1.0
2017-08-05 09:26:32,193 [ 14] INFO - #com.intellij.util.ui.JBUI - System scale factor: 2.0 (JRE-managed HiDPI)
2017-08-05 09:26:32,203 [ 24] INFO - #com.intellij.idea.Main - IDE: PhpStorm (build #PS-172.3544.41, 02 Aug 2017 09:32)
2017-08-05 09:26:32,203 [ 24] INFO - #com.intellij.idea.Main - OS: Mac OS X (10.12.6, x86_64)
2017-08-05 09:26:32,203 [ 24] INFO - #com.intellij.idea.Main - JRE: 1.8.0_152-release-915-b6 (JetBrains s.r.o)
2017-08-05 09:26:32,203 [ 24] INFO - #com.intellij.idea.Main - JVM: 25.152-b6 (OpenJDK 64-Bit Server VM)
2017-08-05 09:26:32,215 [ 36] INFO - #com.intellij.idea.Main - JVM Args: -Xms128m -Xmx750m -XX:ReservedCodeCacheSize=240m -XX:+UseCompressedOops -Dfile.encoding=UTF-8 -XX:+UseConcMarkSweepGC -XX:SoftRefLRUPolicyMSPerMB=50 -ea -Dsun.io.useCanonCaches=false -Djava.net.preferIPv4Stack=true -XX:+HeapDumpOnOutOfMemoryError -XX:-OmitStackTraceInFastThrow -Xverify:none -XX:ErrorFile=/Users/bertog/java_error_in_phpstorm_%p.log -XX:HeapDumpPath=/Users/bertog/java_error_in_phpstorm.hprof -Xbootclasspath/a:../lib/boot.jar -Djb.vmOptionsFile=/Applications/PhpStorm.app/Contents/bin/phpstorm.vmoptions -Didea.java.redist=jdk-bundled -Didea.home.path=/Applications/PhpStorm.app/Contents -Didea.executable=phpstorm -Didea.platform.prefix=PhpStorm -Didea.paths.selector=PhpStorm2017.2
2017-08-05 09:26:32,215 [ 36] INFO - #com.intellij.idea.Main - ext: /Applications/PhpStorm.app/Contents/jdk/Contents/Home/jre/lib/ext: [cldrdata.jar, dnsns.jar, jaccess.jar, jfxrt.jar, localedata.jar, meta-index, nashorn.jar, sunec.jar, sunjce_provider.jar, sunpkcs11.jar, zipfs.jar]
2017-08-05 09:26:32,215 [ 36] INFO - #com.intellij.idea.Main - ext: /System/Library/Java/Extensions: [MRJToolkit.jar]
2017-08-05 09:26:32,215 [ 36] INFO - #com.intellij.idea.Main - JNU charset: UTF-8
2017-08-05 09:26:32,233 [ 54] INFO - #com.intellij.idea.Main - JNA library (64-bit) loaded in 18 ms
2017-08-05 09:26:32,255 [ 76] INFO - #com.intellij.idea.Main - initializing environment
2017-08-05 09:26:32,256 [ 77] INFO - .intellij.util.EnvironmentUtil - loading shell env: /bin/zsh -l -i -c '/Applications/PhpStorm.app/Contents/bin/printenv.py' '/private/var/folders/l1/bvp8__cs6tq84rvs2tqjb2180000gn/T/intellij-shell-env.tmp'
2017-08-05 09:26:33,926 [ 1747] INFO - .intellij.util.EnvironmentUtil - shell environment loaded (19 vars)
2017-08-05 09:26:35,396 [ 3217] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor js-nashorn-support.xml
2017-08-05 09:26:35,623 [ 3444] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor uml-java-support.xml
2017-08-05 09:26:35,624 [ 3445] INFO - llij.ide.plugins.PluginManager - Cannot find optional descriptor uml-properties-support.xml
2017-08-05 09:26:36,124 [ 3945] INFO - llij.ide.plugins.PluginManager - 68 plugins initialized in 1170 ms
2017-08-05 09:26:36,126 [ 3947] INFO - llij.ide.plugins.PluginManager - Loaded bundled plugins: ASP (0.1), AngularJS (172.3544.41), Apache config (.htaccess) support (172.3544.41), Behat Support (172.3544.41), Blade Support (172.3544.41), CSS Support (172.3544.41), CVS Integration (11), Codeception Framework (172.3544.41), CoffeeScript (2.0), Command Line Tool Support (172.3544.41), Copyright (8.1), Database Tools and SQL (172.3544.41), Docker integration (3.0.1), Drupal Support (172.3544.41), File Watchers (172.3544.41), GNU GetText files support (*.po) (172.3544.41), Gherkin (999.999), Git Integration (8.1), GitHub (172.3544.41), Google App Engine Support for PHP (172.3544.41), HTML Tools (2.0), Haml (172.3544.41), IDEA CORE (172.3544.41), Ini4Idea (172.3544.41), IntelliLang (8.0), JavaScript Debugger (1.0), JavaScript Intention Power Pack (0.9.4), JavaScript Support (1.0), Joomla! Support (172.3544.41), Less support (172.3544.41), Markdown support (172.3544.41), NodeJS (172.3544.41), PHP (172.3544.41), PHP Docker (172.3544.41), PHP Remote Interpreter (172.3544.41), PHPSpec BDD Framework (172.3544.41), Perforce Integration (2.0), Performance Testing (172.3544.41), Phing Support (172.3544.41), PhpStorm Workshop (172.3544.41), QuirksMode (172.3544.41), REST Client (172.3544.41), ReStructuredText Support (172.3544.41), Refactor-X (2.01), Remote Hosts Access (0.1), SSH Remote Run (0.1), Sass support (172.3544.41), Settings Repository (172.3544.41), Subversion Integration (1.1), Task Management (1.0), Terminal (0.1), TextMate bundles support (172.3544.41), Time Tracking (1.0), Twig Support (172.3544.41), UML Support (1.0), Vagrant (172.3544.41), W3C Validators (2.0), WordPress Support (172.3544.41), XPathView + XSLT Support (4), XSLT-Debugger (1.4), YAML (172.3544.41), hg4idea (10.0), tslint (172.3544.41)
2017-08-05 09:26:36,127 [ 3948] INFO - llij.ide.plugins.PluginManager - Loaded custom plugins: .ignore (2.0.4), IdeaVim (0.48), Laravel Plugin (0.14.2), Pomodoro-tm (0.52), Vue.js (172.3544.10)
2017-08-05 09:26:36,689 [ 4510] INFO - ellij.util.io.PagedFileStorage - lower=100; upper=500; buffer=10; max=705
2017-08-05 09:26:36,743 [ 4564] INFO - pl.local.NativeFileWatcherImpl - Starting file watcher: /Applications/PhpStorm.app/Contents/bin/fsnotifier
2017-08-05 09:26:36,757 [ 4578] INFO - pl.local.NativeFileWatcherImpl - Native file watcher is operational.
2017-08-05 09:26:36,860 [ 4681] INFO - pi.util.registry.RegistryState - Registry values changed by user:
2017-08-05 09:26:36,860 [ 4681] INFO - pi.util.registry.RegistryState - dumb.aware.run.configurations = true
2017-08-05 09:26:37,762 [ 5583] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=1.0; restored: fontSize=12, fontScale=1.0
2017-08-05 09:26:38,575 [ 6396] INFO - til.net.ssl.CertificateManager - Default SSL context initialized
2017-08-05 09:26:38,714 [ 6535] INFO - rains.ide.BuiltInServerManager - built-in server started, port 63342
2017-08-05 09:26:38,793 [ 6614] INFO - gs.impl.UpdateCheckerComponent - channel: eap
2017-08-05 09:26:39,009 [ 6830] INFO - il.indexing.FileBasedIndexImpl - Index exts enumerated:92
2017-08-05 09:26:39,014 [ 6835] INFO - il.indexing.FileBasedIndexImpl - Index scheduled:5
2017-08-05 09:26:39,081 [ 6902] INFO - tellij.psi.stubs.StubIndexImpl - All stub exts enumerated:53
2017-08-05 09:26:39,081 [ 6902] INFO - tellij.psi.stubs.StubIndexImpl - stub exts update scheduled:0
2017-08-05 09:26:39,148 [ 6969] INFO - j.ide.script.IdeStartupScripts - 0 startup script(s) found
2017-08-05 09:26:39,763 [ 7584] INFO - plication.impl.ApplicationImpl - 83 application components initialized in 4813ms
2017-08-05 09:26:39,796 [ 7617] INFO - .intellij.idea.IdeaApplication - App initialization took 8743 ms
2017-08-05 09:26:39,910 [ 7731] INFO - pl$FileIndexDataInitialization - Initialization done:896
2017-08-05 09:26:40,443 [ 8264] INFO - exImpl$StubIndexInitialization - Initialization done:532
2017-08-05 09:26:41,362 [ 9183] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=1.0; restored: fontSize=12, fontScale=1.0
2017-08-05 09:26:41,462 [ 9283] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-08-05 09:26:41,463 [ 9284] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=11, fontScale=null; restored: fontSize=11, fontScale=1.0
2017-08-05 09:26:41,466 [ 9287] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,471 [ 9292] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=14, fontScale=null; restored: fontSize=14, fontScale=1.0
2017-08-05 09:26:41,471 [ 9292] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,475 [ 9296] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=14, fontScale=null; restored: fontSize=14, fontScale=1.0
2017-08-05 09:26:41,475 [ 9296] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,478 [ 9299] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=14, fontScale=1.0; restored: fontSize=14, fontScale=1.0
2017-08-05 09:26:41,478 [ 9299] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=1.0; restored: fontSize=12, fontScale=1.0
2017-08-05 09:26:41,482 [ 9303] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=14, fontScale=null; restored: fontSize=14, fontScale=1.0
2017-08-05 09:26:41,482 [ 9303] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-08-05 09:26:41,486 [ 9307] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,492 [ 9313] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=15, fontScale=null; restored: fontSize=15, fontScale=1.0
2017-08-05 09:26:41,492 [ 9313] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,496 [ 9317] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=13, fontScale=null; restored: fontSize=13, fontScale=1.0
2017-08-05 09:26:41,496 [ 9317] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,501 [ 9322] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=13, fontScale=null; restored: fontSize=13, fontScale=1.0
2017-08-05 09:26:41,501 [ 9322] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,505 [ 9326] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=14, fontScale=null; restored: fontSize=14, fontScale=1.0
2017-08-05 09:26:41,505 [ 9326] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=12, fontScale=null; restored: fontSize=12, fontScale=1.0
2017-08-05 09:26:41,508 [ 9329] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=13, fontScale=null; restored: fontSize=13, fontScale=1.0
2017-08-05 09:26:41,511 [ 9332] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=16, fontScale=null; restored: fontSize=16, fontScale=1.0
2017-08-05 09:26:41,511 [ 9332] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,516 [ 9337] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=14, fontScale=null; restored: fontSize=14, fontScale=1.0
2017-08-05 09:26:41,516 [ 9337] INFO - com.intellij.ide.ui.UISettings - Loaded: fontSize=10, fontScale=null; restored: fontSize=10, fontScale=1.0
2017-08-05 09:26:41,935 [ 9756] INFO - .openapi.application.Preloader - Finished preloading com.intellij.openapi.actionSystem.impl.ActionPreloader@5bd053b8
2017-08-05 09:26:42,102 [ 9923] INFO - ellij.project.impl.ProjectImpl - 121 project components initialized in 1640 ms
2017-08-05 09:26:42,300 [ 10121] INFO - le.impl.ModuleManagerComponent - 1 module(s) loaded in 196 ms
2017-08-05 09:26:42,703 [ 10524] INFO - ellij.project.impl.ProjectImpl - 22 project components initialized in 60 ms
2017-08-05 09:26:42,712 [ 10533] INFO - .openapi.application.Preloader - Finished preloading com.intellij.ide.ui.search.SearchableOptionPreloader@122602d2
2017-08-05 09:26:42,779 [ 10600] WARN - om.intellij.util.ProfilingUtil - Profiling agent is not enabled. Add -agentlib:yjpagent to idea.vmoptions if necessary to profile IDEA.
2017-08-05 09:26:43,195 [ 11016] INFO - PerformancePlugin - Performance Plugin is in silent mode
2017-08-05 09:26:43,349 [ 11170] INFO - .openapi.application.Preloader - Finished preloading com.intellij.codeInsight.completion.CompletionPreloader@66e26a50
2017-08-05 09:26:43,796 [ 11617] WARN - openapi.wm.impl.ToolWindowImpl - ToolWindow icons should be 13x13. Please fix ToolWindow (ID: Pomodoro) or icon jar:file:/Users/bertog/Library/Application%20Support/PhpStorm2017.2/pomodoro.jar!/resources/pomodoro-icon.png
2017-08-05 09:26:44,687 [ 12508] INFO - m.maddyhome.idea.vim.VimPlugin - Sending statistics: https://plugins.jetbrains.com/plugins/list?pluginId=IdeaVIM&build=PS-172.3544.41&pluginVersion=0.48&os=Mac+OS+X+10.12.6&uuid=7ff4bfcc-0310-45df-8be8-0e3fbea50254
2017-08-05 09:26:44,757 [ 12578] INFO - .diagnostic.PerformanceWatcher - Pushing properties took 755ms; general responsiveness: ok; EDT responsiveness: ok
2017-08-05 09:26:45,116 [ 12937] INFO - tartup.impl.StartupManagerImpl - OpenFilesActivity run in 1198ms under project opening modal progress
2017-08-05 09:26:46,366 [ 14187] INFO - .diagnostic.PerformanceWatcher - Indexable file iteration took 1607ms; general responsiveness: ok; EDT responsiveness: 1/2 sluggish, 1/2 very slow
2017-08-05 09:26:46,797 [ 14618] INFO - son.PackageVersionRangeMatcher - Matching 9 done in 1310 ms, not matched: []
2017-08-05 09:26:46,808 [ 14629] INFO - tartup.impl.StartupManagerImpl - JsbtStartupActivity run in 1610ms under project opening modal progress
2017-08-05 09:26:46,827 [ 14648] INFO - .diagnostic.PerformanceWatcher - Post-startup activities under progress took 2950ms; general responsiveness: ok; EDT responsiveness: 1/3 sluggish, 1/3 very slow
2017-08-05 09:26:49,437 [ 17258] INFO - tartup.impl.StartupManagerImpl - /Users/bertog/Code/aitemsReloaded/.idea case-sensitivity: expected=false actual=false
2017-08-05 09:26:49,509 [ 17330] INFO - lij.tasks.impl.TaskManagerImpl - Searching for task 'AR-15' in YouTrackRepository(URL='https://bertog.myjetbrains.com/youtrack')
2017-08-05 09:26:53,041 [ 20862] INFO - tor.impl.FileEditorManagerImpl - Project opening took 12691 ms

0
Comment actions Permalink

Thanks!

Could you please go to Help > Debug Log Settings, then add there #com.jetbrains.php string and press OK, then reproduce the issue with PHPUnit and check the idea.log again?

0
Comment actions Permalink

Thanks for the reply :)

Here the log for a smaller suite of test about another project and its log:

 

2017-08-08 14:10:24,347 [ 645940] DEBUG - t.PhpUnitLocalRunConfiguration - PHPUnit 5.7.21 was detected. Execute tests with /Users/bertog/Code/forum/vendor/phpunit/phpunit/phpunit
2017-08-08 14:10:24,353 [ 645946] DEBUG - .php.run.filters.PhpUnitFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpLocalPathMapper@713d0000
2017-08-08 14:10:24,353 [ 645946] DEBUG - ains.php.run.filters.PhpFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpLocalPathMapper@713d0000
2017-08-08 14:10:24,354 [ 645947] DEBUG - ains.php.run.filters.PhpFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpIdenticalPathMapper@46ea759f
2017-08-08 14:10:24,354 [ 645947] DEBUG - ains.php.run.filters.PhpFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpIdenticalPathMapper@16323d23
2017-08-08 14:10:24,365 [ 645958] DEBUG - ains.php.run.filters.PhpFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpLocalPathMapper@713d0000
2017-08-08 14:10:24,365 [ 645958] DEBUG - .php.run.filters.PhpUnitFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpLocalPathMapper@713d0000
2017-08-08 14:10:24,365 [ 645958] DEBUG - ains.php.run.filters.PhpFilter - Path mapper: com.jetbrains.php.util.pathmapper.PhpLocalPathMapper@713d0000
2017-08-08 14:10:24,391 [ 645984] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: /usr/local/php5-7.1.4-20170506-100436/bin/php /Users/bertog/Code/forum/vendor/phpunit/phpunit/phpunit --configuration /Users/bertog/Code/forum/phpunit.xml /Users/bertog/Code/forum/tests --teamcity
2017-08-08 14:10:29,557 [ 651150] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: PHPUnit 5.7.21 by Sebastian Bergmann and contributors.
2017-08-08 14:10:29,557 [ 651150] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testCount count='25' flowId='18273']
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='/Users/bertog/Code/forum/tests' flowId='18273']
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Feature\CreateThreadTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/CreateThreadTest.php::\Tests\Feature\CreateThreadTest' flowId='18273']
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,558 [ 651151] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='guest_may_not_create_threads' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/CreateThreadTest.php::\Tests\Feature\CreateThreadTest::guest_may_not_create_threads' flowId='18273']
2017-08-08 14:10:29,864 [ 651457] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,864 [ 651457] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='guest_may_not_create_threads' duration='300' flowId='18273']
2017-08-08 14:10:29,864 [ 651457] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:29,864 [ 651457] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='an_authenticated_user_can_create_a_new_forum_thread' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/CreateThreadTest.php::\Tests\Feature\CreateThreadTest::an_authenticated_user_can_create_a_new_forum_thread' flowId='18273']
2017-08-08 14:10:30,093 [ 651686] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,093 [ 651686] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='an_authenticated_user_can_create_a_new_forum_thread' duration='230' flowId='18273']
2017-08-08 14:10:30,093 [ 651686] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,093 [ 651686] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_require_a_title' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/CreateThreadTest.php::\Tests\Feature\CreateThreadTest::a_thread_require_a_title' flowId='18273']
2017-08-08 14:10:30,239 [ 651832] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,239 [ 651832] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_require_a_title' duration='150' flowId='18273']
2017-08-08 14:10:30,239 [ 651832] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,239 [ 651832] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_require_a_body' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/CreateThreadTest.php::\Tests\Feature\CreateThreadTest::a_thread_require_a_body' flowId='18273']
2017-08-08 14:10:30,383 [ 651976] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,383 [ 651976] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_require_a_body' duration='140' flowId='18273']
2017-08-08 14:10:30,383 [ 651976] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,383 [ 651976] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_requires_a_valid_channel' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/CreateThreadTest.php::\Tests\Feature\CreateThreadTest::a_thread_requires_a_valid_channel' flowId='18273']
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_requires_a_valid_channel' duration='160' flowId='18273']
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Feature\CreateThreadTest' flowId='18273']
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Feature\FavoritesTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/FavoritesTest.php::\Tests\Feature\FavoritesTest' flowId='18273']
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,540 [ 652133] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_guest_cannot_favorite_anything' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/FavoritesTest.php::\Tests\Feature\FavoritesTest::a_guest_cannot_favorite_anything' flowId='18273']
2017-08-08 14:10:30,610 [ 652203] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,610 [ 652203] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_guest_cannot_favorite_anything' duration='70' flowId='18273']
2017-08-08 14:10:30,610 [ 652203] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,610 [ 652203] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='an_authenticated_user_can_favorite_any_reply' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/FavoritesTest.php::\Tests\Feature\FavoritesTest::an_authenticated_user_can_favorite_any_reply' flowId='18273']
2017-08-08 14:10:30,776 [ 652369] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,777 [ 652370] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='an_authenticated_user_can_favorite_any_reply' duration='160' flowId='18273']
2017-08-08 14:10:30,777 [ 652370] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,777 [ 652370] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='an_authenticated_user_can_only_favorite_a_reply_once' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/FavoritesTest.php::\Tests\Feature\FavoritesTest::an_authenticated_user_can_only_favorite_a_reply_once' flowId='18273']
2017-08-08 14:10:30,946 [ 652539] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,946 [ 652539] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='an_authenticated_user_can_only_favorite_a_reply_once' duration='170' flowId='18273']
2017-08-08 14:10:30,947 [ 652540] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,947 [ 652540] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Feature\FavoritesTest' flowId='18273']
2017-08-08 14:10:30,948 [ 652541] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,948 [ 652541] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Feature\ParticipateInForumTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ParticipateInForumTest.php::\Tests\Feature\ParticipateInForumTest' flowId='18273']
2017-08-08 14:10:30,948 [ 652541] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:30,948 [ 652541] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='an_unauthenticated_user_may_try_to_post_a_reply' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ParticipateInForumTest.php::\Tests\Feature\ParticipateInForumTest::an_unauthenticated_user_may_try_to_post_a_reply' flowId='18273']
2017-08-08 14:10:31,018 [ 652611] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,018 [ 652611] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='an_unauthenticated_user_may_try_to_post_a_reply' duration='70' flowId='18273']
2017-08-08 14:10:31,019 [ 652612] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,019 [ 652612] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='an_authenticated_user_may_participate_in_forum_thread' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ParticipateInForumTest.php::\Tests\Feature\ParticipateInForumTest::an_authenticated_user_may_participate_in_forum_thread' flowId='18273']
2017-08-08 14:10:31,206 [ 652799] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,206 [ 652799] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='an_authenticated_user_may_participate_in_forum_thread' duration='190' flowId='18273']
2017-08-08 14:10:31,207 [ 652800] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,207 [ 652800] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_reply_requires_a_body' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ParticipateInForumTest.php::\Tests\Feature\ParticipateInForumTest::a_reply_requires_a_body' flowId='18273']
2017-08-08 14:10:31,365 [ 652958] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,366 [ 652959] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_reply_requires_a_body' duration='160' flowId='18273']
2017-08-08 14:10:31,366 [ 652959] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,366 [ 652959] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Feature\ParticipateInForumTest' flowId='18273']
2017-08-08 14:10:31,373 [ 652966] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,373 [ 652966] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Feature\ReadThreadsTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest' flowId='18273']
2017-08-08 14:10:31,373 [ 652966] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,373 [ 652966] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_user_can_read_all_threads' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest::a_user_can_read_all_threads' flowId='18273']
2017-08-08 14:10:31,516 [ 653109] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,517 [ 653110] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_user_can_read_all_threads' duration='140' flowId='18273']
2017-08-08 14:10:31,517 [ 653110] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,517 [ 653110] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_user_car_read_a_single_thread' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest::a_user_car_read_a_single_thread' flowId='18273']
2017-08-08 14:10:31,671 [ 653264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,671 [ 653264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_user_car_read_a_single_thread' duration='150' flowId='18273']
2017-08-08 14:10:31,671 [ 653264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,671 [ 653264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_user_can_read_the_replies_associated_to_a_thread' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest::a_user_can_read_the_replies_associated_to_a_thread' flowId='18273']
2017-08-08 14:10:31,839 [ 653432] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,839 [ 653432] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_user_can_read_the_replies_associated_to_a_thread' duration='170' flowId='18273']
2017-08-08 14:10:31,839 [ 653432] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,839 [ 653432] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_user_can_filter_threads_according_to_a_channel' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest::a_user_can_filter_threads_according_to_a_channel' flowId='18273']
2017-08-08 14:10:31,996 [ 653589] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,996 [ 653589] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_user_can_filter_threads_according_to_a_channel' duration='160' flowId='18273']
2017-08-08 14:10:31,996 [ 653589] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:31,996 [ 653589] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_user_can_filter_threads_by_any_username' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest::a_user_can_filter_threads_by_any_username' flowId='18273']
2017-08-08 14:10:32,153 [ 653746] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,153 [ 653746] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_user_can_filter_threads_by_any_username' duration='160' flowId='18273']
2017-08-08 14:10:32,153 [ 653746] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,153 [ 653746] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_user_can_filter_threads_by_popularity' locationHint='php_qn:///Users/bertog/Code/forum/tests/Feature/ReadThreadsTest.php::\Tests\Feature\ReadThreadsTest::a_user_can_filter_threads_by_popularity' flowId='18273']
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_user_can_filter_threads_by_popularity' duration='170' flowId='18273']
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Feature\ReadThreadsTest' flowId='18273']
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Feature\ChannelTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ChannelTest.php::\Tests\Feature\ChannelTest' flowId='18273']
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,333 [ 653926] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_channel_consists_of_threads' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ChannelTest.php::\Tests\Feature\ChannelTest::a_channel_consists_of_threads' flowId='18273']
2017-08-08 14:10:32,469 [ 654062] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,469 [ 654062] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_channel_consists_of_threads' duration='140' flowId='18273']
2017-08-08 14:10:32,469 [ 654062] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,469 [ 654062] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Feature\ChannelTest' flowId='18273']
2017-08-08 14:10:32,477 [ 654070] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,477 [ 654070] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Unit\ExampleTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ExampleTest.php::\Tests\Unit\ExampleTest' flowId='18273']
2017-08-08 14:10:32,477 [ 654070] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,477 [ 654070] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='testBasicTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ExampleTest.php::\Tests\Unit\ExampleTest::testBasicTest' flowId='18273']
2017-08-08 14:10:32,498 [ 654091] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,498 [ 654091] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='testBasicTest' duration='20' flowId='18273']
2017-08-08 14:10:32,499 [ 654092] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,499 [ 654092] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Unit\ExampleTest' flowId='18273']
2017-08-08 14:10:32,506 [ 654099] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,506 [ 654099] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Unit\ReplyTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ReplyTest.php::\Tests\Unit\ReplyTest' flowId='18273']
2017-08-08 14:10:32,507 [ 654100] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,507 [ 654100] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='it_has_a_owner' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ReplyTest.php::\Tests\Unit\ReplyTest::it_has_a_owner' flowId='18273']
2017-08-08 14:10:32,663 [ 654256] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,663 [ 654256] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='it_has_a_owner' duration='160' flowId='18273']
2017-08-08 14:10:32,663 [ 654256] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,663 [ 654256] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Unit\ReplyTest' flowId='18273']
2017-08-08 14:10:32,670 [ 654263] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,671 [ 654264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteStarted name='Tests\Unit\ThreadTest' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ThreadTest.php::\Tests\Unit\ThreadTest' flowId='18273']
2017-08-08 14:10:32,671 [ 654264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,671 [ 654264] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_has_replies' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ThreadTest.php::\Tests\Unit\ThreadTest::a_thread_has_replies' flowId='18273']
2017-08-08 14:10:32,806 [ 654399] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,806 [ 654399] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_has_replies' duration='130' flowId='18273']
2017-08-08 14:10:32,806 [ 654399] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,806 [ 654399] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_has_a_creator' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ThreadTest.php::\Tests\Unit\ThreadTest::a_thread_has_a_creator' flowId='18273']
2017-08-08 14:10:32,931 [ 654524] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,932 [ 654525] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_has_a_creator' duration='130' flowId='18273']
2017-08-08 14:10:32,932 [ 654525] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:32,932 [ 654525] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_can_add_a_reply' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ThreadTest.php::\Tests\Unit\ThreadTest::a_thread_can_add_a_reply' flowId='18273']
2017-08-08 14:10:33,068 [ 654661] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,068 [ 654661] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_can_add_a_reply' duration='140' flowId='18273']
2017-08-08 14:10:33,070 [ 654663] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,070 [ 654663] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_belong_to_a_channel' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ThreadTest.php::\Tests\Unit\ThreadTest::a_thread_belong_to_a_channel' flowId='18273']
2017-08-08 14:10:33,205 [ 654798] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,205 [ 654798] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_belong_to_a_channel' duration='140' flowId='18273']
2017-08-08 14:10:33,205 [ 654798] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,205 [ 654798] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testStarted name='a_thread_can_make_a_string_path' locationHint='php_qn:///Users/bertog/Code/forum/tests/Unit/ThreadTest.php::\Tests\Unit\ThreadTest::a_thread_can_make_a_string_path' flowId='18273']
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testFinished name='a_thread_can_make_a_string_path' duration='140' flowId='18273']
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='Tests\Unit\ThreadTest' flowId='18273']
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: ##teamcity[testSuiteFinished name='/Users/bertog/Code/forum/tests' flowId='18273']
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: Time: 3.86 seconds, Memory: 24.00MB
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output:
2017-08-08 14:10:33,346 [ 654939] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: OK (25 tests, 39 assertions)
2017-08-08 14:10:33,369 [ 654962] DEBUG - m.jetbrains.php.run.PhpRunUtil - Output: Process finished with exit code 0

On the Terminal the same suite run in half of time and istantanely.

Thanks again. Guido

0
Comment actions Permalink

Just to be sure, are you using the exact same command when running the test on the Terminal?

/usr/local/php5-7.1.4-20170506-100436/bin/php /Users/bertog/Code/forum/vendor/phpunit/phpunit/phpunit --configuration /Users/bertog/Code/forum/phpunit.xml /Users/bertog/Code/forum/tests

0
Comment actions Permalink

Nope ... 

This is how PhpStorm launch phpunit and the suite. In the terminal I run simply "phpunit" or "phpunit --filter" for specific test. BTW the test are in a Laravel environment.

Guido

0
Comment actions Permalink

I mean, is it as slow if you run the command via Terminal?
We need to understand if it's PhpStorm slows down the process, or it' the certain way PhpStorm runs the tests that is causing this.

0
Comment actions Permalink

Ok, I've run the PhpStorm command in the Terminal and It is slow like in the IDE ... So I think is how PhpStorm run phpunit that slow Everything down ...

G.

0
Comment actions Permalink

So, we don't do any rocket science there - it's just a PHP executable, a PHPUnit executable, your configuration and a path to tests.
Is it possible that when you run "phpunit":

1) a different interpreter is being used (maybe the PhpStorm one has Xdebug enabled?)

2) a different PHPUnit installation is being used (that's for sure, I doubt you use the project one globally)?

Try using the same executables in PhpStorm, does it run faster now?

0
Comment actions Permalink

Hi Eugene,

the phpunit executable is the same for the Terminal and the project and I don't have phpunit installed globally. So I use the phpunit that came with the laravel installation. 

What I can do in the next few days is test everything on another Macbook Pro. Those two machine share, the same project, the same PHP installed by homebrew, but this one is still on OS X El Capitain. I can update PhpStorm on this machine and test phpunit performance. So I can say if the problem is on Sierra or PhpStorm.

Guido.

0
Comment actions Permalink

Ok,

I've done the test. With the same project, same php version, same PhpStorm version, on El Capitan the test performance are equal in PhpStorm and on Terminal. I think is something on Sierra. 

BTW the El Capitan Machine is a Late 2011 MacBook Pro and the Sierra a Late 2015. 

There's some solution or further test I can make?

Guido

0
Comment actions Permalink

Not much we can do/test from PhpStorm side. You can check related threads (like this one) or create your own at https://github.com/sebastianbergmann/phpunit/issues

1

Please sign in to leave a comment.