DST: 20651 #1050
Description
DST Failed
DST run failed for seed=20651, ticks=1000, scenario=default, store=postgres.
Seed
20651
Scenario
default
Store
postgres
Commit
5514db3b6a3a0334fb74c30c5fba44f7af6dcee8
Command
go run ./... dst run --seed 20651 --ticks 1000 --scenario default --aio-store-postgres-enable
Logs
level=INFO msg=DST seed=20651 ticks=1000 reqsPerTick=1:25 dst="DST(ids=20, headers=13, data=21, tags=13, backchannel=551)" system="System(api=API(size=304, subsystems=[]), aio=AIODST(subsystems=[router sender store]), config=Config(cms=809, sbs=960, cbs=910, pbs=5, sbs=5, tbs=7))"
level=INFO msg=DST id=14 t=1000|1000 req="PromiseRegister(awaiter=p18, awaited=p18)" res=<nil> err="The request is invalid"
level=INFO msg=DST id=1 t=0|999 req="PromiseComplete(id=p15, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=3 t=0|999 req="ScheduleGet(id=s01)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=4 t=0|999 req="ScheduleDelete(id=s18)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=6 t=0|999 req="ScheduleDelete(id=s12)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=13 t=1000|1999 req="CallbackCreate(id=__notify:p00:cb14, promiseId=p00, recv=\"dst\", mesg=Mesg(type=notify, root=p00, leaf=), timeout=163076)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=19 t=1000|1999 req="PromiseComplete(id=p17, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=24 t=1000|1999 req="PromiseComplete(id=p19, state=REJECTED, value=Value(headers=map[0:2.0 1:2.1], data=17))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=27 t=1000|1999 req="ScheduleGet(id=s17)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=31 t=1000|1999 req="ScheduleDelete(id=s11)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=10 t=1000|1999 req="PromiseRegister(awaiter=p00, awaited=p02)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=16 t=1000|1999 req="PromiseSubscribe(awaited=p16, address=addr13)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=18 t=1000|1999 req="PromiseGet(id=p09)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=21 t=1000|1999 req="PromiseSubscribe(awaited=p10, address=addr12)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=22 t=1000|1999 req="PromiseSubscribe(awaited=p12, address=addr09)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=23 t=1000|1999 req="PromiseRegister(awaiter=p17, awaited=p08)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=25 t=1000|1999 req="PromiseRegister(awaiter=p09, awaited=p07)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=29 t=1000|1999 req="PromiseRegister(awaiter=p06, awaited=p13)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=30 t=1000|1999 req="PromiseRegister(awaiter=p02, awaited=p07)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=67 t=3000|3000 req="CallbackCreate(id=__resume:p03:p03, promiseId=p03, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p03), timeout=164871)" res=<nil> err="The request is invalid"
level=INFO msg=DST id=32 t=2000|2999 req="ScheduleGet(id=s19)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=33 t=2000|2999 req="CallbackCreate(id=__resume:p05:p02, promiseId=p02, recv=\"dst\", mesg=Mesg(type=resume, root=p05, leaf=p02), timeout=775481)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=34 t=2000|2999 req="PromiseComplete(id=p12, state=REJECTED_CANCELED, value=Value(headers=map[0:12.0 1:12.1], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=35 t=2000|2999 req="ScheduleGet(id=s10)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s10, desc=, cron=27 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=427034, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=1620000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=37 t=2000|2999 req="ScheduleGet(id=s00)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=38 t=2000|2999 req="CallbackCreate(id=__resume:p01:p06, promiseId=p06, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p06), timeout=198347)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=42 t=2000|2999 req="CallbackCreate(id=__notify:p04:cb16, promiseId=p04, recv=\"dst\", mesg=Mesg(type=notify, root=p04, leaf=), timeout=36495)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=44 t=2000|2999 req="ScheduleDelete(id=s03)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=46 t=2000|2999 req="ScheduleGet(id=s10)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s10, desc=, cron=27 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=427034, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=1620000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=48 t=2000|2999 req="PromiseComplete(id=p16, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=50 t=2000|2999 req="PromiseComplete(id=p13, state=REJECTED, value=Value(headers=map[0:3.0 1:3.1 2:3.2], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=51 t=2000|2999 req="ScheduleDelete(id=s13)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=53 t=2000|2999 req="ScheduleDelete(id=s16)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=9 t=1000|2999 req="ScheduleCreate(id=s13, desc=, cron=10 * * * *, tags=map[0:0.0 resonate:timeout:true], promiseId=s13.{{.timestamp}}, promiseTimeout=629116, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s13, desc=, cron=10 * * * *, tags=map[0:0.0 resonate:timeout:true], promiseId=s13.{{.timestamp}}, promiseTimeout=629116, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=600000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=12 t=1000|2999 req="ScheduleCreate(id=s10, desc=, cron=27 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=427034, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s10, desc=, cron=27 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=427034, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=1620000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=0 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p15, param=Value(headers=map[], data=), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p15, processId=p15, ttl=4000, timeout=884242))" res="Response(status=20100, TaskCreate(promise=Promise(id=p15, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=0), task=Task(id=__invoke:p15, processId=p15, state=CLAIMED, rootPromiseId=p15, recv=\"dst\", mesg=Mesg(type=invoke, root=p15, leaf=p15), timeout=884242, counter=1, attempt=0, ttl=4000, expiresAt=4000, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=2 t=0|2999 req="PromiseCreate(id=p19, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), timeout=896002, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p19, state=PENDING, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), value=Value(headers=map[], data=), timeout=896002, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=5 t=0|2999 req="PromiseCreate(id=p08, param=Value(headers=map[], data=10), timeout=992619, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p08, state=PENDING, param=Value(headers=map[], data=10), value=Value(headers=map[], data=), timeout=992619, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=7 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p11, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=8), timeout=960400, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p11, processId=p11, ttl=1000, timeout=960400))" res="Response(status=20100, TaskCreate(promise=Promise(id=p11, state=PENDING, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=8), value=Value(headers=map[], data=), timeout=960400, tags=map[resonate:invoke:dst], createdOn=0, completedOn=0), task=Task(id=__invoke:p11, processId=p11, state=CLAIMED, rootPromiseId=p11, recv=\"dst\", mesg=Mesg(type=invoke, root=p11, leaf=p11), timeout=960400, counter=1, attempt=0, ttl=1000, expiresAt=1000, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=36 t=2000|2999 req="PromiseRegister(awaiter=p03, awaited=p05)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=39 t=2000|2999 req="PromiseSubscribe(awaited=p04, address=addr02)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=41 t=2000|2999 req="PromiseRegister(awaiter=p10, awaited=p16)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=45 t=2000|2999 req="PromiseSubscribe(awaited=p13, address=addr19)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=49 t=2000|2999 req="PromiseGet(id=p12)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=52 t=2000|2999 req="PromiseGet(id=p07)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=55 t=3000|3999 req="ScheduleDelete(id=s13)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=59 t=3000|3999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=66 t=3000|3999 req="ScheduleGet(id=s18)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=70 t=3000|3999 req="ScheduleDelete(id=s12)" res="Response(status=20400, ScheduleDelete())" err=<nil>
...
level=INFO msg=DST id=12555 t=988000|988999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=11), value=Value(headers=map[], data=), timeout=904323, tags=map[0:3.0 resonate:invoke:dst resonate:timeout:true], createdOn=20000, completedOn=50000)))" err=<nil>
level=INFO msg=DST id=12556 t=988000|988999 req="PromiseSubscribe(awaited=p02, address=addr16)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=), timeout=151701, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=17000)))" err=<nil>
level=INFO msg=DST id=12557 t=988000|988999 req="PromiseCreate(id=p18, param=Value(headers=map[], data=), timeout=353414, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p18, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), timeout=902190, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12558 t=988000|988999 req="PromiseSubscribe(awaited=p18, address=addr13)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p18, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), timeout=902190, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12559 t=988000|988999 req="PromiseSubscribe(awaited=p12, address=addr17)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=17), value=Value(headers=map[], data=16), timeout=671429, tags=map[0:9.0 1:9.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=6000)))" err=<nil>
level=INFO msg=DST id=12561 t=988000|988999 req="PromiseSubscribe(awaited=p15, address=addr13)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12564 t=989000|989999 req="ScheduleGet(id=s00)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s00, desc=, cron=7 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=995083, promiseParam=Value(headers=map[0:4.0 1:4.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4020000, createdOn=983000)))" err=<nil>
level=INFO msg=DST id=12565 t=989000|989999 req="ScheduleDelete(id=s01)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12566 t=989000|989999 req="ScheduleGet(id=s04)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s04, desc=, cron=3 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=992263, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=11), promiseTags=map[0:3.0 resonate:timeout:true], lastRunTime=0, nextRunTime=3780000, createdOn=976000)))" err=<nil>
level=INFO msg=DST id=12562 t=989000|989999 req="PromiseGet(id=p12)" res="Response(status=20000, PromiseGet(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=17), value=Value(headers=map[], data=16), timeout=671429, tags=map[0:9.0 1:9.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=6000)))" err=<nil>
level=INFO msg=DST id=12563 t=989000|989999 req="PromiseGet(id=p15)" res="Response(status=20000, PromiseGet(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12567 t=989000|989999 req="PromiseCreate(id=p18, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), timeout=836061, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p18, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), timeout=902190, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12568 t=989000|989999 req="PromiseCreate(id=p19, param=Value(headers=map[], data=11), timeout=896923, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), value=Value(headers=map[0:3.0 1:3.1 2:3.2], data=9), timeout=896002, tags=map[], createdOn=0, completedOn=5000)))" err=<nil>
level=INFO msg=DST id=12569 t=989000|989999 req="TaskCreate(promise=PromiseCreate(id=p05, param=Value(headers=map[0:6.0 1:6.1], data=), timeout=363438, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p05, processId=p05, ttl=5000, timeout=363438))" res="Response(status=20000, TaskCreate(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=11), value=Value(headers=map[], data=), timeout=904323, tags=map[0:3.0 resonate:invoke:dst resonate:timeout:true], createdOn=20000, completedOn=50000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12570 t=989000|989999 req="PromiseGet(id=p06)" res="Response(status=20000, PromiseGet(promise=Promise(id=p06, state=REJECTED, param=Value(headers=map[0:1.0 1:1.1], data=8), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=178233, tags=map[], createdOn=1000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12572 t=990000|990999 req="CallbackCreate(id=__resume:p19:p14, promiseId=p14, recv=\"dst\", mesg=Mesg(type=resume, root=p19, leaf=p14), timeout=999554)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=8), timeout=716918, tags=map[resonate:invoke:dst], createdOn=10000, completedOn=22000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12573 t=990000|990999 req="ScheduleGet(id=s18)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12574 t=990000|990999 req="ScheduleGet(id=s02)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12575 t=990000|990999 req="CallbackCreate(id=__resume:p10:p02, promiseId=p02, recv=\"dst\", mesg=Mesg(type=resume, root=p10, leaf=p02), timeout=999879)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=), timeout=151701, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=17000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12571 t=990000|990999 req="PromiseGet(id=p14)" res="Response(status=20000, PromiseGet(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=8), timeout=716918, tags=map[resonate:invoke:dst], createdOn=10000, completedOn=22000)))" err=<nil>
level=INFO msg=DST id=12576 t=991000|991999 req="ScheduleGet(id=s01)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12578 t=991000|991999 req="ScheduleCreate(id=s13, desc=, cron=46 * * * *, tags=map[], promiseId=s13.{{.timestamp}}, promiseTimeout=992241, promiseParam=Value(headers=map[0:12.0 1:12.1], data=), promiseTags=map[resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s13, desc=, cron=32 * * * *, tags=map[0:4.0], promiseId=s13.{{.timestamp}}, promiseTimeout=993642, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=1920000, createdOn=964000)))" err=<nil>
level=INFO msg=DST id=12579 t=991000|991999 req="ScheduleDelete(id=s07)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12582 t=991000|991999 req="PromiseComplete(id=p16, state=REJECTED_CANCELED, value=Value(headers=map[], data=5))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[0:2.0 1:2.1], data=9), timeout=677377, tags=map[], createdOn=3000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12577 t=991000|991999 req="PromiseGet(id=p11)" res="Response(status=20000, PromiseGet(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=8), value=Value(headers=map[], data=), timeout=960400, tags=map[resonate:invoke:dst], createdOn=0, completedOn=4000)))" err=<nil>
level=INFO msg=DST id=12580 t=991000|991999 req="TaskCreate(promise=PromiseCreate(id=p11, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), timeout=586920, tags=map[0:8.0 1:8.1 2:8.2 resonate:invoke:dst]), task=CreateTask(promiseId=p11, processId=p11, ttl=9223372036854775807, timeout=586920))" res="Response(status=20000, TaskCreate(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=8), value=Value(headers=map[], data=), timeout=960400, tags=map[resonate:invoke:dst], createdOn=0, completedOn=4000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12581 t=991000|991999 req="PromiseSubscribe(awaited=p04, address=addr00)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=14), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=11), timeout=558943, tags=map[0:4.0], createdOn=5000, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12583 t=991000|991999 req="PromiseSubscribe(awaited=p10, address=addr06)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), value=Value(headers=map[0:2.0 1:2.1], data=), timeout=174201, tags=map[0:4.0], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12585 t=992000|992999 req="ScheduleDelete(id=s14)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12588 t=992000|992999 req="PromiseComplete(id=p06, state=RESOLVED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p06, state=REJECTED, param=Value(headers=map[0:1.0 1:1.1], data=8), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=178233, tags=map[], createdOn=1000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12590 t=992000|992999 req="CallbackCreate(id=__resume:p17:p04, promiseId=p04, recv=\"dst\", mesg=Mesg(type=resume, root=p17, leaf=p04), timeout=995281)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=14), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=11), timeout=558943, tags=map[0:4.0], createdOn=5000, completedOn=33000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12584 t=992000|992999 req="PromiseCreate(id=p12, param=Value(headers=map[], data=), timeout=851375, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=17), value=Value(headers=map[], data=16), timeout=671429, tags=map[0:9.0 1:9.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=6000)))" err=<nil>
level=INFO msg=DST id=12586 t=992000|992999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=4), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=), timeout=395126, tags=map[resonate:invoke:dst], createdOn=4000, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12589 t=992000|992999 req="PromiseSubscribe(awaited=p03, address=addr04)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p03, state=REJECTED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[0:8.0], data=16), timeout=346591, tags=map[0:11.0 resonate:timeout:true], createdOn=29000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12593 t=993000|993999 req="PromiseComplete(id=p03, state=REJECTED, value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=18))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p03, state=REJECTED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[0:8.0], data=16), timeout=346591, tags=map[0:11.0 resonate:timeout:true], createdOn=29000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12594 t=993000|993999 req="ScheduleDelete(id=s04)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12597 t=993000|993999 req="ScheduleDelete(id=s07)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12598 t=993000|993999 req="PromiseComplete(id=p05, state=REJECTED, value=Value(headers=map[0:5.0 1:5.1], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=11), value=Value(headers=map[], data=), timeout=904323, tags=map[0:3.0 resonate:invoke:dst resonate:timeout:true], createdOn=20000, completedOn=50000)))" err=<nil>
level=INFO msg=DST id=12587 t=992000|993999 req="ScheduleCreate(id=s17, desc=, cron=34 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=999945, promiseParam=Value(headers=map[], data=18), promiseTags=map[resonate:invoke:dst resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s17, desc=, cron=34 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=999945, promiseParam=Value(headers=map[], data=18), promiseTags=map[resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=2040000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12591 t=992000|993999 req="ScheduleCreate(id=s18, desc=, cron=33 * * * *, tags=map[], promiseId=s18.{{.timestamp}}, promiseTimeout=994366, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=14), promiseTags=map[0:3.0 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s18, desc=, cron=33 * * * *, tags=map[], promiseId=s18.{{.timestamp}}, promiseTimeout=994366, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=14), promiseTags=map[0:3.0 resonate:timeout:true], lastRunTime=0, nextRunTime=1980000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12592 t=992000|993999 req="ScheduleCreate(id=s01, desc=, cron=3 * * * *, tags=map[0:5.0 1:5.1 resonate:invoke:dst], promiseId=s01.{{.timestamp}}, promiseTimeout=994297, promiseParam=Value(headers=map[], data=7), promiseTags=map[resonate:invoke:dst])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s01, desc=, cron=3 * * * *, tags=map[0:5.0 1:5.1 resonate:invoke:dst], promiseId=s01.{{.timestamp}}, promiseTimeout=994297, promiseParam=Value(headers=map[], data=7), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=3780000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12595 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p00, param=Value(headers=map[0:2.0 1:2.1], data=), timeout=216029, tags=map[0:8.0 1:8.1 2:8.2 resonate:invoke:dst]), task=CreateTask(promiseId=p00, processId=p00, ttl=5000, timeout=216029))" res="Response(status=20000, TaskCreate(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[], data=), timeout=452051, tags=map[0:11.0 resonate:timeout:true], createdOn=1000, completedOn=50000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12596 t=993000|993999 req="PromiseRegister(awaiter=p17, awaited=p00)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[], data=), timeout=452051, tags=map[0:11.0 resonate:timeout:true], createdOn=1000, completedOn=50000)))" err=<nil>
level=INFO msg=DST id=12599 t=994000|994999 req="CallbackCreate(id=__notify:p03:cb02, promiseId=p03, recv=\"dst\", mesg=Mesg(type=notify, root=p03, leaf=), timeout=995760)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p03, state=REJECTED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[0:8.0], data=16), timeout=346591, tags=map[0:11.0 resonate:timeout:true], createdOn=29000, completedOn=32000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12600 t=994000|994999 req="PromiseComplete(id=p10, state=REJECTED_CANCELED, value=Value(headers=map[0:10.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), value=Value(headers=map[0:2.0 1:2.1], data=), timeout=174201, tags=map[0:4.0], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12601 t=994000|994999 req="ScheduleCreate(id=s18, desc=, cron=50 * * * *, tags=map[0:4.0], promiseId=s18.{{.timestamp}}, promiseTimeout=996138, promiseParam=Value(headers=map[], data=), promiseTags=map[resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s18, desc=, cron=33 * * * *, tags=map[], promiseId=s18.{{.timestamp}}, promiseTimeout=994366, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=14), promiseTags=map[0:3.0 resonate:timeout:true], lastRunTime=0, nextRunTime=1980000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12607 t=994000|994999 req="PromiseComplete(id=p02, state=REJECTED, value=Value(headers=map[0:10.0], data=15))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=), timeout=151701, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=17000)))" err=<nil>
level=INFO msg=DST id=12608 t=994000|994999 req="PromiseComplete(id=p19, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), value=Value(headers=map[0:3.0 1:3.1 2:3.2], data=9), timeout=896002, tags=map[], createdOn=0, completedOn=5000)))" err=<nil>
level=INFO msg=DST id=12610 t=994000|994999 req="ScheduleCreate(id=s08, desc=, cron=42 * * * *, tags=map[0:11.0 resonate:timeout:true], promiseId=s08.{{.timestamp}}, promiseTimeout=994607, promiseParam=Value(headers=map[0:7.0 1:7.1], data=11), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s08, desc=, cron=54 * * * *, tags=map[], promiseId=s08.{{.timestamp}}, promiseTimeout=981736, promiseParam=Value(headers=map[0:10.0], data=19), promiseTags=map[0:0.0 resonate:timeout:true], lastRunTime=0, nextRunTime=3240000, createdOn=962000)))" err=<nil>
level=INFO msg=DST id=12603 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p13, param=Value(headers=map[], data=11), timeout=206919, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p13, processId=p13, ttl=3000, timeout=206919))" res="Response(status=20000, TaskCreate(promise=Promise(id=p13, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:2.0 1:2.1], data=3), timeout=973175, tags=map[resonate:timeout:true], createdOn=17000, completedOn=23000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12604 t=994000|994999 req="PromiseGet(id=p08)" res="Response(status=20000, PromiseGet(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[], data=10), value=Value(headers=map[0:2.0 1:2.1], data=12), timeout=992619, tags=map[], createdOn=0, completedOn=4000)))" err=<nil>
level=INFO msg=DST id=12605 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p07, param=Value(headers=map[0:0.0 1:0.1 2:0.2], data=), timeout=322400, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p07, processId=p07, ttl=2000, timeout=322400))" res="Response(status=20000, TaskCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=4), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=), timeout=395126, tags=map[resonate:invoke:dst], createdOn=4000, completedOn=13000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12606 t=994000|994999 req="PromiseCreate(id=p06, param=Value(headers=map[], data=), timeout=583204, tags=map[0:9.0 1:9.1 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p06, state=REJECTED, param=Value(headers=map[0:1.0 1:1.1], data=8), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=178233, tags=map[], createdOn=1000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12609 t=994000|994999 req="PromiseCreate(id=p02, param=Value(headers=map[0:12.0 1:12.1], data=), timeout=665569, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=), timeout=151701, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=17000)))" err=<nil>
level=INFO msg=DST id=12602 t=994000|995999 req="ScheduleCreate(id=s14, desc=, cron=31 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=999167, promiseParam=Value(headers=map[0:5.0 1:5.1], data=), promiseTags=map[0:11.0 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s14, desc=, cron=31 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=999167, promiseParam=Value(headers=map[0:5.0 1:5.1], data=), promiseTags=map[0:11.0 resonate:timeout:true], lastRunTime=0, nextRunTime=1860000, createdOn=995000)))" err=<nil>
level=INFO msg=DST id=12611 t=995000|995999 req="PromiseCreate(id=p13, param=Value(headers=map[], data=), timeout=32531, tags=map[0:8.0 1:8.1 2:8.2])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p13, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:2.0 1:2.1], data=3), timeout=973175, tags=map[resonate:timeout:true], createdOn=17000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12614 t=996000|996999 req="ScheduleDelete(id=s10)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12618 t=996000|996999 req="CallbackCreate(id=__resume:p11:p04, promiseId=p04, recv=\"dst\", mesg=Mesg(type=resume, root=p11, leaf=p04), timeout=997409)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=14), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=11), timeout=558943, tags=map[0:4.0], createdOn=5000, completedOn=33000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12619 t=996000|996999 req="ScheduleGet(id=s16)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s16, desc=, cron=7 * * * *, tags=map[], promiseId=s16.{{.timestamp}}, promiseTimeout=998423, promiseParam=Value(headers=map[], data=12), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=4020000, createdOn=987000)))" err=<nil>
level=INFO msg=DST id=12620 t=996000|996999 req="PromiseComplete(id=p15, state=REJECTED, value=Value(headers=map[], data=3))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12624 t=996000|996999 req="ScheduleDelete(id=s05)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12612 t=996000|996999 req="PromiseRegister(awaiter=p05, awaited=p09)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p09, state=RESOLVED, param=Value(headers=map[0:0.0 1:0.1 2:0.2], data=9), value=Value(headers=map[], data=), timeout=707611, tags=map[0:9.0 1:9.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=28000)))" err=<nil>
level=INFO msg=DST id=12613 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p03, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=9), timeout=910213, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p03, processId=p03, ttl=9223372036854775807, timeout=910213))" res="Response(status=20000, TaskCreate(promise=Promise(id=p03, state=REJECTED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[0:8.0], data=16), timeout=346591, tags=map[0:11.0 resonate:timeout:true], createdOn=29000, completedOn=32000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12615 t=996000|996999 req="PromiseSubscribe(awaited=p05, address=addr03)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=11), value=Value(headers=map[], data=), timeout=904323, tags=map[0:3.0 resonate:invoke:dst resonate:timeout:true], createdOn=20000, completedOn=50000)))" err=<nil>
level=INFO msg=DST id=12616 t=996000|996999 req="PromiseSubscribe(awaited=p15, address=addr07)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12617 t=996000|996999 req="PromiseGet(id=p13)" res="Response(status=20000, PromiseGet(promise=Promise(id=p13, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:2.0 1:2.1], data=3), timeout=973175, tags=map[resonate:timeout:true], createdOn=17000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12621 t=996000|996999 req="PromiseGet(id=p13)" res="Response(status=20000, PromiseGet(promise=Promise(id=p13, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:2.0 1:2.1], data=3), timeout=973175, tags=map[resonate:timeout:true], createdOn=17000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12622 t=996000|996999 req="PromiseRegister(awaiter=p01, awaited=p02)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=), timeout=151701, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=17000)))" err=<nil>
level=INFO msg=DST id=12623 t=996000|996999 req="PromiseCreate(id=p02, param=Value(headers=map[], data=11), timeout=368745, tags=map[0:3.0 resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[], data=), timeout=151701, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=17000)))" err=<nil>
level=INFO msg=DST id=12626 t=997000|997999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12627 t=997000|997999 req="PromiseComplete(id=p03, state=REJECTED, value=Value(headers=map[0:3.0 1:3.1 2:3.2], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p03, state=REJECTED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[0:8.0], data=16), timeout=346591, tags=map[0:11.0 resonate:timeout:true], createdOn=29000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12628 t=997000|997999 req="PromiseComplete(id=p15, state=RESOLVED, value=Value(headers=map[], data=3))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12629 t=997000|997999 req="ScheduleGet(id=s14)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s14, desc=, cron=31 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=999167, promiseParam=Value(headers=map[0:5.0 1:5.1], data=), promiseTags=map[0:11.0 resonate:timeout:true], lastRunTime=0, nextRunTime=1860000, createdOn=995000)))" err=<nil>
level=INFO msg=DST id=12631 t=997000|997999 req="ScheduleGet(id=s17)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s17, desc=, cron=34 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=999945, promiseParam=Value(headers=map[], data=18), promiseTags=map[resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=2040000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12625 t=997000|997999 req="PromiseRegister(awaiter=p14, awaited=p05)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=11), value=Value(headers=map[], data=), timeout=904323, tags=map[0:3.0 resonate:invoke:dst resonate:timeout:true], createdOn=20000, completedOn=50000)))" err=<nil>
level=INFO msg=DST id=12630 t=997000|997999 req="PromiseSubscribe(awaited=p15, address=addr04)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12632 t=998000|998999 req="ScheduleCreate(id=s12, desc=, cron=32 * * * *, tags=map[0:0.0 resonate:timeout:true], promiseId=s12.{{.timestamp}}, promiseTimeout=999615, promiseParam=Value(headers=map[], data=), promiseTags=map[0:5.0 1:5.1 resonate:invoke:dst])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s12, desc=, cron=38 * * * *, tags=map[], promiseId=s12.{{.timestamp}}, promiseTimeout=993621, promiseParam=Value(headers=map[0:7.0 1:7.1], data=3), promiseTags=map[0:1.0 1:1.1 resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=2280000, createdOn=975000)))" err=<nil>
level=INFO msg=DST id=12633 t=998000|998999 req="CallbackCreate(id=__notify:p15:cb13, promiseId=p15, recv=\"dst\", mesg=Mesg(type=notify, root=p15, leaf=), timeout=998220)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=11), timeout=884242, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=32000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12638 t=998000|998999 req="ScheduleGet(id=s17)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s17, desc=, cron=34 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=999945, promiseParam=Value(headers=map[], data=18), promiseTags=map[resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=2040000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12640 t=998000|998999 req="CallbackCreate(id=__notify:p11:cb01, promiseId=p11, recv=\"dst\", mesg=Mesg(type=notify, root=p11, leaf=), timeout=998576)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=8), value=Value(headers=map[], data=), timeout=960400, tags=map[resonate:invoke:dst], createdOn=0, completedOn=4000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12641 t=998000|998999 req="ScheduleGet(id=s13)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s13, desc=, cron=32 * * * *, tags=map[0:4.0], promiseId=s13.{{.timestamp}}, promiseTimeout=993642, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=1920000, createdOn=964000)))" err=<nil>
level=INFO msg=DST id=12643 t=998000|998999 req="ScheduleCreate(id=s14, desc=, cron=25 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=998197, promiseParam=Value(headers=map[0:6.0 1:6.1], data=20), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s14, desc=, cron=31 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=999167, promiseParam=Value(headers=map[0:5.0 1:5.1], data=), promiseTags=map[0:11.0 resonate:timeout:true], lastRunTime=0, nextRunTime=1860000, createdOn=995000)))" err=<nil>
level=INFO msg=DST id=12634 t=998000|998999 req="PromiseRegister(awaiter=p01, awaited=p08)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[], data=10), value=Value(headers=map[0:2.0 1:2.1], data=12), timeout=992619, tags=map[], createdOn=0, completedOn=4000)))" err=<nil>
level=INFO msg=DST id=12635 t=998000|998999 req="PromiseGet(id=p06)" res="Response(status=20000, PromiseGet(promise=Promise(id=p06, state=REJECTED, param=Value(headers=map[0:1.0 1:1.1], data=8), value=Value(headers=map[0:5.0 1:5.1], data=), timeout=178233, tags=map[], createdOn=1000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12636 t=998000|998999 req="PromiseSubscribe(awaited=p07, address=addr07)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=4), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=), timeout=395126, tags=map[resonate:invoke:dst], createdOn=4000, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12637 t=998000|998999 req="PromiseRegister(awaiter=p14, awaited=p17)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p17, state=RESOLVED, param=Value(headers=map[], data=20), value=Value(headers=map[], data=), timeout=485813, tags=map[resonate:invoke:dst], createdOn=33000, completedOn=52000)))" err=<nil>
level=INFO msg=DST id=12639 t=998000|998999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=11), value=Value(headers=map[], data=), timeout=904323, tags=map[0:3.0 resonate:invoke:dst resonate:timeout:true], createdOn=20000, completedOn=50000)))" err=<nil>
level=INFO msg=DST id=12642 t=998000|998999 req="PromiseGet(id=p12)" res="Response(status=20000, PromiseGet(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0 1:12.1], data=17), value=Value(headers=map[], data=16), timeout=671429, tags=map[0:9.0 1:9.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=6000)))" err=<nil>
level=INFO msg=DST id=12644 t=999000|1000999 req="CallbackCreate(id=__resume:p10:p11, promiseId=p11, recv=\"dst\", mesg=Mesg(type=resume, root=p10, leaf=p11), timeout=999070)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=8), value=Value(headers=map[], data=), timeout=960400, tags=map[resonate:invoke:dst], createdOn=0, completedOn=4000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12649 t=999000|1000999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12650 t=999000|1000999 req="PromiseComplete(id=p03, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p03, state=REJECTED, param=Value(headers=map[0:6.0 1:6.1], data=), value=Value(headers=map[0:8.0], data=16), timeout=346591, tags=map[0:11.0 resonate:timeout:true], createdOn=29000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12645 t=999000|1000999 req="PromiseCreate(id=p04, param=Value(headers=map[], data=9), timeout=127229, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[], data=14), value=Value(headers=map[0:0.0 1:0.1 2:0.2], data=11), timeout=558943, tags=map[0:4.0], createdOn=5000, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12646 t=999000|1000999 req="PromiseSubscribe(awaited=p08, address=addr02)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[], data=10), value=Value(headers=map[0:2.0 1:2.1], data=12), timeout=992619, tags=map[], createdOn=0, completedOn=4000)))" err=<nil>
level=INFO msg=DST id=12647 t=999000|1000999 req="PromiseGet(id=p10)" res="Response(status=20000, PromiseGet(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), value=Value(headers=map[0:2.0 1:2.1], data=), timeout=174201, tags=map[0:4.0], createdOn=1000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12648 t=999000|1000999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[], data=), timeout=161067, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=5000, timeout=161067))" res="Response(status=20000, TaskCreate(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[0:2.0 1:2.1], data=9), timeout=677377, tags=map[], createdOn=3000, completedOn=23000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12651 t=999000|1000999 req="PromiseGet(id=p13)" res="Response(status=20000, PromiseGet(promise=Promise(id=p13, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:2.0 1:2.1], data=3), timeout=973175, tags=map[resonate:timeout:true], createdOn=17000, completedOn=23000)))" err=<nil>
level=INFO msg=DST id=12652 t=999000|1000999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[], data=), timeout=621920, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=3000, timeout=621920))" res="Response(status=20000, TaskCreate(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[0:11.0 1:11.1 2:11.2], data=), value=Value(headers=map[0:2.0 1:2.1], data=9), timeout=677377, tags=map[], createdOn=3000, completedOn=23000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12653 t=999000|1000999 req="PromiseCreate(id=p08, param=Value(headers=map[0:9.0 1:9.1 2:9.2], data=), timeout=543499, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[], data=10), value=Value(headers=map[0:2.0 1:2.1], data=12), timeout=992619, tags=map[], createdOn=0, completedOn=4000)))" err=<nil>
level=ERROR msg="DST is non linearizable, run with -v flag for more information" v=false
Error: DST failed for seed='20651'