[squeak-dev] MC tests timed out

Chris Muller ma.chris.m at gmail.com
Sat Sep 21 20:32:57 UTC 2013


Is that for a directory with > 5K files?  I'm judging my perception of
what it _used_ to be.

On Sat, Sep 21, 2013 at 3:15 PM, Nicolas Cellier
<nicolas.cellier.aka.nice at gmail.com> wrote:
> Not specially here, [FileList2 open] timeToRun -> 349, same kind of timing
> with FileList
>
>
> 2013/9/21 Chris Muller <asqueaker at gmail.com>
>>
>> Has anyone else felt that opening a File browser takes a log longer
>> than it used to (like, earlier this year)?
>>
>> On Sat, Sep 21, 2013 at 3:06 PM, Nicolas Cellier
>> <nicolas.cellier.aka.nice at gmail.com> wrote:
>> > Some MC tests are failing for time out reason.
>> > As shown below #allFileNames seems the main contributor...
>> >
>> > MessageTally spyOn: [MCWorkingCopyTest new setUp; testBackport;
>> > tearDown]
>> >
>> >
>> >  - 10700 tallies, 10708 msec.
>> >
>> > **Tree**
>> > --------------------------------
>> > Process: other processes
>> > --------------------------------
>> > 2.9% {312ms} WeakArray class>>finalizationProcess
>> > --------------------------------
>> > Process: (40s) 43306: nil
>> > --------------------------------
>> > 47.4% {5081ms} MCWorkingCopyTest>>snapshot
>> >   |47.4% {5081ms} MCWorkingCopy>>newVersion
>> >   |  34.3% {3672ms} MCWorkingCopy>>newVersionWithName:message:
>> >   |    |31.3% {3357ms} MCVersion
>> > class>>package:info:snapshot:dependencies:
>> >   |    |  |31.3% {3357ms}
>> > MCVersion>>initializeWithPackage:info:snapshot:dependencies:
>> >   |    |  |  31.3% {3357ms} MCVersion>>addToCache
>> >   |    |  |    31.3% {3355ms}
>> > MCCacheRepository(MCRepository)>>storeVersion:
>> >   |    |  |      16.5% {1763ms}
>> > MCCacheRepository(MCRepository)>>sendNotificationsForVersion:
>> >   |    |  |        |16.5% {1763ms}
>> > MCCacheRepository(MCFileBasedRepository)>>notifyList
>> >   |    |  |        |  15.9% {1700ms}
>> > MCCacheRepository(MCDirectoryRepository)>>allFileNames
>> >   |    |  |        |    15.6% {1671ms}
>> > UnixFileDirectory(FileDirectory)>>entries
>> >   |    |  |        |      15.6% {1671ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >   |    |  |        |        15.6% {1667ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >   |    |  |        |          15.1% {1617ms} DirectoryEntry
>> > class>>fromArray:directory:
>> >   |    |  |        |            15.1% {1615ms} primitives
>> >   |    |  |      14.9% {1591ms} MCCacheRepository>>basicStoreVersion:
>> >   |    |  |        14.2% {1521ms}
>> > MCCacheRepository(MCDirectoryRepository)>>allFileNames
>> >   |    |  |          13.9% {1493ms}
>> > UnixFileDirectory(FileDirectory)>>entries
>> >   |    |  |            13.9% {1493ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >   |    |  |              13.9% {1491ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >   |    |  |                13.5% {1450ms} DirectoryEntry
>> > class>>fromArray:directory:
>> >   |    |  |                  13.5% {1446ms} primitives
>> >   |    |2.9% {305ms} MCPackage>>snapshot
>> >   |    |  2.8% {303ms} MCMockPackageInfo(PackageInfo)>>overriddenMethods
>> >   |    |    2.8% {303ms}
>> > MCMockPackageInfo(PackageInfo)>>overriddenMethodsDo:
>> >   |    |      2.6% {275ms}
>> > MCMockPackageInfo(PackageInfo)>>isOverrideOfYourMethod:
>> >   |    |        2.6% {275ms}
>> > MCMockPackageInfo(PackageInfo)>>changeRecordForOverriddenMethod:
>> >   |    |          2.6% {275ms}
>> > MCMockPackageInfo(PackageInfo)>>changeRecordsForMethod:do:
>> >   |    |            2.5% {267ms}
>> > PackageOrganizer>>packageOfMethodCategory:ofClass:ifNone:
>> >   |    |              2.5% {267ms}
>> > PackageInfo>>includesMethodCategory:ofClassNamed:
>> >   |    |                2.5% {267ms} PackageInfo>>includesClassNamed:
>> >   |    |                  2.5% {267ms}
>> > SystemOrganizer(Categorizer)>>categoryOfElement:
>> >   |    |                    2.5% {267ms}
>> > SystemOrganizer(Categorizer)>>numberOfCategoryOfElement:
>> >   |    |                      1.9% {203ms} primitives
>> >   |  13.2% {1409ms} MCWorkingCopy>>patchMessageSuggestion
>> >   |    13.2% {1409ms} MCWorkingCopy>>patchMessageChanges
>> >   |      10.1% {1084ms} MCWorkingCopy>>findSnapshotWithVersionInfo:
>> >   |        |10.1% {1084ms} MCRepositoryGroup>>versionWithInfo:
>> >   |        |  10.1% {1084ms} MCRepositoryGroup>>versionWithInfo:ifNone:
>> >   |        |    10.1% {1082ms}
>> > MCCacheRepository(MCRepository)>>versionWithInfo:
>> >   |        |      10.1% {1082ms}
>> > MCCacheRepository(MCFileBasedRepository)>>versionWithInfo:ifAbsent:
>> >   |        |        10.1% {1082ms}
>> > MCCacheRepository(MCFileBasedRepository)>>allFileNamesForVersionNamed:
>> >   |        |          10.0% {1067ms}
>> > MCCacheRepository(MCFileBasedRepository)>>readableFileNames
>> >   |        |            9.5% {1018ms}
>> > MCCacheRepository(MCFileBasedRepository)>>allFileNamesOrCache
>> >   |        |              9.5% {1018ms}
>> > MCCacheRepository(MCDirectoryRepository)>>allFileNames
>> >   |        |                9.4% {1002ms}
>> > UnixFileDirectory(FileDirectory)>>entries
>> >   |        |                  9.4% {1002ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >   |        |                    9.3% {997ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >   |        |                      9.1% {973ms} DirectoryEntry
>> > class>>fromArray:directory:
>> >   |      3.0% {321ms} MCPackage>>snapshot
>> >   |        2.9% {305ms}
>> > MCMockPackageInfo(PackageInfo)>>overriddenMethods
>> >   |          2.9% {305ms}
>> > MCMockPackageInfo(PackageInfo)>>overriddenMethodsDo:
>> >   |            2.6% {283ms}
>> > MCMockPackageInfo(PackageInfo)>>isOverrideOfYourMethod:
>> >   |              2.6% {283ms}
>> > MCMockPackageInfo(PackageInfo)>>changeRecordForOverriddenMethod:
>> >   |                2.6% {283ms}
>> > MCMockPackageInfo(PackageInfo)>>changeRecordsForMethod:do:
>> >   |                  2.6% {277ms}
>> > PackageOrganizer>>packageOfMethodCategory:ofClass:ifNone:
>> >   |                    2.6% {277ms}
>> > PackageInfo>>includesMethodCategory:ofClassNamed:
>> >   |                      2.6% {277ms} PackageInfo>>includesClassNamed:
>> >   |                        2.6% {277ms}
>> > SystemOrganizer(Categorizer)>>categoryOfElement:
>> >   |                          2.6% {277ms}
>> > SystemOrganizer(Categorizer)>>numberOfCategoryOfElement:
>> >   |                            1.7% {183ms} primitives
>> > 22.6% {2415ms} MCWorkingCopy>>backportChangesTo:
>> >   20.2% {2162ms} MCRepositoryGroup>>versionWithInfo:
>> >     |20.2% {2162ms} MCRepositoryGroup>>versionWithInfo:ifNone:
>> >     |  20.2% {2162ms} MCCacheRepository(MCRepository)>>versionWithInfo:
>> >     |    20.2% {2162ms}
>> > MCCacheRepository(MCFileBasedRepository)>>versionWithInfo:ifAbsent:
>> >     |      20.2% {2162ms}
>> > MCCacheRepository(MCFileBasedRepository)>>allFileNamesForVersionNamed:
>> >     |        19.9% {2129ms}
>> > MCCacheRepository(MCFileBasedRepository)>>readableFileNames
>> >     |          19.1% {2043ms}
>> > MCCacheRepository(MCFileBasedRepository)>>allFileNamesOrCache
>> >     |            19.1% {2043ms}
>> > MCCacheRepository(MCDirectoryRepository)>>allFileNames
>> >     |              18.7% {2004ms}
>> > UnixFileDirectory(FileDirectory)>>entries
>> >     |                18.7% {2004ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >     |                  18.7% {2000ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >     |                    18.2% {1945ms} DirectoryEntry
>> > class>>fromArray:directory:
>> >     |                      18.1% {1939ms} primitives
>> >   2.3% {246ms} MCPackageLoader class>>updatePackage:withSnapshot:
>> >     1.9% {205ms} MCPackageLoader>>updatePackage:withSnapshot:
>> >       1.9% {201ms} MCPackage>>snapshot
>> >         1.8% {195ms} MCMockPackageInfo(PackageInfo)>>overriddenMethods
>> >           1.8% {195ms}
>> > MCMockPackageInfo(PackageInfo)>>overriddenMethodsDo:
>> >             1.7% {181ms}
>> > MCMockPackageInfo(PackageInfo)>>isOverrideOfYourMethod:
>> >               1.7% {181ms}
>> > MCMockPackageInfo(PackageInfo)>>changeRecordForOverriddenMethod:
>> >                 1.7% {181ms}
>> > MCMockPackageInfo(PackageInfo)>>changeRecordsForMethod:do:
>> >                   1.7% {179ms}
>> > PackageOrganizer>>packageOfMethodCategory:ofClass:ifNone:
>> >                     1.7% {179ms}
>> > PackageInfo>>includesMethodCategory:ofClassNamed:
>> >                       1.7% {179ms} PackageInfo>>includesClassNamed:
>> >                         1.7% {179ms}
>> > SystemOrganizer(Categorizer)>>categoryOfElement:
>> >                           1.7% {179ms}
>> > SystemOrganizer(Categorizer)>>numberOfCategoryOfElement:
>> > 9.1% {979ms} MCWorkingCopyTest(MCTestCase)>>tearDown
>> >   |9.1% {979ms} MCWorkingCopyTest>>clearPackageCache
>> >   |  4.6% {489ms} UnixFileDirectory(FileDirectory)>>fileNamesMatching:
>> >   |    |4.5% {485ms} UnixFileDirectory(FileDirectory)>>fileNames
>> >   |    |  4.5% {485ms} UnixFileDirectory(FileDirectory)>>entries
>> >   |    |    4.5% {485ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >   |    |      4.5% {483ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >   |    |        4.4% {469ms} DirectoryEntry class>>fromArray:directory:
>> >   |  4.5% {486ms} MCWorkingCopyTest(MCTestCase)>>clearPackageCache
>> >   |    4.5% {486ms} UnixFileDirectory(FileDirectory)>>fileNamesMatching:
>> >   |      4.5% {480ms} UnixFileDirectory(FileDirectory)>>fileNames
>> >   |        4.5% {480ms} UnixFileDirectory(FileDirectory)>>entries
>> >   |          4.5% {480ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >   |            4.5% {480ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >   |              4.4% {466ms} DirectoryEntry class>>fromArray:directory:
>> > 1.3% {139ms} MCWorkingCopyTest(MCTestCase)>>restoreMocks
>> >   1.3% {139ms} MCSnapshot>>updatePackage:
>> >     1.3% {139ms} MCPackageLoader class>>updatePackage:withSnapshot:
>> >       1.0% {108ms} MCPackageLoader>>updatePackage:withSnapshot:
>> > 9.1% {970ms} MCWorkingCopyTest>>clearPackageCache
>> >   4.5% {486ms} UnixFileDirectory(FileDirectory)>>fileNamesMatching:
>> >     |4.5% {482ms} UnixFileDirectory(FileDirectory)>>fileNames
>> >     |  4.5% {480ms} UnixFileDirectory(FileDirectory)>>entries
>> >     |    4.5% {480ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >     |      4.5% {478ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >     |        4.4% {466ms} DirectoryEntry class>>fromArray:directory:
>> >     |          4.3% {464ms} primitives
>> >   4.5% {483ms} MCWorkingCopyTest(MCTestCase)>>clearPackageCache
>> >     4.5% {483ms} UnixFileDirectory(FileDirectory)>>fileNamesMatching:
>> >       4.5% {479ms} UnixFileDirectory(FileDirectory)>>fileNames
>> >         4.5% {479ms} UnixFileDirectory(FileDirectory)>>entries
>> >           4.5% {479ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:
>> >             4.5% {479ms}
>> > UnixFileDirectory(FileDirectory)>>directoryContentsFor:do:
>> >               4.4% {471ms} DirectoryEntry class>>fromArray:directory:
>> > 6.6% {708ms} PasteUpMorph>>displayWorld
>> >   6.6% {708ms} PasteUpMorph>>privateOuterDisplayWorld
>> >     6.6% {708ms} WorldState>>displayWorld:submorphs:
>> >       6.0% {646ms} WorldState>>drawWorld:submorphs:invalidAreasOn:
>> >         5.9% {636ms} FormCanvas(Canvas)>>fullDrawMorph:
>> >           5.9% {636ms} FormCanvas(Canvas)>>fullDraw:
>> >             5.9% {636ms} PluggableSystemWindow(Morph)>>fullDrawOn:
>> >               2.8% {295ms}
>> > PluggableSystemWindow(Morph)>>drawSubmorphsOn:
>> >                 |2.8% {295ms} FormCanvas(Canvas)>>fullDrawMorph:
>> >                 |  2.8% {295ms} FormCanvas(Canvas)>>fullDraw:
>> >                 |    2.7% {289ms}
>> > PluggableListMorphPlus(Morph)>>fullDrawOn:
>> >                 |      2.0% {216ms}
>> > PluggableListMorphPlus(Morph)>>drawSubmorphsOn:
>> >                 |        2.0% {216ms} FormCanvas(Canvas)>>fullDrawMorph:
>> >                 |          2.0% {216ms} FormCanvas(Canvas)>>fullDraw:
>> >                 |            1.5% {159ms}
>> > PluggableTextMorphPlus(PluggableTextMorph)>>fullDrawOn:
>> >                 |              1.5% {157ms}
>> > PluggableTextMorphPlus(Morph)>>fullDrawOn:
>> >                 |                1.4% {151ms}
>> > PluggableTextMorphPlus(Morph)>>drawSubmorphsOn:
>> >                 |                  1.4% {151ms}
>> > FormCanvas(Canvas)>>fullDrawMorph:
>> >                 |                    1.4% {151ms}
>> > FormCanvas(Canvas)>>fullDraw:
>> >                 |                      1.4% {151ms}
>> > TransformMorph(Morph)>>fullDrawOn:
>> >                 |                        1.0% {110ms}
>> > TransformMorph>>drawSubmorphsOn:
>> >                 |                          1.0% {110ms}
>> > FormCanvas(Canvas)>>fullDrawMorph:
>> >                 |                            1.0% {110ms}
>> > FormCanvas(Canvas)>>fullDraw:
>> >                 |                              1.0% {110ms}
>> > TextMorphForEditView(Morph)>>fullDrawOn:
>> >                 |                                1.0% {108ms}
>> > FormCanvas(Canvas)>>drawMorph:
>> >                 |                                  1.0% {108ms}
>> > FormCanvas(Canvas)>>draw:
>> >                 |                                    1.0% {108ms}
>> > TextMorphForEditView(TextMorph)>>drawOn:
>> >                 |                                      1.0% {108ms}
>> > FormCanvas>>paragraph:bounds:color:
>> >                 |                                        1.0% {108ms}
>> > MultiNewParagraph>>displayOn:using:at:
>> >                 |                                          1.0% {108ms}
>> > MultiDisplayScanner>>displayLine:offset:leftInRun:
>> >               1.9% {199ms}
>> > PluggableSystemWindow(Morph)>>hasRolloverBorder
>> >               1.2% {132ms} FormCanvas(Canvas)>>drawMorph:
>> >                 1.2% {132ms} FormCanvas(Canvas)>>draw:
>> >                   1.2% {132ms} PluggableSystemWindow(Morph)>>drawOn:
>> >                     1.2% {132ms}
>> > FormCanvas(Canvas)>>fillRectangle:fillStyle:borderStyle:
>> >                       1.2% {128ms} FormCanvas>>fillRectangle:fillStyle:
>> >                         1.2% {128ms}
>> > FormCanvas>>balloonFillRectangle:fillStyle:
>> >                           1.2% {126ms}
>> > BalloonCanvas>>fillRectangle:fillStyle:
>> >                             1.2% {126ms}
>> > BalloonCanvas>>drawRectangle:color:borderWidth:borderColor:
>> >                               1.1% {122ms}
>> > BalloonEngine>>drawRectangle:fill:borderWidth:borderColor:transform:
>> >                                 1.1% {118ms}
>> > BalloonEngine>>postFlushIfNeeded
>> >                                   1.1% {118ms} BalloonEngine>>copyBits
>> >                                     1.1% {118ms}
>> > BalloonEngine>>copyLoopFaster
>> >
>> > **Leaves**
>> > 73.3% {7845ms} DirectoryEntry class>>fromArray:directory:
>> > 5.2% {558ms} SystemOrganizer(Categorizer)>>numberOfCategoryOfElement:
>> > 2.9% {312ms} WeakArray class>>finalizationProcess
>> > 2.2% {231ms} ByteString(String)>>=
>> > 1.9% {199ms} PluggableSystemWindow(Morph)>>hasRolloverBorder
>> > 1.5% {157ms} BalloonEngine>>copyLoopFaster
>> >
>> > **Memory**
>> >     old            -409,724 bytes
>> >     young        -292,264 bytes
>> >     used        -701,988 bytes
>> >     free        +370,736 bytes
>> >
>> > **GCs**
>> >     full            2 totalling 241 ms (2.25% uptime), avg 120.5 ms
>> >     incr            152 totalling 198 ms (1.8% uptime), avg 1.3 ms
>> >     tenures        38 (avg 4 GCs/tenure)
>> >     root table    0 overflows
>> >
>> >
>> >
>> >
>>
>


More information about the Squeak-dev mailing list