[squeak-dev] MC tests timed out

Nicolas Cellier nicolas.cellier.aka.nice at gmail.com
Sat Sep 21 20:15:26 UTC 2013


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
> >
> >
> >
> >
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.squeakfoundation.org/pipermail/squeak-dev/attachments/20130921/6ffbb578/attachment-0001.htm


More information about the Squeak-dev mailing list