DST: 15449 #1052
Description
DST Failed
DST run failed for seed=15449, ticks=1000, scenario=default, store=postgres.
Seed
15449
Scenario
default
Store
postgres
Commit
5514db3b6a3a0334fb74c30c5fba44f7af6dcee8
Command
go run ./... dst run --seed 15449 --ticks 1000 --scenario default --aio-store-postgres-enable
Logs
level=INFO msg=DST seed=15449 ticks=1000 reqsPerTick=1:25 dst="DST(ids=12, headers=22, data=10, tags=15, backchannel=595)" system="System(api=API(size=516, subsystems=[]), aio=AIODST(subsystems=[router sender store]), config=Config(cms=818, sbs=258, cbs=151, pbs=68, sbs=42, tbs=81))"
level=INFO msg=DST id=2 t=0|999 req="ScheduleGet(id=s02)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=3 t=0|999 req="CallbackCreate(id=__notify:p11:cb08, promiseId=p11, recv=\"dst\", mesg=Mesg(type=notify, root=p11, leaf=), timeout=2163)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=7 t=0|999 req="PromiseComplete(id=p02, state=RESOLVED, value=Value(headers=map[], data=3))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=11 t=0|999 req="ScheduleGet(id=s04)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=0 t=0|999 req="PromiseGet(id=p02)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=9 t=0|999 req="PromiseGet(id=p11)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=13 t=1000|1999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=15 t=1000|1999 req="ScheduleDelete(id=s10)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=17 t=1000|1999 req="PromiseComplete(id=p00, state=REJECTED_CANCELED, value=Value(headers=map[0:1.0], data=0))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=18 t=1000|1999 req="ScheduleGet(id=s01)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=19 t=1000|1999 req="ScheduleDelete(id=s03)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=22 t=1000|1999 req="ScheduleDelete(id=s08)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=27 t=1000|1999 req="ScheduleDelete(id=s08)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=28 t=1000|1999 req="ScheduleDelete(id=s07)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=29 t=1000|1999 req="ScheduleGet(id=s10)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=33 t=1000|1999 req="ScheduleDelete(id=s08)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=35 t=1000|1999 req="CallbackCreate(id=__resume:p04:p06, promiseId=p06, recv=\"dst\", mesg=Mesg(type=resume, root=p04, leaf=p06), timeout=257486)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=21 t=1000|1999 req="PromiseGet(id=p05)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=23 t=1000|1999 req="PromiseSubscribe(awaited=p06, address=addr00)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=24 t=1000|1999 req="PromiseRegister(awaiter=p05, awaited=p03)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=25 t=1000|1999 req="PromiseRegister(awaiter=p01, awaited=p10)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=30 t=1000|1999 req="PromiseRegister(awaiter=p09, awaited=p11)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=34 t=1000|1999 req="PromiseSubscribe(awaited=p09, address=addr04)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=36 t=2000|2999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=37 t=2000|2999 req="CallbackCreate(id=__resume:p09:p02, promiseId=p02, recv=\"dst\", mesg=Mesg(type=resume, root=p09, leaf=p02), timeout=197162)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=38 t=2000|2999 req="ScheduleDelete(id=s02)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=1 t=0|2999 req="PromiseCreate(id=p05, param=Value(headers=map[], data=), timeout=771433, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p05, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=771433, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=4 t=0|2999 req="PromiseCreate(id=p04, param=Value(headers=map[], data=), timeout=263505, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p04, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=6 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p08, param=Value(headers=map[0:4.0 1:4.1], data=0), timeout=560349, tags=map[resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p08, processId=p08, ttl=1000, timeout=560349))" res="Response(status=20100, TaskCreate(promise=Promise(id=p08, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=560349, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=0), task=Task(id=__invoke:p08, processId=p08, state=CLAIMED, rootPromiseId=p08, recv=\"dst\", mesg=Mesg(type=invoke, root=p08, leaf=p08), timeout=560349, counter=1, attempt=0, ttl=1000, expiresAt=1000, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=10 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p09, param=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p09, processId=p09, ttl=9223372036854775807, timeout=251237))" res="Response(status=20100, TaskCreate(promise=Promise(id=p09, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=0), task=Task(id=__invoke:p09, processId=p09, state=CLAIMED, rootPromiseId=p09, recv=\"dst\", mesg=Mesg(type=invoke, root=p09, leaf=p09), timeout=251237, counter=1, attempt=0, ttl=9223372036854775807, expiresAt=9223372036854775807, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=12 t=0|2999 req="PromiseCreate(id=p10, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), timeout=708184, tags=map[0:1.0 resonate:invoke:dst])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p10, state=PENDING, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=39 t=2000|2999 req="PromiseGet(id=p09)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=41 t=3000|3999 req="ScheduleGet(id=s01)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=42 t=3000|3999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=47 t=3000|3999 req="ScheduleDelete(id=s09)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=50 t=3000|3999 req="PromiseComplete(id=p06, state=REJECTED, value=Value(headers=map[], data=7))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=16 t=1000|3999 req="PromiseCreate(id=p02, param=Value(headers=map[], data=), timeout=913393, tags=map[0:9.0])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p02, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=913393, tags=map[0:9.0], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=20 t=1000|3999 req="TaskCreate(promise=PromiseCreate(id=p03, param=Value(headers=map[0:7.0 1:7.1], data=5), timeout=229207, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p03, processId=p03, ttl=4000, timeout=229207))" res="Response(status=20100, TaskCreate(promise=Promise(id=p03, state=PENDING, param=Value(headers=map[0:7.0 1:7.1], data=5), value=Value(headers=map[], data=), timeout=229207, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=0), task=Task(id=__invoke:p03, processId=p03, state=CLAIMED, rootPromiseId=p03, recv=\"dst\", mesg=Mesg(type=invoke, root=p03, leaf=p03), timeout=229207, counter=1, attempt=0, ttl=4000, expiresAt=5000, createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=31 t=1000|3999 req="PromiseCreate(id=p06, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=0), timeout=261544, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p06, state=PENDING, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=0), value=Value(headers=map[], data=), timeout=261544, tags=map[], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=32 t=1000|3999 req="PromiseCreate(id=p11, param=Value(headers=map[0:19.0 1:19.1], data=3), timeout=508756, tags=map[0:9.0])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p11, state=PENDING, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=40 t=3000|3999 req="PromiseRegister(awaiter=p01, awaited=p04)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=46 t=3000|3999 req="PromiseGet(id=p11)" res="Response(status=20000, PromiseGet(promise=Promise(id=p11, state=PENDING, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=48 t=3000|3999 req="PromiseRegister(awaiter=p01, awaited=p11)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=49 t=3000|3999 req="PromiseGet(id=p11)" res="Response(status=20000, PromiseGet(promise=Promise(id=p11, state=PENDING, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=5 t=0|3999 req="TaskCreate(promise=PromiseCreate(id=p08, param=Value(headers=map[0:5.0 1:5.1], data=0), timeout=13477, tags=map[0:8.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p08, processId=p08, ttl=5000, timeout=13477))" res="Response(status=20000, TaskCreate(promise=Promise(id=p08, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=560349, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=0), task=<nil>))" err=<nil>
level=INFO msg=DST id=8 t=0|3999 req="PromiseCreate(id=p04, param=Value(headers=map[], data=7), timeout=485173, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p04, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=51 t=4000|4999 req="PromiseComplete(id=p00, state=REJECTED_CANCELED, value=Value(headers=map[0:10.0], data=0))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=53 t=4000|4999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=58 t=4000|4999 req="ScheduleGet(id=s07)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
...
level=INFO msg=DST id=12622 t=993000|993999 req="CallbackCreate(id=__resume:p06:p01, promiseId=p01, recv=\"dst\", mesg=Mesg(type=resume, root=p06, leaf=p01), timeout=995633)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1], data=6), timeout=771205, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=29000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12626 t=993000|993999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12627 t=993000|993999 req="PromiseComplete(id=p07, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12604 t=993000|993999 req="PromiseCreate(id=p04, param=Value(headers=map[0:19.0 1:19.1], data=0), timeout=214908, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12607 t=993000|993999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:3.0], data=9), timeout=771433, tags=map[], createdOn=0, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12608 t=993000|993999 req="PromiseGet(id=p08)" res="Response(status=20000, PromiseGet(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[0:18.0 1:18.1 2:18.2], data=), timeout=560349, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=43000)))" err=<nil>
level=INFO msg=DST id=12609 t=993000|993999 req="PromiseGet(id=p01)" res="Response(status=20000, PromiseGet(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1], data=6), timeout=771205, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12610 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p02, param=Value(headers=map[0:10.0], data=), timeout=385850, tags=map[0:10.0 resonate:invoke:dst]), task=CreateTask(promiseId=p02, processId=p02, ttl=2000, timeout=385850))" res="Response(status=20000, TaskCreate(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=913393, tags=map[0:9.0], createdOn=1000, completedOn=11000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12614 t=993000|993999 req="PromiseSubscribe(awaited=p07, address=addr10)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12616 t=993000|993999 req="PromiseGet(id=p09)" res="Response(status=20000, PromiseGet(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12618 t=993000|993999 req="PromiseCreate(id=p10, param=Value(headers=map[], data=), timeout=746411, tags=map[0:10.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12623 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p01, param=Value(headers=map[], data=), timeout=759306, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p01, processId=p01, ttl=2000, timeout=759306))" res="Response(status=20000, TaskCreate(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1], data=6), timeout=771205, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=29000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12625 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[], data=), timeout=713569, tags=map[0:13.0 1:13.1 2:13.2 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p10, processId=p10, ttl=5000, timeout=713569))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12632 t=994000|994999 req="PromiseComplete(id=p10, state=RESOLVED, value=Value(headers=map[0:19.0 1:19.1], data=8))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12637 t=994000|994999 req="CallbackCreate(id=__notify:p07:cb10, promiseId=p07, recv=\"dst\", mesg=Mesg(type=notify, root=p07, leaf=), timeout=997264)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12638 t=994000|994999 req="ScheduleDelete(id=s05)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12639 t=994000|994999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12640 t=994000|994999 req="ScheduleGet(id=s00)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12628 t=994000|994999 req="PromiseGet(id=p00)" res="Response(status=20000, PromiseGet(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[0:18.0 1:18.1 2:18.2], data=0), value=Value(headers=map[0:2.0], data=6), timeout=72705, tags=map[], createdOn=19000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12629 t=994000|994999 req="PromiseRegister(awaiter=p03, awaited=p00)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[0:18.0 1:18.1 2:18.2], data=0), value=Value(headers=map[0:2.0], data=6), timeout=72705, tags=map[], createdOn=19000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12630 t=994000|994999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12631 t=994000|994999 req="PromiseCreate(id=p07, param=Value(headers=map[], data=), timeout=39794, tags=map[0:10.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12633 t=994000|994999 req="PromiseRegister(awaiter=p04, awaited=p02)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12635 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p04, param=Value(headers=map[0:3.0], data=1), timeout=595278, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p04, processId=p04, ttl=5000, timeout=595278))" res="Response(status=20000, TaskCreate(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=33000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12636 t=994000|994999 req="PromiseRegister(awaiter=p10, awaited=p00)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[0:18.0 1:18.1 2:18.2], data=0), value=Value(headers=map[0:2.0], data=6), timeout=72705, tags=map[], createdOn=19000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12641 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p02, param=Value(headers=map[0:0.0 1:0.1], data=), timeout=826849, tags=map[0:4.0 resonate:invoke:dst]), task=CreateTask(promiseId=p02, processId=p02, ttl=5000, timeout=826849))" res="Response(status=20000, TaskCreate(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=913393, tags=map[0:9.0], createdOn=1000, completedOn=11000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12642 t=994000|994999 req="PromiseCreate(id=p07, param=Value(headers=map[], data=1), timeout=469065, tags=map[0:4.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12678 t=996000|996000 req="PromiseRegister(awaiter=p02, awaited=p02)" res=<nil> err="The request is invalid"
level=INFO msg=DST id=12679 t=996000|996000 req="PromiseRegister(awaiter=p06, awaited=p06)" res=<nil> err="The request is invalid"
level=INFO msg=DST id=12643 t=995000|995999 req="ScheduleGet(id=s07)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s07, desc=, cron=6 * * * *, tags=map[], promiseId=s07.{{.timestamp}}, promiseTimeout=990538, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[0:8.0 resonate:timeout:true], lastRunTime=0, nextRunTime=3960000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12644 t=995000|995999 req="ScheduleDelete(id=s01)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12645 t=995000|995999 req="ScheduleCreate(id=s02, desc=, cron=57 * * * *, tags=map[resonate:invoke:dst resonate:timeout:true], promiseId=s02.{{.timestamp}}, promiseTimeout=998644, promiseParam=Value(headers=map[], data=1), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s02, desc=, cron=20 * * * *, tags=map[0:4.0], promiseId=s02.{{.timestamp}}, promiseTimeout=996164, promiseParam=Value(headers=map[], data=2), promiseTags=map[], lastRunTime=0, nextRunTime=1200000, createdOn=990000)))" err=<nil>
level=INFO msg=DST id=12646 t=995000|995999 req="PromiseComplete(id=p04, state=REJECTED, value=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12650 t=995000|995999 req="PromiseComplete(id=p03, state=RESOLVED, value=Value(headers=map[0:14.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p03, state=REJECTED_CANCELED, param=Value(headers=map[0:7.0 1:7.1], data=5), value=Value(headers=map[0:12.0 1:12.1], data=), timeout=229207, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=9000)))" err=<nil>
level=INFO msg=DST id=12655 t=995000|995999 req="ScheduleGet(id=s08)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12657 t=995000|995999 req="PromiseComplete(id=p07, state=REJECTED, value=Value(headers=map[0:1.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12660 t=995000|995999 req="ScheduleDelete(id=s00)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12663 t=995000|995999 req="ScheduleGet(id=s03)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s03, desc=, cron=20 * * * *, tags=map[], promiseId=s03.{{.timestamp}}, promiseTimeout=999526, promiseParam=Value(headers=map[0:21.0], data=2), promiseTags=map[], lastRunTime=0, nextRunTime=1200000, createdOn=989000)))" err=<nil>
level=INFO msg=DST id=12664 t=995000|995999 req="CallbackCreate(id=__resume:p08:p06, promiseId=p06, recv=\"dst\", mesg=Mesg(type=resume, root=p08, leaf=p06), timeout=998860)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=0), value=Value(headers=map[], data=), timeout=261544, tags=map[], createdOn=1000, completedOn=15000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12647 t=995000|995999 req="PromiseSubscribe(awaited=p07, address=addr04)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12648 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p03, param=Value(headers=map[], data=5), timeout=862822, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p03, processId=p03, ttl=9223372036854775807, timeout=862822))" res="Response(status=20000, TaskCreate(promise=Promise(id=p03, state=REJECTED_CANCELED, param=Value(headers=map[0:7.0 1:7.1], data=5), value=Value(headers=map[0:12.0 1:12.1], data=), timeout=229207, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=9000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12649 t=995000|995999 req="PromiseGet(id=p03)" res="Response(status=20000, PromiseGet(promise=Promise(id=p03, state=REJECTED_CANCELED, param=Value(headers=map[0:7.0 1:7.1], data=5), value=Value(headers=map[0:12.0 1:12.1], data=), timeout=229207, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=9000)))" err=<nil>
level=INFO msg=DST id=12651 t=995000|995999 req="PromiseRegister(awaiter=p08, awaited=p02)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=913393, tags=map[0:9.0], createdOn=1000, completedOn=11000)))" err=<nil>
level=INFO msg=DST id=12652 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p08, param=Value(headers=map[], data=), timeout=874861, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p08, processId=p08, ttl=9223372036854775807, timeout=874861))" res="Response(status=20000, TaskCreate(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[0:18.0 1:18.1 2:18.2], data=), timeout=560349, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=43000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12653 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[0:19.0 1:19.1], data=5), timeout=676488, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p10, processId=p10, ttl=1000, timeout=676488))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12656 t=995000|995999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:3.0], data=9), timeout=771433, tags=map[], createdOn=0, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12658 t=995000|995999 req="PromiseRegister(awaiter=p05, awaited=p03)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12659 t=995000|995999 req="PromiseSubscribe(awaited=p07, address=addr04)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12661 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p11, param=Value(headers=map[], data=), timeout=177629, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p11, processId=p11, ttl=4000, timeout=177629))" res="Response(status=20000, TaskCreate(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=15000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12662 t=995000|995999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:3.0], data=9), timeout=771433, tags=map[], createdOn=0, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12666 t=996000|996999 req="ScheduleCreate(id=s10, desc=, cron=25 * * * *, tags=map[0:9.0], promiseId=s10.{{.timestamp}}, promiseTimeout=999745, promiseParam=Value(headers=map[], data=), promiseTags=map[0:1.0 resonate:invoke:dst])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s10, desc=, cron=56 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=998038, promiseParam=Value(headers=map[0:5.0 1:5.1], data=), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=3360000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12667 t=996000|996999 req="ScheduleDelete(id=s03)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12668 t=996000|996999 req="ScheduleGet(id=s02)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s02, desc=, cron=20 * * * *, tags=map[0:4.0], promiseId=s02.{{.timestamp}}, promiseTimeout=996164, promiseParam=Value(headers=map[], data=2), promiseTags=map[], lastRunTime=0, nextRunTime=1200000, createdOn=990000)))" err=<nil>
level=INFO msg=DST id=12671 t=996000|996999 req="CallbackCreate(id=__resume:p01:p07, promiseId=p07, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p07), timeout=997973)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12672 t=996000|996999 req="CallbackCreate(id=__resume:p02:p05, promiseId=p05, recv=\"dst\", mesg=Mesg(type=resume, root=p02, leaf=p05), timeout=998739)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:3.0], data=9), timeout=771433, tags=map[], createdOn=0, completedOn=29000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12674 t=996000|996999 req="CallbackCreate(id=__resume:p03:p08, promiseId=p08, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p08), timeout=999011)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[0:18.0 1:18.1 2:18.2], data=), timeout=560349, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=43000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12675 t=996000|996999 req="PromiseComplete(id=p01, state=REJECTED, value=Value(headers=map[], data=6))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1], data=6), timeout=771205, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12677 t=996000|996999 req="CallbackCreate(id=__notify:p10:cb04, promiseId=p10, recv=\"dst\", mesg=Mesg(type=notify, root=p10, leaf=), timeout=998922)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12682 t=996000|996999 req="CallbackCreate(id=__resume:p03:p05, promiseId=p05, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p05), timeout=998416)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:3.0], data=9), timeout=771433, tags=map[], createdOn=0, completedOn=29000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12683 t=996000|996999 req="CallbackCreate(id=__resume:p11:p01, promiseId=p01, recv=\"dst\", mesg=Mesg(type=resume, root=p11, leaf=p01), timeout=998844)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1], data=6), timeout=771205, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=29000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12684 t=996000|996999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12654 t=995000|996999 req="ScheduleCreate(id=s11, desc=, cron=39 * * * *, tags=map[0:13.0 1:13.1 2:13.2 resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=999308, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=39 * * * *, tags=map[0:13.0 1:13.1 2:13.2 resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=999308, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2340000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12665 t=996000|996999 req="PromiseSubscribe(awaited=p06, address=addr06)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=0), value=Value(headers=map[], data=), timeout=261544, tags=map[], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12669 t=996000|996999 req="PromiseRegister(awaiter=p03, awaited=p00)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[0:18.0 1:18.1 2:18.2], data=0), value=Value(headers=map[0:2.0], data=6), timeout=72705, tags=map[], createdOn=19000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12673 t=996000|996999 req="PromiseCreate(id=p03, param=Value(headers=map[], data=3), timeout=827205, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p03, state=REJECTED_CANCELED, param=Value(headers=map[0:7.0 1:7.1], data=5), value=Value(headers=map[0:12.0 1:12.1], data=), timeout=229207, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=9000)))" err=<nil>
level=INFO msg=DST id=12676 t=996000|996999 req="PromiseSubscribe(awaited=p09, address=addr05)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12680 t=996000|996999 req="PromiseRegister(awaiter=p01, awaited=p04)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12681 t=996000|996999 req="PromiseRegister(awaiter=p02, awaited=p01)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12685 t=996000|996999 req="PromiseSubscribe(awaited=p10, address=addr09)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12687 t=997000|997999 req="ScheduleGet(id=s03)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12690 t=997000|997999 req="ScheduleDelete(id=s00)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12692 t=997000|997999 req="ScheduleGet(id=s10)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s10, desc=, cron=56 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=998038, promiseParam=Value(headers=map[0:5.0 1:5.1], data=), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=3360000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12695 t=997000|997999 req="PromiseComplete(id=p09, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12670 t=996000|997999 req="ScheduleCreate(id=s05, desc=, cron=0 * * * *, tags=map[0:9.0], promiseId=s05.{{.timestamp}}, promiseTimeout=996837, promiseParam=Value(headers=map[], data=4), promiseTags=map[resonate:invoke:dst resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s05, desc=, cron=0 * * * *, tags=map[0:9.0], promiseId=s05.{{.timestamp}}, promiseTimeout=996837, promiseParam=Value(headers=map[], data=4), promiseTags=map[resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=3600000, createdOn=997000)))" err=<nil>
level=INFO msg=DST id=12686 t=997000|997999 req="PromiseSubscribe(awaited=p11, address=addr09)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12688 t=997000|997999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[], data=0), timeout=783577, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p10, processId=p10, ttl=4000, timeout=783577))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=REJECTED, param=Value(headers=map[0:20.0 1:20.1 2:20.2], data=6), value=Value(headers=map[], data=), timeout=708184, tags=map[0:1.0 resonate:invoke:dst], createdOn=0, completedOn=15000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12689 t=997000|997999 req="TaskCreate(promise=PromiseCreate(id=p09, param=Value(headers=map[], data=), timeout=652524, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p09, processId=p09, ttl=3000, timeout=652524))" res="Response(status=20000, TaskCreate(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12691 t=997000|997999 req="PromiseGet(id=p04)" res="Response(status=20000, PromiseGet(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=263505, tags=map[], createdOn=0, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12693 t=997000|997999 req="PromiseRegister(awaiter=p11, awaited=p05)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12694 t=997000|997999 req="PromiseGet(id=p06)" res="Response(status=20000, PromiseGet(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=0), value=Value(headers=map[], data=), timeout=261544, tags=map[], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12702 t=998000|998999 req="ScheduleCreate(id=s11, desc=, cron=53 * * * *, tags=map[], promiseId=s11.{{.timestamp}}, promiseTimeout=999655, promiseParam=Value(headers=map[], data=8), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=39 * * * *, tags=map[0:13.0 1:13.1 2:13.2 resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=999308, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2340000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12704 t=998000|998999 req="ScheduleDelete(id=s01)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12705 t=998000|998999 req="PromiseComplete(id=p05, state=REJECTED, value=Value(headers=map[0:16.0 1:16.1], data=0))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:3.0], data=9), timeout=771433, tags=map[], createdOn=0, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12696 t=998000|998999 req="PromiseGet(id=p09)" res="Response(status=20000, PromiseGet(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12697 t=998000|998999 req="PromiseCreate(id=p11, param=Value(headers=map[], data=0), timeout=400428, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12698 t=998000|998999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12699 t=998000|998999 req="PromiseCreate(id=p09, param=Value(headers=map[], data=4), timeout=715272, tags=map[0:10.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12700 t=998000|998999 req="PromiseCreate(id=p07, param=Value(headers=map[], data=), timeout=498213, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12703 t=998000|998999 req="TaskCreate(promise=PromiseCreate(id=p06, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=551126, tags=map[0:13.0 1:13.1 2:13.2 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p06, processId=p06, ttl=1000, timeout=551126))" res="Response(status=20000, TaskCreate(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:6.0 1:6.1 2:6.2], data=0), value=Value(headers=map[], data=), timeout=261544, tags=map[], createdOn=1000, completedOn=15000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12706 t=999000|1000999 req="PromiseComplete(id=p11, state=RESOLVED, value=Value(headers=map[0:15.0 1:15.1], data=1))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:19.0 1:19.1], data=3), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=508756, tags=map[0:9.0], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12707 t=999000|1000999 req="ScheduleGet(id=s00)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12709 t=999000|1000999 req="ScheduleCreate(id=s11, desc=, cron=50 * * * *, tags=map[], promiseId=s11.{{.timestamp}}, promiseTimeout=999097, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=39 * * * *, tags=map[0:13.0 1:13.1 2:13.2 resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=999308, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2340000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12710 t=999000|1000999 req="ScheduleCreate(id=s07, desc=, cron=35 * * * *, tags=map[], promiseId=s07.{{.timestamp}}, promiseTimeout=999482, promiseParam=Value(headers=map[0:21.0], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s07, desc=, cron=6 * * * *, tags=map[], promiseId=s07.{{.timestamp}}, promiseTimeout=990538, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[0:8.0 resonate:timeout:true], lastRunTime=0, nextRunTime=3960000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12701 t=998000|1000999 req="ScheduleCreate(id=s04, desc=, cron=17 * * * *, tags=map[0:1.0 resonate:invoke:dst], promiseId=s04.{{.timestamp}}, promiseTimeout=998857, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=17 * * * *, tags=map[0:1.0 resonate:invoke:dst], promiseId=s04.{{.timestamp}}, promiseTimeout=998857, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=1020000, createdOn=999000)))" err=<nil>
level=INFO msg=DST id=12708 t=999000|1000999 req="PromiseCreate(id=p09, param=Value(headers=map[0:13.0 1:13.1], data=), timeout=260020, tags=map[resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12711 t=999000|1000999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:1.0], data=2), value=Value(headers=map[0:13.0 1:13.1], data=2), timeout=172892, tags=map[], createdOn=19000, completedOn=45000)))" err=<nil>
level=INFO msg=DST id=12712 t=999000|1000999 req="PromiseGet(id=p09)" res="Response(status=20000, PromiseGet(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=251237, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=53000)))" err=<nil>
level=INFO msg=DST id=12713 t=999000|1000999 req="PromiseRegister(awaiter=p06, awaited=p09)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=ERROR msg="DST is non linearizable, run with -v flag for more information" v=false
Error: DST failed for seed='15449'