search

JetBrains / intellij-platform-gradle-plugin

Gradle plugin for building plugins for IntelliJ-based IDEs
https://plugins.jetbrains.com/docs/intellij/gradle-prerequisites.html
Apache License 2.0
1.43k stars 271 forks source link

Indexing stopped working after upgrading target platform to 2021.2 #771

Closed stukalin closed 3 years ago

stukalin commented 3 years ago

I want to upgrade the platform of the Ember.js plugin to 2021.2 but when I do that some of the tests start to fail. Namely the ones which which use the FileBasedIndex.getAllKeys() method.

I use the v1.1.4 of the gradle-intellij-plugin.

Here's my log for a failing test

0    [Test worker] INFO  #com.intellij.ui.scale.JBUIScale  - System scale factor: 1.0 (IDE-managed HiDPI)
247  [JobScheduler FJ pool 6/16] WARN  #com.intellij.ide.plugins.PluginManager  - id redefinition ([row,col,system-id]: [2,3,"java.util.zip.ZipFile@ba9b49b"])
345  [JobScheduler FJ pool 0/16] INFO  #com.intellij.ide.plugins.PluginManager  - D:\Projects\intellij-emberjs\.sandbox\plugins-test\Ember.js overrides D:\Projects\intellij-emberjs\build\resources\main
348  [JobScheduler FJ pool 0/16] INFO  #com.intellij.ide.plugins.PluginManager  - D:\Projects\intellij-emberjs\.sandbox\plugins-test\handlebars overrides C:\Users\andrey\.gradle\caches\modules-2\files-2.1\com.jetbrains.intellij.idea\unzipped.com.jetbrains.plugins\com.dmarcotte.handlebars-212.4746.57\handlebars
367  [JobScheduler FJ pool 0/16] INFO  #com.intellij.ide.plugins.PluginManager  - Module intellij.profiler.ultimate is not enabled because dependency com.intellij.java is not available
367  [JobScheduler FJ pool 0/16] INFO  #com.intellij.ide.plugins.PluginManager  - Module intellij.javascript.impl/diagrams is not enabled because dependency com.intellij.diagram is not available
370  [JobScheduler FJ pool 0/16] INFO  #com.intellij.ide.plugins.PluginManager  - Loaded bundled plugins: IDEA CORE (212.4746.92), Images (212.4746.92), CSS (212.4746.92), JavaScript and TypeScript (212.4746.92), YAML (212.4746.92)
371  [JobScheduler FJ pool 0/16] INFO  #com.intellij.ide.plugins.PluginManager  - Loaded custom plugins: Handlebars/Mustache (212.4746.57), Ember.js (2021.2.1)
427  [JobScheduler FJ pool 8/16] WARN  #com.intellij.internal.DebugAttachDetector  - Unable to start DebugAttachDetector, please add `--add-exports java.base/jdk.internal.vm=ALL-UNNAMED` to VM options
461  [JobScheduler FJ pool 1/16] INFO  #com.intellij.application.options.PathMacrosImpl  - Loaded path macros: {}
492  [JobScheduler FJ pool 0/16] INFO  #com.intellij.util.io.FilePageCache  - lower=100; upper=492; buffer=10; max=492
550  [JobScheduler FJ pool 8/16] INFO  #com.intellij.openapi.util.io.win32.IdeaWin32  - Native filesystem for Windows is operational
645  [ApplicationImpl pooled thread 1] INFO  #com.intellij.util.net.ssl.CertificateManager  - Default SSL context initialized
7498 [JobScheduler FJ pool 0/16] INFO  #com.intellij.workspaceModel.ide.impl.legacyBridge.module.ModuleBridgeLoaderService  - Workspace model loaded without cache. Loading real project state into workspace model. Thread[JobScheduler FJ pool 0/16,4,main]
7691 [File Watcher] INFO  #com.intellij.openapi.vfs.impl.local.NativeFileWatcherImpl  - Native file watcher is disabled
7695 [File Watcher] INFO  #com.intellij.openapi.vfs.impl.wsl.WslFileWatcher  - WSL file watcher: C:\Users\andrey\.gradle\caches\modules-2\files-2.1\com.jetbrains.intellij.idea\ideaIU\2021.2\31f9496f635cec87427837101e04cbcddfb544d7\ideaIU-2021.2\bin\fsnotifier-wsl
7809 [AWT-EventQueue-0] INFO  #com.intellij.jna.JnaLoader  - JNA library (64-bit) loaded in 43 ms
7970 [Index Data Initializer Pool] INFO  com.intellij.util.indexing.FileBasedIndexDataInitialization  - Index data initialization done: 581 ms. Initialized indexes: [FilenameIndex, FrameworkDetectionIndex, TodoIndex, IdIndex, filetypes, Stubs, Trigram.Index, fileIncludes, DomFileIndex, RelaxSymbolIndex, XmlTagNames, XmlNamespaces, html5.custom.attributes.index, SchemaTypeInheritance, json.file.root.values, HashFragmentIndex, CssIndex, css.template.selectors, js.test.names, js.package.index, js.custom.single.entry.index, js.implicit.elements.index, js.string.literal.words.index, TypeScriptExternalPathCandidates, yaml.keys.name, ember.names, ember.i18n, ember.intl].
8016 [Index Data Initializer Pool] INFO  com.intellij.psi.stubs.StubIndexImpl$StubIndexInitialization  - Index data initialization done: 46 ms. Initialized stub indexes: {Css.class, es6.exported.index, Css.custom.mixin, js.class.index2, js.namespace.index, amd.baseUrl.index, js.symbol.qualified.names.index, js.amd.modules, ts.local.classes, js.gcl.modules, js.exported.index, js.imported.bindings.index, dom.namespaceKey, js.nonglobal.symbol.index, ts.embedded.content.index, dom.elementClass, js.lazy.packages, Css.attr, es6.assignment.index, js.element.qualifiedName, js.global.symbol.index, js.generics.index, JS.base.types.index, js.local.namespace.members.index, amd.requirePaths.index, JS.subclass.stub.index, Css.ampersand.selector, Css.id, Css.custom.property, js.qualified.shortName, js.typedef.index2, JS.global.qualified.elements, ts.external.module.index, js.symbol.index2, JS.custom.stub.index, JS.class.super, JS.class.implements, js.doc.modules}.
8115 [AWT-EventQueue-0] WARN  #com.intellij.internal.statistic.eventLog.StatisticsEventLogProviderUtil  - Cannot find event log provider with recorder-id=FUS
8123 [AWT-EventQueue-0] WARN  #com.intellij.internal.statistic.eventLog.EventLogConfiguration  - Failed retrieving device id for FUS
8166 [ApplicationImpl pooled thread 1] INFO  #com.intellij.openapi.application.Experiments  - Experimental features enabled for user: wsl.p9.support, wsl.prefer.p9.support, wsl.p9.show.roots.in.file.chooser, wsl.execute.with.wsl.exe, linux.native.menu, recent.and.edited.files.together, show.create.new.element.in.popup, search.everywhere.mixed.results, editor.reader.mode, new.large.text.file.viewer
8182 [AWT-EventQueue-0] INFO  #com.intellij.psi.search.LogFileTypeIndex  - Loading file type index snapshot
8189 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Started indexing of light_temp_1wymuHjBvLD21TimMMFiCODhy02. Reason: On project open
8194 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Performing delayed pushing properties tasks for light_temp_1wymuHjBvLD21TimMMFiCODhy02 took 0ms; general responsiveness: ok; EDT responsiveness: ok
8224 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning of light_temp_1wymuHjBvLD21TimMMFiCODhy02 uses 16 scanning threads
8445 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning completed for light_temp_1wymuHjBvLD21TimMMFiCODhy02. Number of scanned files: 297; Number of files for indexing: 0 took 249ms; general responsiveness: ok; EDT responsiveness: ok
8446 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Finished for light_temp_1wymuHjBvLD21TimMMFiCODhy02. No files to index with loading content.
8558 [JobScheduler FJ pool 11/16] INFO  #com.intellij.openapi.externalSystem.service.project.manage.ExternalProjectsDataStorage  - Load external projects data in 1 millis (read time: 1)
8612 [JobScheduler FJ pool 11/16] INFO  #com.intellij.diagnostic.PerformanceWatcher  - Post-startup activities under progress took 160ms; general responsiveness: ok; EDT responsiveness: ok
8674 [AWT-EventQueue-0] INFO  #com.intellij.psi.codeStyle.ProjectCodeStyleSettingsManager  - Initialized from default code style settings.
8755 [AWT-EventQueue-0] WARN  #com.intellij.openapi.actionSystem.impl.ActionManagerImpl  - keymap "Visual Studio" not found [Plugin: com.intellij]
8758 [AWT-EventQueue-0] WARN  #com.intellij.openapi.actionSystem.impl.ActionManagerImpl  - keymap "Eclipse" not found [Plugin: com.intellij]
8758 [AWT-EventQueue-0] WARN  #com.intellij.openapi.actionSystem.impl.ActionManagerImpl  - keymap "NetBeans 6.5" not found [Plugin: com.intellij]
8758 [AWT-EventQueue-0] WARN  #com.intellij.openapi.actionSystem.impl.ActionManagerImpl  - keymap "Visual Studio" not found [Plugin: com.intellij]
8771 [AWT-EventQueue-0] WARN  #com.intellij.openapi.actionSystem.impl.ActionManagerImpl  - keymap "Eclipse" not found [Plugin: JavaScript]
8771 [AWT-EventQueue-0] WARN  #com.intellij.openapi.actionSystem.impl.ActionManagerImpl  - keymap "Visual Studio" not found [Plugin: JavaScript]
8886 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
8935 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
8963 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
8966 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
9039 [AWT-EventQueue-0] INFO  #com.intellij.openapi.application.impl.ApplicationImpl  - LONG VFS PROCESSING. Topic=BulkFileListener, offender=class com.intellij.openapi.vfs.newvfs.AsyncEventSupport$1, message=before, time=70ms
9049 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
9052 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
9055 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
9061 [AWT-EventQueue-0] INFO  #com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager  - Found 0 libraries, trying to add them: []
9121 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.FileBasedIndexImpl  - Rebuild requested for index ember.names
java.lang.Throwable
    at com.intellij.util.indexing.FileBasedIndex.requestRebuild(FileBasedIndex.java:83)
    at com.emberjs.index.EmberNameIndexTest.doTest(EmberNameIndexTest.kt:22)
    at com.emberjs.index.EmberNameIndexTest.testExample(EmberNameIndexTest.kt:29)
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.base/java.lang.reflect.Method.invoke(Method.java:566)
    at junit.framework.TestCase.runTest(TestCase.java:176)
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$11(UsefulTestCase.java:533)
    at com.intellij.testFramework.UsefulTestCase.lambda$wrapTestRunnable$13(UsefulTestCase.java:553)
    at com.intellij.testFramework.UsefulTestCase.runTestRunnable(UsefulTestCase.java:465)
    at com.intellij.testFramework.fixtures.BasePlatformTestCase.runTestRunnable(BasePlatformTestCase.java:106)
    at com.intellij.testFramework.UsefulTestCase.defaultRunBare(UsefulTestCase.java:481)
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$12(UsefulTestCase.java:541)
    at com.intellij.testFramework.EdtTestUtil.lambda$runInEdtAndWait$1(EdtTestUtil.java:40)
    at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:303)
    at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:770)
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:721)
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:715)
    at java.base/java.security.AccessController.doPrivileged(Native Method)
    at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:85)
    at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:740)
    at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:408)
    at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:203)
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:124)
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:113)
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:109)
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
    at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:90)
9126 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Started indexing of light_temp_1wymuHjBvLD21TimMMFiCODhy02. Reason: Rebuild requested for index ember.names
9126 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Performing delayed pushing properties tasks for light_temp_1wymuHjBvLD21TimMMFiCODhy02 took 0ms; general responsiveness: ok; EDT responsiveness: ok
9127 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning of light_temp_1wymuHjBvLD21TimMMFiCODhy02 uses 16 scanning threads
9157 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning completed for light_temp_1wymuHjBvLD21TimMMFiCODhy02. Number of scanned files: 305; Number of files for indexing: 7 took 31ms; general responsiveness: ok; EDT responsiveness: ok
9186 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Use 4 indexing threads for indexing of light_temp_1wymuHjBvLD21TimMMFiCODhy02
9669 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Finished for light_temp_1wymuHjBvLD21TimMMFiCODhy02. Unindexed files update took 496ms; general responsiveness: ok; EDT responsiveness: ok

and the corresponding standard error

09:03:44,446 DEBUG aemon.impl.PassExecutorService -  null Cancel Write action start true; progress=1578423484 V?
09:03:44,453 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/app'] with 0 children]?
09:03:44,462 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/app?
09:03:44,472 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/app'] with 0 children?
09:03:44,473 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src (id = 110); old = modStamp: 929; children: [], new = modStamp: 929; children: ["app"; nameId: 6213; id: 388 ([type:DIRECTORY length:0 modified:5 case sensitive: SENSITIVE])]?
09:03:44,477 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/app: true?
09:03:44,477 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,478 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,520 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,523 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,524 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/app/routes'] with 0 children]?
09:03:44,525 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/app/routes?
09:03:44,525 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/app/routes'] with 0 children?
09:03:44,526 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app (id = 388); old = modStamp: 936; children: [], new = modStamp: 936; children: ["routes"; nameId: 6217; id: 387 ([type:DIRECTORY length:0 modified:6 case sensitive: SENSITIVE])]?
09:03:44,526 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/app/routes: true?
09:03:44,526 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,526 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/routes, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,529 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,530 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,530 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/app/routes/index.js']]?
09:03:44,531 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/app/routes/index.js']?
09:03:44,531 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app/routes (id = 387); old = modStamp: 942; children: [], new = modStamp: 942; children: ["index.js"; nameId: 6236; id: 386 ([type:FILE length:0 modified:7 case sensitive: UNKNOWN])]?
09:03:44,531 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/routes/index.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,532 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,532 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,540 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/app/routes/index.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,540 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\app\routes\index.js, oldTimestamp:7, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:8, oldLength:0, newLength:0]]?
09:03:44,552 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/app/routes/index.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,552 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,552 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,553 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/app/controllers'] with 0 children]?
09:03:44,553 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/app/controllers?
09:03:44,553 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/app/controllers'] with 0 children?
09:03:44,554 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app (id = 388); old = modStamp: 953; children: ["routes"; nameId: 6217; id: 387 (unknown)], new = modStamp: 953; children: ["controllers"; nameId: 6224; id: 385 ([type:DIRECTORY length:0 modified:10 case sensitive: SENSITIVE]), "routes"; nameId: 6217; id: 387 (unknown)]?
09:03:44,554 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/app/controllers: true?
09:03:44,554 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,554 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/controllers, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,555 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,555 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,556 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/app/controllers/user'] with 0 children]?
09:03:44,556 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/app/controllers/user?
09:03:44,556 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/app/controllers/user'] with 0 children?
09:03:44,556 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app/controllers (id = 385); old = modStamp: 960; children: [], new = modStamp: 960; children: ["user"; nameId: 6245; id: 384 ([type:DIRECTORY length:0 modified:11 case sensitive: SENSITIVE])]?
09:03:44,557 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/app/controllers/user: true?
09:03:44,557 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,557 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/controllers/user, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,558 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,558 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,558 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/app/controllers/user/new.ts']]?
09:03:44,558 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/app/controllers/user/new.ts']?
09:03:44,559 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app/controllers/user (id = 384); old = modStamp: 966; children: [], new = modStamp: 966; children: ["new.ts"; nameId: 6265; id: 383 ([type:FILE length:0 modified:12 case sensitive: UNKNOWN])]?
09:03:44,559 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/controllers/user/new.ts, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,559 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,560 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,560 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/app/controllers/user/new.ts, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,560 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\app\controllers\user\new.ts, oldTimestamp:12, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:13, oldLength:0, newLength:0]]?
09:03:44,605 DEBUG service.JSLanguageServiceQueue - TypeScript Vfs Events Processing: class com.intellij.openapi.vfs.newvfs.events.VFileContentChangeEvent file temp:///src/app/controllers/user/new.ts com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,631 INFO  plication.impl.ApplicationImpl - LONG VFS PROCESSING. Topic=BulkFileListener, offender=class com.intellij.openapi.vfs.newvfs.AsyncEventSupport$1, message=before, time=70ms?
09:03:44,631 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/app/controllers/user/new.ts, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,632 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,632 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,632 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/app/controllers/user/index.js']]?
09:03:44,632 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/app/controllers/user/index.js']?
09:03:44,632 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app/controllers/user (id = 384); old = modStamp: 976; children: ["new.ts"; nameId: 6265; id: 383 (unknown)], new = modStamp: 976; children: ["index.js"; nameId: 6236; id: 382 ([type:FILE length:0 modified:15 case sensitive: UNKNOWN]), "new.ts"; nameId: 6265; id: 383 (unknown)]?
09:03:44,633 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/controllers/user/index.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,633 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,633 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,634 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/app/controllers/user/index.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,634 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\app\controllers\user\index.js, oldTimestamp:15, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:16, oldLength:0, newLength:0]]?
09:03:44,635 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/app/controllers/user/index.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,635 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,635 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,635 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/app/controllers/application.js']]?
09:03:44,636 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/app/controllers/application.js']?
09:03:44,636 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/app/controllers (id = 385); old = modStamp: 986; children: ["user"; nameId: 6245; id: 384 (unknown)], new = modStamp: 986; children: ["application.js"; nameId: 6250; id: 381 ([type:FILE length:0 modified:18 case sensitive: UNKNOWN]), "user"; nameId: 6245; id: 384 (unknown)]?
09:03:44,636 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/app/controllers/application.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,637 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,637 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,638 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/app/controllers/application.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,638 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\app\controllers\application.js, oldTimestamp:18, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:19, oldLength:0, newLength:0]]?
09:03:44,639 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/app/controllers/application.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,639 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,639 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,639 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/tests'] with 0 children]?
09:03:44,639 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/tests?
09:03:44,640 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/tests'] with 0 children?
09:03:44,640 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src (id = 110); old = modStamp: 997; children: ["app"; nameId: 6213; id: 388 (unknown)], new = modStamp: 997; children: ["tests"; nameId: 6196; id: 380 ([type:DIRECTORY length:0 modified:21 case sensitive: SENSITIVE]), "app"; nameId: 6213; id: 388 (unknown)]?
09:03:44,640 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/tests: true?
09:03:44,640 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,640 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/tests, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,642 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,642 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,642 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/tests/unit'] with 0 children]?
09:03:44,642 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/tests/unit?
09:03:44,643 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/tests/unit'] with 0 children?
09:03:44,643 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/tests (id = 380); old = modStamp: 1004; children: [], new = modStamp: 1004; children: ["unit"; nameId: 6272; id: 379 ([type:DIRECTORY length:0 modified:22 case sensitive: SENSITIVE])]?
09:03:44,643 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/tests/unit: true?
09:03:44,643 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,644 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/tests/unit, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,645 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,645 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,645 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/tests/unit/helpers'] with 0 children]?
09:03:44,645 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/tests/unit/helpers?
09:03:44,646 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/tests/unit/helpers'] with 0 children?
09:03:44,646 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/tests/unit (id = 379); old = modStamp: 1011; children: [], new = modStamp: 1011; children: ["helpers"; nameId: 6288; id: 378 ([type:DIRECTORY length:0 modified:23 case sensitive: SENSITIVE])]?
09:03:44,646 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/tests/unit/helpers: true?
09:03:44,647 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,647 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/tests/unit/helpers, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,648 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,648 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,648 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/tests/unit/helpers/format-number-test.js']]?
09:03:44,648 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/tests/unit/helpers/format-number-test.js']?
09:03:44,649 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/tests/unit/helpers (id = 378); old = modStamp: 1017; children: [], new = modStamp: 1017; children: ["format-number-test.js"; nameId: 6296; id: 377 ([type:FILE length:0 modified:24 case sensitive: UNKNOWN])]?
09:03:44,649 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/tests/unit/helpers/format-number-test.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,649 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,649 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,650 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/tests/unit/helpers/format-number-test.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,650 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\tests\unit\helpers\format-number-test.js, oldTimestamp:24, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:25, oldLength:0, newLength:0]]?
09:03:44,651 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/tests/unit/helpers/format-number-test.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,651 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,651 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,651 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create (empty) dir 'temp:///src/tests/acceptance'] with 0 children]?
09:03:44,651 DEBUG ry.NodeModulesDirectoryManager - Watching temp:///src/tests/acceptance?
09:03:44,652 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create (empty) dir 'temp:///src/tests/acceptance'] with 0 children?
09:03:44,652 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/tests (id = 380); old = modStamp: 1028; children: ["unit"; nameId: 6272; id: 379 (unknown)], new = modStamp: 1028; children: ["acceptance"; nameId: 6277; id: 376 ([type:DIRECTORY length:0 modified:27 case sensitive: SENSITIVE]), "unit"; nameId: 6272; id: 379 (unknown)]?
09:03:44,652 DEBUG ry.NodeModulesDirectoryManager - validityChanged for temp:///src/tests/acceptance: true?
09:03:44,652 INFO  ry.NodeModulesDirectoryManager - Found 0 libraries, trying to add them: []?
09:03:44,653 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/tests/acceptance, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,654 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,654 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,654 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/tests/acceptance/user-page-test.js']]?
09:03:44,654 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/tests/acceptance/user-page-test.js']?
09:03:44,654 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src/tests/acceptance (id = 376); old = modStamp: 1034; children: [], new = modStamp: 1034; children: ["user-page-test.js"; nameId: 6318; id: 375 ([type:FILE length:0 modified:28 case sensitive: UNKNOWN])]?
09:03:44,654 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/tests/acceptance/user-page-test.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,655 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,655 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,656 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/tests/acceptance/user-page-test.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,656 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\tests\acceptance\user-page-test.js, oldTimestamp:28, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:29, oldLength:0, newLength:0]]?
09:03:44,657 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/tests/acceptance/user-page-test.js, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,657 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,657 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,657 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/.ember-cli']]?
09:03:44,658 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/.ember-cli']?
09:03:44,658 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src (id = 110); old = modStamp: 1044; children: ["tests"; nameId: 6196; id: 380 (unknown), "app"; nameId: 6213; id: 388 (unknown)], new = modStamp: 1044; children: [".ember-cli"; nameId: 6202; id: 374 ([type:FILE length:0 modified:31 case sensitive: UNKNOWN]), "tests"; nameId: 6196; id: 380 (unknown), "app"; nameId: 6213; id: 388 (unknown)]?
09:03:44,658 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/.ember-cli, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,661 DEBUG .impl.FileTypeDetectionService - temp:///src/.ember-cli; type=Unknown; 0?
09:03:44,661 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,662 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,662 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/.ember-cli, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,662 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\.ember-cli, oldTimestamp:31, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:32, oldLength:0, newLength:0]]?
09:03:44,664 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/.ember-cli, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,664 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,664 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,664 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[create file 'temp:///src/package.json']]?
09:03:44,664 DEBUG newvfs.persistent.PersistentFS - Applying VfsEvent[create file 'temp:///src/package.json']?
09:03:44,664 DEBUG fs.newvfs.persistent.FSRecords - Update children for temp:///src (id = 110); old = modStamp: 1055; children: [".ember-cli"; nameId: 6202; id: 374 (unknown), "tests"; nameId: 6196; id: 380 (unknown), "app"; nameId: 6213; id: 388 (unknown)], new = modStamp: 1055; children: [".ember-cli"; nameId: 6202; id: 374 (unknown), "tests"; nameId: 6196; id: 380 (unknown), "app"; nameId: 6213; id: 388 (unknown), "package.json"; nameId: 2305; id: 390 ([type:FILE length:0 modified:34 case sensitive: UNKNOWN])]?
09:03:44,665 DEBUG fs.impl.VirtualFileManagerImpl - fileCreated: file = temp:///src/package.json, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,673 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,673 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,674 DEBUG riptExternalDefinitionsService - Scheduling update. Used definitions: []?
java.lang.Throwable?
    at com.intellij.lang.javascript.library.typings.TypeScriptExternalDefinitionsService.scheduleUpdate(TypeScriptExternalDefinitionsService.kt:62)?
    at com.intellij.lang.javascript.library.typings.TypeScriptExternalDefinitionsService$1.onChange(TypeScriptExternalDefinitionsService.kt:47)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeMethod(MessageBusImpl.java:645)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeListener(MessageBusImpl.java:624)?
    at com.intellij.util.messages.impl.MessageBusImpl.deliverMessage(MessageBusImpl.java:417)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpWaitingBuses(MessageBusImpl.java:390)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpMessages(MessageBusImpl.java:372)?
    at com.intellij.util.messages.impl.MessageBusImpl.access$200(MessageBusImpl.java:33)?
    at com.intellij.util.messages.impl.MessageBusImpl$MessagePublisher.invoke(MessageBusImpl.java:179)?
    at com.sun.proxy.$Proxy58.onChange(Unknown Source)?
    at com.intellij.javascript.nodejs.packageJson.PackageJsonFileManager.lambda$publishEvent$1(PackageJsonFileManager.java:165)?
    at com.intellij.ui.AppUIUtil.invokeLaterIfProjectAlive(AppUIUtil.java:203)?
    at com.intellij.javascript.nodejs.packageJson.PackageJsonFileManager.publishEvent(PackageJsonFileManager.java:161)?
    at com.intellij.javascript.nodejs.packageJson.PackageJsonFileManager.onPackageJsonAdded(PackageJsonFileManager.java:123)?
    at com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager.handleAddingPackageJson(NodeModulesDirectoryManager.java:401)?
    at com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager$MyFileListener.fileCreated(NodeModulesDirectoryManager.java:659)?
    at com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager$MyFileListener$2.afterVfsChange(NodeModulesDirectoryManager.java:620)?
    at com.intellij.openapi.vfs.newvfs.AsyncEventSupport.afterVfsChange(AsyncEventSupport.java:139)?
    at com.intellij.openapi.vfs.newvfs.AsyncEventSupport$1.after(AsyncEventSupport.java:70)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeMethod(MessageBusImpl.java:645)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeListener(MessageBusImpl.java:624)?
    at com.intellij.util.messages.impl.MessageBusImpl.deliverMessage(MessageBusImpl.java:417)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpWaitingBuses(MessageBusImpl.java:390)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpMessages(MessageBusImpl.java:372)?
    at com.intellij.util.messages.impl.MessageBusImpl.access$200(MessageBusImpl.java:33)?
    at com.intellij.util.messages.impl.MessageBusImpl$MessagePublisher.invoke(MessageBusImpl.java:179)?
    at com.sun.proxy.$Proxy56.after(Unknown Source)?
    at com.intellij.openapi.vfs.newvfs.persistent.PersistentFSImpl.fireAfterEvents(PersistentFSImpl.java:1189)?
    at com.intellij.openapi.vfs.newvfs.persistent.PersistentFSImpl.processEvent(PersistentFSImpl.java:833)?
    at com.intellij.openapi.vfs.newvfs.persistent.PersistentFSImpl.createChildFile(PersistentFSImpl.java:505)?
    at com.intellij.openapi.vfs.newvfs.impl.VirtualFileSystemEntry.createChildData(VirtualFileSystemEntry.java:276)?
    at com.intellij.openapi.vfs.VfsUtilCore.copyFile(VfsUtilCore.java:241)?
    at com.intellij.openapi.vfs.VfsUtilCore.copyFile(VfsUtilCore.java:225)?
    at com.intellij.openapi.vfs.VfsUtil.copyDirectory(VfsUtil.java:76)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1.compute(LightTempDirTestFixtureImpl.java:92)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1.compute(LightTempDirTestFixtureImpl.java:79)?
    at com.intellij.openapi.application.impl.ApplicationImpl.lambda$runWriteAction$15(ApplicationImpl.java:945)?
    at com.intellij.openapi.application.impl.ApplicationImpl.runWriteActionWithClass(ApplicationImpl.java:924)?
    at com.intellij.openapi.application.impl.ApplicationImpl.runWriteAction(ApplicationImpl.java:945)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl.copyAll(LightTempDirTestFixtureImpl.java:79)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl.copyAll(LightTempDirTestFixtureImpl.java:74)?
    at com.intellij.testFramework.fixtures.impl.CodeInsightTestFixtureImpl.copyDirectoryToProject(CodeInsightTestFixtureImpl.java:453)?
    at com.emberjs.index.EmberNameIndexTest.doTest(EmberNameIndexTest.kt:17)?
    at com.emberjs.index.EmberNameIndexTest.testExample(EmberNameIndexTest.kt:29)?
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)?
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)?
    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)?
    at java.base/java.lang.reflect.Method.invoke(Method.java:566)?
    at junit.framework.TestCase.runTest(TestCase.java:176)?
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$11(UsefulTestCase.java:533)?
    at com.intellij.testFramework.UsefulTestCase.lambda$wrapTestRunnable$13(UsefulTestCase.java:553)?
    at com.intellij.testFramework.UsefulTestCase.runTestRunnable(UsefulTestCase.java:465)?
    at com.intellij.testFramework.fixtures.BasePlatformTestCase.runTestRunnable(BasePlatformTestCase.java:106)?
    at com.intellij.testFramework.UsefulTestCase.defaultRunBare(UsefulTestCase.java:481)?
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$12(UsefulTestCase.java:541)?
    at com.intellij.testFramework.EdtTestUtil.lambda$runInEdtAndWait$1(EdtTestUtil.java:40)?
    at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:303)?
    at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:770)?
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:721)?
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:715)?
    at java.base/java.security.AccessController.doPrivileged(Native Method)?
    at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:85)?
    at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:740)?
    at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:408)?
    at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:203)?
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:124)?
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:113)?
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:109)?
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)?
    at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:90)?
?
09:03:44,674 DEBUG riptExternalDefinitionsService - Install type definitions for project [light_temp_1wymuHjBvLD21TimMMFiCODhy02] started?
09:03:44,682 DEBUG riptExternalDefinitionsService - Install type definitions for project [light_temp_1wymuHjBvLD21TimMMFiCODhy02] completed?
09:03:44,684 DEBUG fs.impl.VirtualFileManagerImpl - beforeContentsChange: file = temp:///src/package.json, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,685 DEBUG i.vfs.newvfs.AsyncEventSupport - Processing [VfsEvent[update: \src\package.json, oldTimestamp:34, newTimestamp:-1, oldModificationStamp:0, newModificationStamp:35, oldLength:0, newLength:0]]?
09:03:44,686 DEBUG fs.impl.VirtualFileManagerImpl - contentsChanged: file = temp:///src/package.json, requestor = com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1@67c90568?
09:03:44,686 DEBUG tellij.psi.impl.PsiManagerImpl - beforePropertyChange: element =  null, propertyName = propUnloadedPsi, oldValue = null?
09:03:44,686 DEBUG tellij.psi.impl.PsiManagerImpl - propertyChanged: element =  null, propertyName = propUnloadedPsi, oldValue = null, newValue = null?
09:03:44,686 DEBUG riptExternalDefinitionsService - Scheduling update. Used definitions: []?
java.lang.Throwable?
    at com.intellij.lang.javascript.library.typings.TypeScriptExternalDefinitionsService.scheduleUpdate(TypeScriptExternalDefinitionsService.kt:62)?
    at com.intellij.lang.javascript.library.typings.TypeScriptExternalDefinitionsService$1.onChange(TypeScriptExternalDefinitionsService.kt:47)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeMethod(MessageBusImpl.java:645)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeListener(MessageBusImpl.java:624)?
    at com.intellij.util.messages.impl.MessageBusImpl.deliverMessage(MessageBusImpl.java:417)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpWaitingBuses(MessageBusImpl.java:390)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpMessages(MessageBusImpl.java:372)?
    at com.intellij.util.messages.impl.MessageBusImpl.access$200(MessageBusImpl.java:33)?
    at com.intellij.util.messages.impl.MessageBusImpl$MessagePublisher.invoke(MessageBusImpl.java:179)?
    at com.sun.proxy.$Proxy58.onChange(Unknown Source)?
    at com.intellij.javascript.nodejs.packageJson.PackageJsonFileManager.lambda$publishEvent$1(PackageJsonFileManager.java:165)?
    at com.intellij.ui.AppUIUtil.invokeLaterIfProjectAlive(AppUIUtil.java:203)?
    at com.intellij.javascript.nodejs.packageJson.PackageJsonFileManager.publishEvent(PackageJsonFileManager.java:161)?
    at com.intellij.javascript.nodejs.packageJson.PackageJsonFileManager.onPackageJsonContentChanged(PackageJsonFileManager.java:157)?
    at com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager.handlePackageJsonContentChanged(NodeModulesDirectoryManager.java:435)?
    at com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager$MyFileListener.fileContentChanged(NodeModulesDirectoryManager.java:712)?
    at com.intellij.javascript.nodejs.library.NodeModulesDirectoryManager$MyFileListener$2.afterVfsChange(NodeModulesDirectoryManager.java:611)?
    at com.intellij.openapi.vfs.newvfs.AsyncEventSupport.afterVfsChange(AsyncEventSupport.java:139)?
    at com.intellij.openapi.vfs.newvfs.AsyncEventSupport$1.after(AsyncEventSupport.java:70)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeMethod(MessageBusImpl.java:645)?
    at com.intellij.util.messages.impl.MessageBusImpl.invokeListener(MessageBusImpl.java:624)?
    at com.intellij.util.messages.impl.MessageBusImpl.deliverMessage(MessageBusImpl.java:417)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpWaitingBuses(MessageBusImpl.java:390)?
    at com.intellij.util.messages.impl.MessageBusImpl.pumpMessages(MessageBusImpl.java:372)?
    at com.intellij.util.messages.impl.MessageBusImpl.access$200(MessageBusImpl.java:33)?
    at com.intellij.util.messages.impl.MessageBusImpl$MessagePublisher.invoke(MessageBusImpl.java:179)?
    at com.sun.proxy.$Proxy56.after(Unknown Source)?
    at com.intellij.openapi.vfs.newvfs.persistent.PersistentFSImpl.fireAfterEvents(PersistentFSImpl.java:1189)?
    at com.intellij.openapi.vfs.newvfs.persistent.PersistentFSImpl$3.close(PersistentFSImpl.java:789)?
    at com.intellij.openapi.vfs.newvfs.impl.VirtualFileImpl.setBinaryContent(VirtualFileImpl.java:159)?
    at com.intellij.openapi.vfs.VfsUtilCore.copyFile(VfsUtilCore.java:242)?
    at com.intellij.openapi.vfs.VfsUtilCore.copyFile(VfsUtilCore.java:225)?
    at com.intellij.openapi.vfs.VfsUtil.copyDirectory(VfsUtil.java:76)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1.compute(LightTempDirTestFixtureImpl.java:92)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl$1.compute(LightTempDirTestFixtureImpl.java:79)?
    at com.intellij.openapi.application.impl.ApplicationImpl.lambda$runWriteAction$15(ApplicationImpl.java:945)?
    at com.intellij.openapi.application.impl.ApplicationImpl.runWriteActionWithClass(ApplicationImpl.java:924)?
    at com.intellij.openapi.application.impl.ApplicationImpl.runWriteAction(ApplicationImpl.java:945)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl.copyAll(LightTempDirTestFixtureImpl.java:79)?
    at com.intellij.testFramework.fixtures.impl.LightTempDirTestFixtureImpl.copyAll(LightTempDirTestFixtureImpl.java:74)?
    at com.intellij.testFramework.fixtures.impl.CodeInsightTestFixtureImpl.copyDirectoryToProject(CodeInsightTestFixtureImpl.java:453)?
    at com.emberjs.index.EmberNameIndexTest.doTest(EmberNameIndexTest.kt:17)?
    at com.emberjs.index.EmberNameIndexTest.testExample(EmberNameIndexTest.kt:29)?
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)?
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)?
    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)?
    at java.base/java.lang.reflect.Method.invoke(Method.java:566)?
    at junit.framework.TestCase.runTest(TestCase.java:176)?
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$11(UsefulTestCase.java:533)?
    at com.intellij.testFramework.UsefulTestCase.lambda$wrapTestRunnable$13(UsefulTestCase.java:553)?
    at com.intellij.testFramework.UsefulTestCase.runTestRunnable(UsefulTestCase.java:465)?
    at com.intellij.testFramework.fixtures.BasePlatformTestCase.runTestRunnable(BasePlatformTestCase.java:106)?
    at com.intellij.testFramework.UsefulTestCase.defaultRunBare(UsefulTestCase.java:481)?
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$12(UsefulTestCase.java:541)?
    at com.intellij.testFramework.EdtTestUtil.lambda$runInEdtAndWait$1(EdtTestUtil.java:40)?
    at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:303)?
    at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:770)?
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:721)?
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:715)?
    at java.base/java.security.AccessController.doPrivileged(Native Method)?
    at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:85)?
    at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:740)?
    at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:408)?
    at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:203)?
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:124)?
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:113)?
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:109)?
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)?
    at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:90)?
?
09:03:44,687 DEBUG riptExternalDefinitionsService - Install type definitions for project [light_temp_1wymuHjBvLD21TimMMFiCODhy02] started?
09:03:44,687 DEBUG riptExternalDefinitionsService - Install type definitions for project [light_temp_1wymuHjBvLD21TimMMFiCODhy02] completed?
09:03:44,706 DEBUG .impl.FileTypeDetectionService - temp:///src/.ember-cli; type=Unknown; 1?
09:03:44,714 INFO  il.indexing.FileBasedIndexImpl - Rebuild requested for index ember.names?
java.lang.Throwable?
    at com.intellij.util.indexing.FileBasedIndex.requestRebuild(FileBasedIndex.java:83)?
    at com.emberjs.index.EmberNameIndexTest.doTest(EmberNameIndexTest.kt:22)?
    at com.emberjs.index.EmberNameIndexTest.testExample(EmberNameIndexTest.kt:29)?
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)?
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)?
    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)?
    at java.base/java.lang.reflect.Method.invoke(Method.java:566)?
    at junit.framework.TestCase.runTest(TestCase.java:176)?
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$11(UsefulTestCase.java:533)?
    at com.intellij.testFramework.UsefulTestCase.lambda$wrapTestRunnable$13(UsefulTestCase.java:553)?
    at com.intellij.testFramework.UsefulTestCase.runTestRunnable(UsefulTestCase.java:465)?
    at com.intellij.testFramework.fixtures.BasePlatformTestCase.runTestRunnable(BasePlatformTestCase.java:106)?
    at com.intellij.testFramework.UsefulTestCase.defaultRunBare(UsefulTestCase.java:481)?
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$12(UsefulTestCase.java:541)?
    at com.intellij.testFramework.EdtTestUtil.lambda$runInEdtAndWait$1(EdtTestUtil.java:40)?
    at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:303)?
    at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:770)?
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:721)?
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:715)?
    at java.base/java.security.AccessController.doPrivileged(Native Method)?
    at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:85)?
    at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:740)?
    at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:408)?
    at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:203)?
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:124)?
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:113)?
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:109)?
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)?
    at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:90)?
?
09:03:44,715 DEBUG il.indexing.FileBasedIndexImpl - Rewriting D:\Projects\intellij-emberjs\.sandbox\system-test\index\ember.names\ember.names.ver,4?
09:03:44,716 DEBUG penapi.project.DumbServiceImpl - Scheduling task UnindexedFilesUpdater[light_temp_1wymuHjBvLD21TimMMFiCODhy02]?
09:03:44,717 INFO  indexing.UnindexedFilesUpdater - Started indexing of light_temp_1wymuHjBvLD21TimMMFiCODhy02. Reason: Rebuild requested for index ember.names?
09:03:44,717 INFO  indexing.UnindexedFilesUpdater - Performing delayed pushing properties tasks for light_temp_1wymuHjBvLD21TimMMFiCODhy02 took 0ms; general responsiveness: ok; EDT responsiveness: ok?
09:03:44,718 INFO  indexing.UnindexedFilesUpdater - Scanning of light_temp_1wymuHjBvLD21TimMMFiCODhy02 uses 16 scanning threads?
09:03:44,748 INFO  indexing.UnindexedFilesUpdater - Scanning completed for light_temp_1wymuHjBvLD21TimMMFiCODhy02. Number of scanned files: 305; Number of files for indexing: 7 took 31ms; general responsiveness: ok; EDT responsiveness: ok?
09:03:44,778 INFO  indexing.UnindexedFilesUpdater - Use 4 indexing threads for indexing of light_temp_1wymuHjBvLD21TimMMFiCODhy02?
09:03:44,967 DEBUG colors.impl.ValueElementReader - short color value: 0?
09:03:44,967 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,967 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,967 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,967 DEBUG colors.impl.ValueElementReader - short color value: 000?
09:03:44,969 DEBUG colors.impl.ValueElementReader - short color value: 000?
09:03:44,969 DEBUG colors.impl.ValueElementReader - short color value: 0?
09:03:44,969 DEBUG colors.impl.ValueElementReader - short color value: 80?
09:03:44,969 DEBUG colors.impl.ValueElementReader - short color value: ff?
09:03:44,969 DEBUG colors.impl.ValueElementReader - short color value: ff?
09:03:44,970 DEBUG colors.impl.ValueElementReader - short color value: ff?
09:03:44,972 DEBUG colors.impl.ValueElementReader - short color value: 0?
09:03:44,973 DEBUG colors.impl.ValueElementReader - short color value: 80?
09:03:44,973 DEBUG colors.impl.ValueElementReader - short color value: 6666?
09:03:44,973 DEBUG colors.impl.ValueElementReader - short color value: ff?
09:03:44,973 DEBUG colors.impl.ValueElementReader - short color value: 8000?
09:03:44,973 DEBUG colors.impl.ValueElementReader - short color value: 80?
09:03:44,973 DEBUG colors.impl.ValueElementReader - short color value: 8000?
09:03:44,974 DEBUG colors.impl.ValueElementReader - short color value: 0?
09:03:44,975 DEBUG colors.impl.ValueElementReader - short color value: 80?
09:03:44,976 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,976 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,976 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,976 DEBUG colors.impl.ValueElementReader - empty attribute: value?
09:03:44,977 DEBUG colors.impl.ValueElementReader - short color value: f9795?
09:03:44,983 DEBUG colors.impl.ValueElementReader - short color value: 0?
09:03:44,983 DEBUG colors.impl.ValueElementReader - short color value: ff?
09:03:45,229 DEBUG i.stubs.impl.JSFileStubBuilder - Building stub for /src/app/controllers/application.js took 127ms, language: Language: ECMAScript 6?
09:03:45,229 DEBUG i.stubs.impl.JSFileStubBuilder - Building stub for /src/tests/acceptance/user-page-test.js took 127ms, language: Language: ECMAScript 6?
09:03:45,229 DEBUG i.stubs.impl.JSFileStubBuilder - Building stub for /src/tests/unit/helpers/format-number-test.js took 127ms, language: Language: ECMAScript 6?
09:03:45,229 DEBUG i.stubs.impl.JSFileStubBuilder - Building stub for /src/app/routes/index.js took 127ms, language: Language: ECMAScript 6?
09:03:45,239 DEBUG ij.psi.stubs.StubUpdatingIndex - Stub is built for temp:///src/app/routes/index.js, indexing timestamp = 9, binary = false, byte size = 0, char size = 0?
09:03:45,239 DEBUG ij.psi.stubs.StubUpdatingIndex - Stub is built for temp:///src/tests/acceptance/user-page-test.js, indexing timestamp = 30, binary = false, byte size = 0, char size = 0?
09:03:45,239 DEBUG ij.psi.stubs.StubUpdatingIndex - Stub is built for temp:///src/tests/unit/helpers/format-number-test.js, indexing timestamp = 26, binary = false, byte size = 0, char size = 0?
09:03:45,239 DEBUG ij.psi.stubs.StubUpdatingIndex - Stub is built for temp:///src/app/controllers/application.js, indexing timestamp = 20, binary = false, byte size = 0, char size = 0?
09:03:45,246 DEBUG i.stubs.impl.JSFileStubBuilder - Building stub for /src/app/controllers/user/index.js took 0ms, language: Language: ECMAScript 6?
09:03:45,246 DEBUG ij.psi.stubs.StubUpdatingIndex - Stub is built for temp:///src/app/controllers/user/index.js, indexing timestamp = 17, binary = false, byte size = 0, char size = 0?
09:03:45,256 DEBUG i.stubs.impl.JSFileStubBuilder - Building stub for /src/app/controllers/user/new.ts took 0ms, language: Language: TypeScript?
09:03:45,256 DEBUG ij.psi.stubs.StubUpdatingIndex - Stub is built for temp:///src/app/controllers/user/new.ts, indexing timestamp = 14, binary = false, byte size = 0, char size = 0?
09:03:45,260 INFO  indexing.UnindexedFilesUpdater - Finished for light_temp_1wymuHjBvLD21TimMMFiCODhy02. Unindexed files update took 496ms; general responsiveness: ok; EDT responsiveness: ok?

And this is what I have as std output if I simply change the platform version back to 2021.1

0    [Test worker] INFO  #com.intellij.ui.scale.JBUIScale  - System scale factor: 1.0 (IDE-managed HiDPI)
198  [AWT-EventQueue-0] INFO  #com.intellij.testFramework.TestApplicationManager  - CPU cores: 16; ForkJoinPool.commonPool: java.util.concurrent.ForkJoinPool@45e7ed06[Running, parallelism = 16, size = 0, active = 0, running = 0, steals = 0, tasks = 0, submissions = 0]; factory: com.intellij.concurrency.IdeaForkJoinWorkerThreadFactory@a9ffe2
442  [ApplicationImpl pooled thread 1] INFO  #com.intellij.ide.plugins.PluginManager  - D:\Projects\intellij-emberjs\.sandbox\plugins-test\Ember.js overrides D:\Projects\intellij-emberjs\build\resources\main
447  [ApplicationImpl pooled thread 1] INFO  #com.intellij.ide.plugins.PluginManager  - D:\Projects\intellij-emberjs\.sandbox\plugins-test\handlebars overrides C:\Users\andrey\.gradle\caches\modules-2\files-2.1\com.jetbrains.intellij.idea\unzipped.com.jetbrains.plugins\com.dmarcotte.handlebars-211.6693.44\handlebars
477  [ApplicationImpl pooled thread 1] INFO  #com.intellij.ide.plugins.PluginManager  - Loaded bundled plugins: IDEA CORE (211.6693.111), com.intellij.platform.images (211.6693.111), CSS (211.6693.111), JavaScript and TypeScript (211.6693.111), YAML (211.6693.111)
477  [ApplicationImpl pooled thread 1] INFO  #com.intellij.ide.plugins.PluginManager  - Loaded custom plugins: Handlebars/Mustache (211.6693.44), Ember.js (2021.2.1)
981  [ApplicationImpl pooled thread 14] WARN  #com.intellij.internal.DebugAttachDetector  - Unable to start DebugAttachDetector, please add `--add-exports java.base/jdk.internal.vm=ALL-UNNAMED` to VM options
994  [ApplicationImpl pooled thread 12] INFO  #com.intellij.application.options.PathMacrosImpl  - Loaded path macros: {}
1032 [ApplicationImpl pooled thread 4] INFO  #com.intellij.util.io.StorageLock  - lower=100; upper=492; buffer=10; max=492
1045 [ApplicationImpl pooled thread 4] INFO  #com.intellij.util.io.FileChannelUtil  - uninterruptible FileChannels will be used for indexes
1065 [ApplicationImpl pooled thread 7] INFO  #com.intellij.openapi.util.io.win32.IdeaWin32  - Native filesystem for Windows is operational
1100 [ApplicationImpl pooled thread 7] INFO  #com.intellij.openapi.vfs.newvfs.persistent.PersistentFSConnector  - Filesystem storage is corrupted or does not exist. [Re]Building. Reason: FS repository version mismatch: actual=52027 expected=52026
1124 [ApplicationImpl pooled thread 7] INFO  #com.intellij.openapi.vfs.newvfs.persistent.PersistentFSConnector  - Marking VFS as corrupted: 'D:\Projects\intellij-emberjs\.sandbox\system-test\caches\names.dat' does not exist
1142 [ApplicationImpl pooled thread 17] INFO  #com.intellij.util.net.ssl.CertificateManager  - Default SSL context initialized
1214 [AWT-EventQueue-0] INFO  #com.intellij.workspaceModel.ide.impl.legacyBridge.LegacyBridgeProjectLifecycleListener  - Using workspace model to open project
1367 [Index Data Initializer Pool] INFO  #com.intellij.psi.stubs.SerializationManagerImpl  - Name storage is repaired
1486 [AWT-EventQueue-0] INFO  #com.intellij.workspaceModel.ide.impl.jps.serialization.JpsProjectModelSynchronizer  - Workspace model loaded without cache. Loading real project state into workspace model. Thread[AWT-EventQueue-0,6,main]
1589 [File Watcher] INFO  #com.intellij.openapi.vfs.impl.local.NativeFileWatcherImpl  - Native file watcher is disabled
1593 [File Watcher] INFO  #com.intellij.openapi.vfs.impl.wsl.WslFileWatcher  - WSL file watcher: C:\Users\andrey\.gradle\caches\modules-2\files-2.1\com.jetbrains.intellij.idea\ideaIU\2021.1\fb73193bad7ac7619a8bfac40db2cb035e38bd13\ideaIU-2021.1\bin\fsnotifier-wsl
1702 [AWT-EventQueue-0] INFO  #com.intellij.jna.JnaLoader  - JNA library (64-bit) loaded in 43 ms
1714 [Index Data Initializer Pool] INFO  #com.intellij.util.indexing.FileBasedIndexImpl  - Indexes to be rebuilt after corruption:FilenameIndex(v = 3),FrameworkDetectionIndex(v = 65536),TodoIndex(v = 4107),IdIndex(v = 4113),filetypes(v = 3),Stubs(v = 46),Trigram.Index(v = 4099),fileIncludes(v = 6),DomFileIndex(v = 0),RelaxSymbolIndex(v = 0),XmlTagNames(v = 1),XmlNamespaces(v = 8),html5.custom.attributes.index(v = 1),SchemaTypeInheritance(v = 2),json.file.root.values(v = 5),HashFragmentIndex(v = 19115599),yaml.keys.name(v = 1),ember.names(v = 4),ember.i18n(v = 1),ember.intl(v = 1),CssIndex(v = 31),css.template.selectors(v = 30),js.test.names(v = 22),js.package.index(v = 12),js.custom.single.entry.index(v = 210),js.implicit.elements.index(v = 897),js.string.literal.words.index(v = 3),TypeScriptExternalPathCandidates(v = 0)
1717 [Index Data Initializer Pool] INFO  com.intellij.util.indexing.FileBasedIndexDataInitialization  - Index data initialization done: 548 ms. Initialized indexes: [FilenameIndex, FrameworkDetectionIndex, TodoIndex, IdIndex, filetypes, Trigram.Index, DomFileIndex, RelaxSymbolIndex, XmlTagNames, fileIncludes, html5.custom.attributes.index, XmlNamespaces, SchemaTypeInheritance, json.file.root.values, HashFragmentIndex, js.test.names, css.template.selectors, CssIndex, js.package.index, js.custom.single.entry.index, js.string.literal.words.index, js.implicit.elements.index, TypeScriptExternalPathCandidates, ember.names, yaml.keys.name, ember.i18n, ember.intl, Stubs].
1760 [Index Data Initializer Pool] INFO  #com.intellij.psi.stubs.StubIndexImpl  - Following stub indices will be built:js.lazy.packages(v = 3),js.gcl.modules(v = 2),js.doc.modules(v = 2),js.amd.modules(v = 2),dom.namespaceKey(v = 1),dom.elementClass(v = 0),Css.ampersand.selector(v = 2),Css.class(v = 2),Css.id(v = 2),Css.attr(v = 2),Css.custom.property(v = 2),Css.custom.mixin(v = 2),js.qualified.shortName(v = 4),js.element.qualifiedName(v = 3),JS.global.qualified.elements(v = 3),ts.external.module.index(v = 3),JS.class.super(v = 5),JS.class.implements(v = 3),JS.custom.stub.index(v = 2),js.symbol.index2(v = 6),js.typedef.index2(v = 2),js.generics.index(v = 2),JS.base.types.index(v = 3),js.class.index2(v = 3),js.namespace.index(v = 2),js.local.namespace.members.index(v = 2),JS.subclass.stub.index(v = 2),js.symbol.qualified.names.index(v = 1),amd.baseUrl.index(v = 2),amd.requirePaths.index(v = 2),js.global.symbol.index(v = 2),js.exported.index(v = 2),es6.exported.index(v = 2),js.imported.bindings.index(v = 2),es6.assignment.index(v = 2),ts.local.classes(v = 2),ts.embedded.content.index(v = 2),js.nonglobal.symbol.index(v = 2)
1762 [Index Data Initializer Pool] INFO  com.intellij.psi.stubs.StubIndexImpl$StubIndexInitialization  - Index data initialization done: 44 ms. Initialized stub indexes: {Css.class, es6.exported.index, Css.custom.mixin, js.class.index2, js.namespace.index, amd.baseUrl.index, js.symbol.qualified.names.index, js.amd.modules, ts.local.classes, js.gcl.modules, js.exported.index, js.imported.bindings.index, dom.namespaceKey, js.nonglobal.symbol.index, ts.embedded.content.index, dom.elementClass, js.lazy.packages, Css.attr, es6.assignment.index, js.element.qualifiedName, js.global.symbol.index, js.generics.index, JS.base.types.index, js.local.namespace.members.index, amd.requirePaths.index, JS.subclass.stub.index, Css.ampersand.selector, Css.id, Css.custom.property, js.qualified.shortName, js.typedef.index2, JS.global.qualified.elements, ts.external.module.index, js.symbol.index2, JS.custom.stub.index, JS.class.super, JS.class.implements, js.doc.modules}.
2167 [AWT-EventQueue-0] WARN  #com.intellij.internal.statistic.eventLog.StatisticsEventLogProviderUtil  - Cannot find event log provider with recorder-id=FUS
2174 [AWT-EventQueue-0] WARN  #com.intellij.internal.statistic.eventLog.EventLogConfiguration  - Failed retrieving device id for FUS
2223 [ApplicationImpl pooled thread 6] INFO  #com.intellij.openapi.application.Experiments  - Experimental features enabled for user: wsl.p9.support, wsl.prefer.p9.support, wsl.p9.show.roots.in.file.chooser, wsl.execute.with.wsl.exe, inline.browse.button, linux.native.menu, recent.and.edited.files.together, show.create.new.element.in.popup, search.everywhere.mixed.results, i18n.match.actions, editor.reader.mode, edit.run.configurations.while.dumb, new.large.text.file.viewer
2241 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Started
2279 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Pushing properties took 30ms; general responsiveness: ok; EDT responsiveness: ok
2303 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning: use 16 scanning threads
2512 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning completed. Number of scanned files: 291; Number of files for indexing: 291 took 233ms; general responsiveness: ok; EDT responsiveness: ok
2519 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Use 4 indexing threads
6308 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Finished. Unindexed files update took 3795ms; general responsiveness: ok; EDT responsiveness: ok
6429 [ApplicationImpl pooled thread 16] INFO  #com.intellij.openapi.externalSystem.service.project.manage.ExternalProjectsDataStorage  - Load external projects data in 2 millis (read time: 1)
6495 [ApplicationImpl pooled thread 16] INFO  #com.intellij.diagnostic.PerformanceWatcher  - Post-startup activities under progress took 166ms; general responsiveness: ok; EDT responsiveness: ok
6561 [AWT-EventQueue-0] INFO  #com.intellij.psi.codeStyle.ProjectCodeStyleSettingsManager  - Initialized from default code style settings.
6694 [AWT-EventQueue-0] INFO  #com.intellij.openapi.application.impl.ApplicationImpl  - LONG VFS PROCESSING. Topic=BulkFileListener, offender=class com.intellij.psi.impl.file.impl.PsiVFSListener$1, message=after, time=59ms
6822 [AWT-EventQueue-0] INFO  #com.intellij.openapi.application.impl.ApplicationImpl  - LONG VFS PROCESSING. Topic=BulkFileListener, offender=class com.intellij.openapi.vfs.newvfs.AsyncEventSupport$1, message=before, time=77ms
6904 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.FileBasedIndexImpl  - Rebuild requested for index ember.names
java.lang.Throwable
    at com.intellij.util.indexing.FileBasedIndex.requestRebuild(FileBasedIndex.java:83)
    at com.emberjs.index.EmberNameIndexTest.doTest(EmberNameIndexTest.kt:22)
    at com.emberjs.index.EmberNameIndexTest.testExample(EmberNameIndexTest.kt:29)
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.base/java.lang.reflect.Method.invoke(Method.java:566)
    at junit.framework.TestCase.runTest(TestCase.java:176)
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$10(UsefulTestCase.java:517)
    at com.intellij.testFramework.UsefulTestCase.lambda$wrapTestRunnable$12(UsefulTestCase.java:537)
    at com.intellij.testFramework.UsefulTestCase.runTestRunnable(UsefulTestCase.java:449)
    at com.intellij.testFramework.fixtures.BasePlatformTestCase.runTestRunnable(BasePlatformTestCase.java:108)
    at com.intellij.testFramework.UsefulTestCase.defaultRunBare(UsefulTestCase.java:465)
    at com.intellij.testFramework.UsefulTestCase.lambda$runBare$11(UsefulTestCase.java:525)
    at com.intellij.testFramework.EdtTestUtil.lambda$runInEdtAndWait$1(EdtTestUtil.java:40)
    at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:303)
    at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:770)
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:721)
    at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:715)
    at java.base/java.security.AccessController.doPrivileged(Native Method)
    at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:85)
    at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:740)
    at com.intellij.ide.IdeEventQueue.dispatchEvent(IdeEventQueue.java:419)
    at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:203)
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:124)
    at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:113)
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:109)
    at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
    at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:90)
6908 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.FileBasedIndexImpl  - schedule index re-scanning, reason: checkRebuild
6909 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Started
6910 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Pushing properties took 1ms; general responsiveness: ok; EDT responsiveness: ok
6922 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning: use 16 scanning threads
6957 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Scanning completed. Number of scanned files: 299; Number of files for indexing: 7 took 36ms; general responsiveness: ok; EDT responsiveness: ok
6957 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Use 4 indexing threads
7057 [AWT-EventQueue-0] INFO  #com.intellij.util.indexing.UnindexedFilesUpdater  - Finished. Unindexed files update took 100ms; general responsiveness: ok; EDT responsiveness: ok

Just wondering if you guys can spot anything unusual. My understanding is that this UnindexedFilesUpdater works a bit differently because it gives different output.

YannCebron commented 3 years ago

com.emberjs.index.EmberNameIndex: AFAIU it depends on directory structure/contents by checking containing directory of currently indexed file to contain package.json file (com.emberjs.utils.VirtualFileExtensionsKt#getParentEmberModule) and then using data from it (com.emberjs.resolver.EmberName.Companion#from(com.intellij.openapi.vfs.VirtualFile, com.intellij.openapi.vfs.VirtualFile))

this violates contract as described here https://plugins.jetbrains.com/docs/intellij/file-based-indexes.html#implementing-a-file-based-index:

The data returned by DataIndexer.map() must depend only on input data passed to the method, and must not depend on any external files. Otherwise, your index will not be correctly updated when the external data changes, and you will have stale data in your index.

I assume that's the reason all tests force explicit re-index, otherwise indexed test data will be stale or not present

[TBC]

Turbo87 commented 3 years ago

@YannCebron any suggestions on how else to implement this? it had been working quite well this way until now 😅

YannCebron commented 3 years ago

@Turbo87 I've pinged colleagues about it, will update ASAP

tomblachut commented 3 years ago

Issue has been resolved on Ember plugin side, I think this issue can be closed.