lumiera_/doc/devel/dump/2023-12-09.Scheduler-Integration/Dump-04
Ichthyostega da57e3dfcd Scheduler-test: ''can demonstrate running a synthetic load'' (closes #1346)
* added benchmark over synchronous execution as point of reference
 * verified running times and execution pattern
 * Scheduler **behaves as expected** for this example
2023-12-11 23:53:25 +01:00

510 lines
15 KiB
Text

invokeTestCase: ++------------------- invoking TEST: vault::gear::test::TestChainLoad_test
+++ 8F: seed(num:64)
ANCHOR=0 preRoll=5120
‖▷▷▷‖ 8F: @ -4805 EMPTY
‖IGN‖ wof:8
‖SCH‖ 8F: @-3381 ○ start=-4951 dead:100000
!◆! plan...to:63|curr=0 (max:64)
|n.(0,lev:0)
... dispose(i=0,lev:0) -> @0
‖•△•‖ wof:8 HT:195
‖SCH‖ 8F: @-3186 ○ start=0 dead:10000
|n.(1,lev:1)
... dispose(i=1,lev:1) -> @1000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-3103 ○ start=1000 dead:10000
|n.(2,lev:2)
... dispose(i=2,lev:2) -> @2000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-3021 ○ start=2000 dead:10000
|n.(3,lev:2)
... dispose(i=3,lev:2) -> @2000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2959 ○ start=2000 dead:10000
|n.(4,lev:3)
... dispose(i=4,lev:3) -> @3000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2895 ○ start=3000 dead:10000
|n.(5,lev:3)
... dispose(i=5,lev:3) -> @3000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2814 ○ start=3000 dead:10000
|n.(6,lev:3)
... dispose(i=6,lev:3) -> @3000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2749 ○ start=3000 dead:10000
|n.(7,lev:4)
... dispose(i=7,lev:4) -> @4000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2686 ○ start=4000 dead:10000
|n.(8,lev:4)
... dispose(i=8,lev:4) -> @4000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2625 ○ start=4000 dead:10000
|n.(9,lev:4)
... dispose(i=9,lev:4) -> @4000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2561 ○ start=4000 dead:10000
|n.(10,lev:4)
... dispose(i=10,lev:4) -> @4000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2496 ○ start=4000 dead:10000
|n.(11,lev:5)
... dispose(i=11,lev:5) -> @5000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2433 ○ start=5000 dead:10000
|n.(12,lev:5)
... dispose(i=12,lev:5) -> @5000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2370 ○ start=5000 dead:10000
|n.(13,lev:5)
... dispose(i=13,lev:5) -> @5000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2307 ○ start=5000 dead:10000
|n.(14,lev:5)
... dispose(i=14,lev:5) -> @5000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2246 ○ start=5000 dead:10000
|n.(15,lev:6)
... dispose(i=15,lev:6) -> @6000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2184 ○ start=6000 dead:10000
|n.(16,lev:6)
... dispose(i=16,lev:6) -> @6000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2123 ○ start=6000 dead:10000
|n.(17,lev:6)
... dispose(i=17,lev:6) -> @6000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2061 ○ start=6000 dead:10000
|n.(18,lev:6)
... dispose(i=18,lev:6) -> @6000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-2001 ○ start=6000 dead:10000
|n.(19,lev:6)
... dispose(i=19,lev:6) -> @6000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1889 ○ start=6000 dead:10000
|n.(20,lev:7)
... dispose(i=20,lev:7) -> @7000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1826 ○ start=7000 dead:10000
|n.(21,lev:7)
... dispose(i=21,lev:7) -> @7000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1747 ○ start=7000 dead:10000
|n.(22,lev:7)
... dispose(i=22,lev:7) -> @7000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1670 ○ start=7000 dead:10000
|n.(23,lev:7)
... dispose(i=23,lev:7) -> @7000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1607 ○ start=7000 dead:10000
|n.(24,lev:7)
... dispose(i=24,lev:7) -> @7000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1544 ○ start=7000 dead:10000
|n.(25,lev:8)
... dispose(i=25,lev:8) -> @8000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1483 ○ start=8000 dead:10000
|n.(26,lev:8)
... dispose(i=26,lev:8) -> @8000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1411 ○ start=8000 dead:10000
|n.(27,lev:8)
... dispose(i=27,lev:8) -> @8000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1345 ○ start=8000 dead:10000
|n.(28,lev:8)
... dispose(i=28,lev:8) -> @8000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1268 ○ start=8000 dead:10000
|n.(29,lev:8)
... dispose(i=29,lev:8) -> @8000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1184 ○ start=8000 dead:10000
|n.(30,lev:9)
... dispose(i=30,lev:9) -> @9000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1115 ○ start=9000 dead:10000
|n.(31,lev:9)
... dispose(i=31,lev:9) -> @9000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-1047 ○ start=9000 dead:10000
|n.(32,lev:9)
... dispose(i=32,lev:9) -> @9000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-980 ○ start=9000 dead:10000
|n.(33,lev:9)
... dispose(i=33,lev:9) -> @9000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-914 ○ start=9000 dead:10000
|n.(34,lev:9)
... dispose(i=34,lev:9) -> @9000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-846 ○ start=9000 dead:10000
|n.(35,lev:10)
... dispose(i=35,lev:10) -> @10000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-773 ○ start=10000 dead:10000
|n.(36,lev:10)
... dispose(i=36,lev:10) -> @10000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-709 ○ start=10000 dead:10000
|n.(37,lev:10)
... dispose(i=37,lev:10) -> @10000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-645 ○ start=10000 dead:10000
|n.(38,lev:10)
... dispose(i=38,lev:10) -> @10000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-585 ○ start=10000 dead:10000
|n.(39,lev:10)
... dispose(i=39,lev:10) -> @10000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-523 ○ start=10000 dead:10000
|n.(40,lev:11)
... dispose(i=40,lev:11) -> @11000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-460 ○ start=11000 dead:10000
|n.(41,lev:11)
... dispose(i=41,lev:11) -> @11000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-398 ○ start=11000 dead:10000
|n.(42,lev:11)
... dispose(i=42,lev:11) -> @11000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-334 ○ start=11000 dead:10000
|n.(43,lev:11)
... dispose(i=43,lev:11) -> @11000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-270 ○ start=11000 dead:10000
|n.(44,lev:11)
... dispose(i=44,lev:11) -> @11000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-207 ○ start=11000 dead:10000
|n.(45,lev:12)
... dispose(i=45,lev:12) -> @12000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-147 ○ start=12000 dead:10000
|n.(46,lev:12)
... dispose(i=46,lev:12) -> @12000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-86 ○ start=12000 dead:10000
|n.(47,lev:12)
... dispose(i=47,lev:12) -> @12000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @-24 ○ start=12000 dead:10000
|n.(48,lev:12)
... dispose(i=48,lev:12) -> @12000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @36 ○ start=12000 dead:10000
|n.(49,lev:12)
... dispose(i=49,lev:12) -> @12000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @98 ○ start=12000 dead:10000
|n.(50,lev:13)
... dispose(i=50,lev:13) -> @13000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @160 ○ start=13000 dead:10000
|n.(51,lev:13)
... dispose(i=51,lev:13) -> @13000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @222 ○ start=13000 dead:10000
|n.(52,lev:13)
... dispose(i=52,lev:13) -> @13000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @283 ○ start=13000 dead:10000
|n.(53,lev:13)
... dispose(i=53,lev:13) -> @13000
‖•△•‖ wof:8 HT:0
‖SCH‖ 8F: @359 ○ start=13000 dead:10000
·‖ 6A: @ 350 HT:195 -> ▶ 0
|n.(54,lev:13)
!◆! 6A: calc(i=0, lev:0)
... dispose(i=54,lev:13) -> @13000
‖•△•‖ wof:8 HT:195
‖SCH‖ 8F: @451 ○ start=13000 dead:10000
|n.(55,lev:14)
... dispose(i=55,lev:14) -> @14000
‖•△•‖ wof:8 HT:195
‖SCH‖ 8F: @520 ○ start=14000 dead:10000
!◆! 6A: calc(i=1, lev:1)
|n.(56,lev:14)
... dispose(i=56,lev:14) -> @14000
‖•△•‖ wof:8 HT:195
‖SCH‖ 8F: @612 ○ start=14000 dead:10000
|n.(57,lev:14)
... dispose(i=57,lev:14) -> @14000
‖•△•‖ wof:8 HT:195
‖SCH‖ 8F: @683 ○ start=14000 dead:10000
!◆! 6A: calc(i=3, lev:2)
·‖ 70: @ 695 HT:1000 -> ▶ 195
‖▷▷▷‖ 70: @ 764 HT:1000
|n.(58,lev:14)
... dispose(i=58,lev:14) -> @14000
‖•△•‖ wof:8 HT:1000
‖SCH‖ 8F: @793 ○ start=14000 dead:10000
!◆! 6A: calc(i=6, lev:3)
|n.(59,lev:14)
... dispose(i=59,lev:14) -> @14000
‖•△•‖ wof:8 HT:1000
‖SCH‖ 8F: @882 ○ start=14000 dead:10000
|n.(60,lev:15)
... dispose(i=60,lev:15) -> @15000
‖•△•‖ wof:8 HT:1000
‖SCH‖ 8F: @954 ○ start=15000 dead:10000
!◆! 6A: calc(i=10, lev:4)
|n.(61,lev:15)
... dispose(i=61,lev:15) -> @15000
‖•△•‖ wof:8 HT:1000
‖SCH‖ 8F: @1043 ○ start=15000 dead:10000
·‖ 70: @ 1063 HT:2000 -> ▶ 1000
|n.(62,lev:15)
... dispose(i=62,lev:15) -> @15000
‖•△•‖ wof:8 HT:2000
‖SCH‖ 8F: @1127 ○ start=15000 dead:10000
!◆! 6A: calc(i=14, lev:5)
|n.(63,lev:16)
... dispose(i=63,lev:16) -> @16000
‖•△•‖ wof:8 HT:2000
‖SCH‖ 8F: @1217 ○ start=16000 dead:10000
+++ 8F: Continuation(lastNode=63, levelDone=16, work_left:false)
‖•△•‖ wof:8 HT:2000
‖SCH‖ 8F: @1320 ○ start=17000 dead:100000
!◆! 6A: calc(i=19, lev:6)
·‖ 70: @ 2084 HT:2000 -> ▶ 2000
·‖ 70: @ 2119 HT:3000 -> ▶ 2000
!◆! 70: calc(i=2, lev:2)
!◆! 70: calc(i=4, lev:3)
·‖ 70: @ 3061 HT:3000 -> ▶ 3000
!◆! 70: calc(i=5, lev:3)
!◆! 70: calc(i=8, lev:4)
·‖ 70: @ 3441 HT:3000 -> ▶ 3000
·‖ 70: @ 3464 HT:4000 -> ▶ 3000
·‖ 70: @ 4130 HT:4000 -> ▶ 4000
·‖ 70: @ 4192 HT:4000 -> ▶ 4000
!◆! 70: calc(i=9, lev:4)
!◆! 70: calc(i=12, lev:5)
·‖ 70: @ 4596 HT:4000 -> ▶ 4000
·‖ 70: @ 4619 HT:5000 -> ▶ 4000
!◆! 70: calc(i=7, lev:4)
!◆! 70: calc(i=11, lev:5)
!◆! 70: calc(i=16, lev:6)
·‖ 98: @ 5008 HT:5000 -> ▶ 5000
!◆! 98: calc(i=13, lev:5)
·‖ 74: @ 5106 HT:5000 -> ▶ 5000
·‖ 70: @ 5212 HT:5000 -> ∘
·‖ 98: @ 5248 HT:5000 -> ∘
·‖ 70: @ 5258 HT:5000 -> ∘
·‖ 98: @ 5265 HT:5000 -> ∘
·‖ 70: @ 5284 HT:5000 -> ∘
·‖ 74: @ 5271 HT:5000 -> ▶ 5000
·‖ 98: @ 5291 HT:5000 -> ∘
·‖ 70: @ 5319 HT:5000 -> ∘
·‖ 98: @ 5352 HT:5000 -> ∘
·‖ 74: @ 5353 HT:5239 -> ▶ 5000
·‖ 70: @ 5362 HT:5239 -> ∘
·‖ 98: @ 5366 HT:5239 -> ∘
·‖ 70: @ 5385 HT:5239 -> ∘
·‖ 70: @ 5430 HT:5239 -> ∘
·‖ 98: @ 5432 HT:5239 -> ∘
·‖ 74: @ 5433 HT:6000 -> ▶ 5239
!◆! 74: calc(i=18, lev:6)
!◆! 74: calc(i=23, lev:7)
·‖ C2: @ 6021 HT:6000 -> ▶ 6000
·‖ 98: @ 6023 HT:6000 -> ∘
·‖ C2: @ 6048 HT:6000 -> ▶ 6000
·‖ C2: @ 6064 HT:6000 -> ▶ 6000
·‖ 98: @ 6064 HT:6000 -> ∘
·‖ 74: @ 6073 HT:6000 -> ∘
·‖ 70: @ 6066 HT:6000 -> ∘
·‖ 74: @ 6110 HT:6000 -> ▶ 6000
·‖ 70: @ 6134 HT:6000 -> ∘
!◆! C2: calc(i=17, lev:6)
·‖ 98: @ 6121 HT:6000 -> ∘
·‖ 70: @ 6157 HT:6000 -> ∘
·‖ 74: @ 6185 HT:7000 -> ▶ 6000
!◆! 74: calc(i=15, lev:6)
!◆! C2: calc(i=21, lev:7)
!◆! 74: calc(i=20, lev:7)
!◆! 74: calc(i=26, lev:8)
·‖ 74: @ 7003 HT:7000 -> ▶ 7000
!◆! 74: calc(i=24, lev:7)
·‖ 98: @ 7064 HT:7000 -> ∘
·‖ 70: @ 7064 HT:7000 -> ▶ 7000
·‖ 70: @ 7094 HT:7000 -> ▶ 7000
·‖ 98: @ 7129 HT:7000 -> ▶ 7000
!◆! 70: calc(i=22, lev:7)
·‖ 98: @ 7157 HT:8000 -> ▶ 7000
!◆! 74: calc(i=29, lev:8)
!◆! 70: calc(i=28, lev:8)
!◆! 74: calc(i=33, lev:9)
·‖ 98: @ 8060 HT:8000 -> ▶ 8000
·‖ 98: @ 8086 HT:8000 -> ▶ 8000
·‖ 98: @ 8107 HT:8000 -> ▶ 8000
!◆! 98: calc(i=27, lev:8)
!◆! 98: calc(i=31, lev:9)
·‖ 98: @ 8485 HT:8000 -> ▶ 8000
·‖ 98: @ 8506 HT:9000 -> ▶ 8000
!◆! 98: calc(i=25, lev:8)
!◆! 98: calc(i=30, lev:9)
!◆! 98: calc(i=36, lev:10)
·‖ 98: @ 9065 HT:9000 -> ∘
·‖ 98: @ 9082 HT:9000 -> ∘
·‖ D4: @ 9064 HT:9000 -> ▶ 9000
!◆! D4: calc(i=34, lev:9)
·‖ 98: @ 9101 HT:9000 -> ∘
·‖ 98: @ 9127 HT:9000 -> ▶ 9000
·‖ 98: @ 9158 HT:9000 -> ▶ 9000
!◆! 98: calc(i=32, lev:9)
!◆! D4: calc(i=39, lev:10)
!◆! 98: calc(i=38, lev:10)
·‖ D4: @ 9508 HT:9000 -> ▶ 9000
·‖ D4: @ 9530 HT:10000 -> ▶ 9000
!◆! 98: calc(i=43, lev:11)
·‖ D4: @ 10063 HT:10000 -> ▶ 10000
·‖ D4: @ 10084 HT:10000 -> ▶ 10000
·‖ D4: @ 10101 HT:10000 -> ▶ 10000
!◆! D4: calc(i=37, lev:10)
!◆! D4: calc(i=41, lev:11)
·‖ D4: @ 10416 HT:10000 -> ▶ 10000
·‖ D4: @ 10435 HT:11000 -> ▶ 10000
!◆! D4: calc(i=35, lev:10)
!◆! D4: calc(i=40, lev:11)
!◆! D4: calc(i=46, lev:12)
·‖ 70: @ 11064 HT:11000 -> ▶ 11000
·‖ 70: @ 11096 HT:11000 -> ▶ 11000
!◆! 70: calc(i=44, lev:11)
!◆! 70: calc(i=49, lev:12)
·‖ C2: @ 11487 HT:11000 -> ∘
·‖ 70: @ 11489 HT:11000 -> ▶ 11000
·‖ 70: @ 11520 HT:11000 -> ▶ 11000
·‖ C2: @ 11547 HT:11000 -> ∘
·‖ 70: @ 11559 HT:12000 -> ▶ 11000
!◆! 70: calc(i=42, lev:11)
!◆! 70: calc(i=48, lev:12)
!◆! 70: calc(i=53, lev:13)
·‖ C2: @ 12060 HT:12000 -> ▶ 12000
·‖ C2: @ 12081 HT:12000 -> ▶ 12000
·‖ C2: @ 12098 HT:12000 -> ▶ 12000
·‖ 70: @ 12120 HT:12000 -> ▶ 12000
!◆! 70: calc(i=45, lev:12)
!◆! C2: calc(i=47, lev:12)
!◆! C2: calc(i=51, lev:13)
!◆! 70: calc(i=50, lev:13)
!◆! 70: calc(i=56, lev:14)
·‖ C2: @ 12387 HT:13000 -> ▶ 12000
·‖ C2: @ 13060 HT:13000 -> ▶ 13000
·‖ C2: @ 13080 HT:13000 -> ▶ 13000
!◆! C2: calc(i=54, lev:13)
!◆! C2: calc(i=59, lev:14)
·‖ C2: @ 13366 HT:13000 -> ▶ 13000
·‖ C2: @ 13383 HT:13000 -> ▶ 13000
·‖ C2: @ 13400 HT:14000 -> ▶ 13000
!◆! C2: calc(i=52, lev:13)
!◆! C2: calc(i=58, lev:14)
!◆! C2: calc(i=62, lev:15)
·‖ C2: @ 14059 HT:14000 -> ▶ 14000
·‖ C2: @ 14078 HT:14000 -> ▶ 14000
·‖ C2: @ 14095 HT:14000 -> ▶ 14000
!◆! C2: calc(i=57, lev:14)
·‖ 74: @ 14144 HT:14000 -> ▶ 14000
!◆! 74: calc(i=55, lev:14)
!◆! C2: calc(i=61, lev:15)
!◆! 74: calc(i=60, lev:15)
·‖ C2: @ 14367 HT:15000 -> ▶ 14000
!◆! 74: calc(i=63, lev:16)
·‖ C2: @ 15060 HT:15000 -> ▶ 15000
·‖ C2: @ 15080 HT:15000 -> ▶ 15000
·‖ C2: @ 15097 HT:16000 -> ▶ 15000
·‖ C2: @ 16060 HT:17000 -> ▶ 16000
·‖ C2: @ 17060 HT:50764 -> ▶ 17000
+++ 8F: wake-up / shutdown
‖▽▼▽‖ 5D: @ 17446
‖▽▼▽‖ 6A: @ 17999
‖▽▼▽‖ 98: @ 18212
‖▽▼▽‖ CE: @ 18263
‖▽▼▽‖ 74: @ 18327
‖▽▼▽‖ D4: @ 18682
‖▽▼▽‖ 70: @ 21125
‖▽▼▽‖ C2: @ 50886