- Pharo-dev - lists.pharo.org

Curious context change latency experiment results (wrt DelayScheduler)
by Ben Coman May 28, 2018

May 28, 2018

One of the key parts of Delay scheduling is setting the resumption time. There are two places this could be done. a. In Delay>>#schedule, running (for example) at userSchedulingPriority=40 b. In DelayMicrosecondScheduler>>#handleTImerEvent:, running at timingPriority=80 When we were using the millisecond clock for delay scheduling, it made sense to use (b.) since the clock might(?) roll over between when resumption time was set, and when #handleTImerEvent: expires delays. This should not be a problem now that we are using the microsecond clock, so I wondered what the latency penalty might be between (a.) and (b.). I ran a little experiment that indicates the cost/latency of switching threads, and was curious if anyone can comment on the validity of the experiment and interpretation of results. I made the following three changes... DelayScheduler subclass: #DelayMicrosecondScheduler instanceVariableNames: 'latencyStart countSinceLastZero' classVariableNames: 'LatencyCounts' poolDictionaries: '' category: 'Kernel-Processes' DelayMicrosecondScheduler>>#schedule: aDelay latencyStart:= Time primUTCMicrosecondsClock. "This is position (a.)" aDelay schedulerBeingWaitedOn ifTrue: [^self error: 'This Delay has already been scheduled.']. accessProtect critical: [ scheduledDelay := aDelay. timingSemaphore signal. "#handleTimerEvent: sets scheduledDelay:=nil" ]. DelayMicrosecondScheduler>>#handleTimerEvent: microsecondNowTick | microsecondNextTick | "Process any schedule requests" "This is position (b.)" scheduledDelay ifNotNil: [ |latency| latency := Time primUTCMicrosecondsClock - latencyStart. LatencyCounts ifNil: [ LatencyCounts := Bag new ]. LatencyCounts add: latency. latency = 0 ifTrue: [ countSinceLastZero := 1 + (countSinceLastZero ifNil: [0])] ifFalse: [Transcript crShow: 'zero latency count ' , countSinceLastZero printString ; show: ' before latency ', latency printString. countSinceLastZero := 0]. "Schedule the given delay." scheduledDelay scheduler: self resumptionTime: microsecondNowTick + (1000 * scheduledDelay millisecondDelayDuration). self scheduleDelay: scheduledDelay. scheduledDelay := nil ]. <snip> rest of method unchanged Then opened the Transcript and in Playground evaluated... Delay delaySchedulerClass: DelayMicrosecondScheduler. The Transcript results are shown below with some comments inserted. Now I guess the latency is affected by garbage collection. But one thing I was curious about is why the latency's were quantised in 1000s. Another interesting thing is that vast majority of the latency's were zero, which was a nice surprise, but can it be true? Or is it a consequence of the quantitisation rounding down? It seems that the idle-ness of the image affected how often a non-zero latency occurred. After I left the house for a while, the count of zero latency was very high, but a few still occurred. It would make sense there was less GC while idle. What is a good snippet of code to stress GC. I presume the latency might increase. zero latency count 2273 before latency 1000 zero latency count 943 before latency 1000 zero latency count 3666 before latency 1000 zero latency count 1643 before latency 1000 zero latency count 27 before latency 1000 "Left house for 20 minutes" zero latency count 12022 before latency 1000 zero latency count 15195 before latency 1000 zero latency count 41998 before latency 1000 "Returned from outing" zero latency count 128 before latency 1000 zero latency count 116 before latency 1000 zero latency count 555 before latency 1000 zero latency count 2377 before latency 1000 zero latency count 5423 before latency 1000 zero latency count 3178 before latency 1000 zero latency count 47 before latency 1000 zero latency count 2276 before latency 1000 "Left house to go shopping" zero latency count 6708 before latency 3000 zero latency count 4896 before latency 1000 zero latency count 433 before latency 1000 zero latency count 7106 before latency 1000 zero latency count 2195 before latency 1000 zero latency count 12397 before latency 1000 zero latency count 4815 before latency 2000 zero latency count 3480 before latency 1000 zero latency count 5690 before latency 1000 zero latency count 1670 before latency 1000 zero latency count 320 before latency 1000 zero latency count 1641 before latency 2000 zero latency count 290 before latency 1000 zero latency count 3835 before latency 1000 zero latency count 3818 before latency 1000 zero latency count 1132 before latency 1000 "Returned from shopping." zero latency count 2045 before latency 1000 zero latency count 671 before latency 1000 zero latency count 167 before latency 1000 zero latency count 147 before latency 1000 >From the System Browser, inspecting the class variable LatencyCounts showed... 0 171329 3000 1 2000 3 1000 49 cheers -ben

2 4

[Pharo 7.0-dev] Build #973: 22008-Move-GTPlaygroundBasicTest-cleaning-to-tearDown
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #973 was: SUCCESS. The Pull Request #1451 was integrated: "22008-Move-GTPlaygroundBasicTest-cleaning-to-tearDown" Pull request url: https://github.com/pharo-project/pharo/pull/1451 Issue Url: https://pharo.fogbugz.com/f/cases/22008 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #972: 21815-shrink-for-OrderedCollection
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #972 was: FAILURE. The Pull Request #1428 was integrated: "21815-shrink-for-OrderedCollection" Pull request url: https://github.com/pharo-project/pharo/pull/1428 Issue Url: https://pharo.fogbugz.com/f/cases/21815 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #971: Format methods from the suggestion menu in a debugger leads to a DNU â¦
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #971 was: SUCCESS. The Pull Request #1423 was integrated: "Format methods from the suggestion menu in a debugger leads to a DNU â¦" Pull request url: https://github.com/pharo-project/pharo/pull/1423 Issue Url: https://pharo.fogbugz.com/f/cases/21377 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #970: 21997 Cleanup Tool-Diff package
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #970 was: SUCCESS. The Pull Request #1441 was integrated: "21997 Cleanup Tool-Diff package" Pull request url: https://github.com/pharo-project/pharo/pull/1441 Issue Url: https://pharo.fogbugz.com/f/cases/21997 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #969: 21964-SmalltalkImage-transforming-deprecations-for-old-API
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #969 was: SUCCESS. The Pull Request #1422 was integrated: "21964-SmalltalkImage-transforming-deprecations-for-old-API" Pull request url: https://github.com/pharo-project/pharo/pull/1422 Issue Url: https://pharo.fogbugz.com/f/cases/21964 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

2 1

[Pharo 7.0-dev] Build #968: 21990-isProbablyPrime-and-friends-should-not-refer-to-Transcript
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #968 was: FAILURE. The Pull Request #1435 was integrated: "21990-isProbablyPrime-and-friends-should-not-refer-to-Transcript" Pull request url: https://github.com/pharo-project/pharo/pull/1435 Issue Url: https://pharo.fogbugz.com/f/cases/21990 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #967: 22001 Cleanup System-Object Events package
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #967 was: FAILURE. The Pull Request #1444 was integrated: "22001 Cleanup System-Object Events package" Pull request url: https://github.com/pharo-project/pharo/pull/1444 Issue Url: https://pharo.fogbugz.com/f/cases/22001 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #966: 22002 Cleanup System-Platforms
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #966 was: SUCCESS. The Pull Request #1445 was integrated: "22002 Cleanup System-Platforms" Pull request url: https://github.com/pharo-project/pharo/pull/1445 Issue Url: https://pharo.fogbugz.com/f/cases/22002 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0

[Pharo 7.0-dev] Build #965: 22003-OSWindowDriver-shutdown-throws-DNU
by ci-pharo-ci-jenkins2＠inria.fr May 28, 2018

May 28, 2018

There is a new Pharo build available! The status of the build #965 was: SUCCESS. The Pull Request #1446 was integrated: "22003-OSWindowDriver-shutdown-throws-DNU" Pull request url: https://github.com/pharo-project/pharo/pull/1446 Issue Url: https://pharo.fogbugz.com/f/cases/22003 Build Url: https://ci.inria.fr/pharo-ci-jenkins2/job/Test%20pending%20pull%20request%2…

1 0