DST run failed for seed=12737, ticks=1000, scenario=default, store=postgres.
level=INFO msg=DST seed=12737 ticks=1000 reqsPerTick=1:25 dst="DST(ids=17, headers=14, data=7, tags=15, backchannel=323)" system="System(api=API(size=52, subsystems=[]), aio=AIODST(subsystems=[router sender store]), config=Config(cms=589, sbs=861, cbs=106, pbs=71, sbs=31, tbs=69))"
level=INFO msg=DST id=0 t=0|999 req="CallbackCreate(id=__notify:p11:cb16, promiseId=p11, recv=\"dst\", mesg=Mesg(type=notify, root=p11, leaf=), timeout=976523)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=3 t=0|999 req="PromiseComplete(id=p05, state=REJECTED_CANCELED, value=Value(headers=map[0:12.0 1:12.1], data=3))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=5 t=0|999 req="CallbackCreate(id=__notify:p16:cb03, promiseId=p16, recv=\"dst\", mesg=Mesg(type=notify, root=p16, leaf=), timeout=448342)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=7 t=0|999 req="PromiseComplete(id=p16, state=REJECTED, value=Value(headers=map[0:1.0 1:1.1], data=6))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=8 t=0|999 req="ScheduleDelete(id=s13)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=10 t=0|999 req="PromiseComplete(id=p01, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=14 t=0|999 req="CallbackCreate(id=__resume:p13:p08, promiseId=p08, recv=\"dst\", mesg=Mesg(type=resume, root=p13, leaf=p08), timeout=347985)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=17 t=0|999 req="ScheduleDelete(id=s08)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=18 t=0|999 req="CallbackCreate(id=__notify:p00:cb14, promiseId=p00, recv=\"dst\", mesg=Mesg(type=notify, root=p00, leaf=), timeout=22993)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=1 t=0|999 req="PromiseGet(id=p16)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=2 t=0|999 req="PromiseRegister(awaiter=p08, awaited=p03)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=6 t=0|999 req="PromiseGet(id=p09)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=9 t=0|999 req="PromiseRegister(awaiter=p16, awaited=p12)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=20 t=1000|1999 req="PromiseComplete(id=p14, state=RESOLVED, value=Value(headers=map[0:7.0 1:7.1], data=1))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=21 t=1000|1999 req="PromiseComplete(id=p07, state=REJECTED, value=Value(headers=map[0:1.0 1:1.1], data=4))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=11 t=0|1999 req="ScheduleCreate(id=s04, desc=, cron=6 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=863994, promiseParam=Value(headers=map[], data=), promiseTags=map[0:9.0 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=6 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=863994, promiseParam=Value(headers=map[], data=), promiseTags=map[0:9.0 resonate:timeout:true], lastRunTime=0, nextRunTime=360000, createdOn=1000)))" err=<nil>
level=INFO msg=DST id=19 t=1000|1999 req="PromiseSubscribe(awaited=p04, address=addr07)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=23 t=1000|1999 req="PromiseRegister(awaiter=p00, awaited=p06)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=24 t=2000|2999 req="ScheduleDelete(id=s16)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=25 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=26 t=2000|2999 req="CallbackCreate(id=__notify:p01:cb06, promiseId=p01, recv=\"dst\", mesg=Mesg(type=notify, root=p01, leaf=), timeout=564379)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=27 t=2000|2999 req="ScheduleGet(id=s06)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=22 t=1000|2999 req="ScheduleCreate(id=s01, desc=, cron=40 * * * *, tags=map[], promiseId=s01.{{.timestamp}}, promiseTimeout=797447, promiseParam=Value(headers=map[0:4.0 1:4.1], data=1), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s01, desc=, cron=40 * * * *, tags=map[], promiseId=s01.{{.timestamp}}, promiseTimeout=797447, promiseParam=Value(headers=map[0:4.0 1:4.1], data=1), promiseTags=map[], lastRunTime=0, nextRunTime=2400000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=4 t=0|2999 req="PromiseCreate(id=p03, param=Value(headers=map[0:1.0 1:1.1], data=6), timeout=893649, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p03, state=PENDING, param=Value(headers=map[0:1.0 1:1.1], data=6), value=Value(headers=map[], data=), timeout=893649, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=12 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p04, param=Value(headers=map[0:4.0 1:4.1], data=), timeout=543317, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p04, processId=p04, ttl=9223372036854775807, timeout=543317))" res="Response(status=20100, TaskCreate(promise=Promise(id=p04, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=0), task=Task(id=__invoke:p04, processId=p04, state=CLAIMED, rootPromiseId=p04, recv=\"dst\", mesg=Mesg(type=invoke, root=p04, leaf=p04), timeout=543317, counter=1, attempt=0, ttl=9223372036854775807, expiresAt=9223372036854775807, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=13 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p07, param=Value(headers=map[0:4.0 1:4.1], data=), timeout=626364, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p07, processId=p07, ttl=9223372036854775807, timeout=626364))" res="Response(status=20100, TaskCreate(promise=Promise(id=p07, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=0), task=Task(id=__invoke:p07, processId=p07, state=CLAIMED, rootPromiseId=p07, recv=\"dst\", mesg=Mesg(type=invoke, root=p07, leaf=p07), timeout=626364, counter=1, attempt=0, ttl=9223372036854775807, expiresAt=9223372036854775807, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=16 t=0|2999 req="PromiseCreate(id=p05, param=Value(headers=map[0:4.0 1:4.1], data=6), timeout=564388, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p05, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=6), value=Value(headers=map[], data=), timeout=564388, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=28 t=2000|2999 req="PromiseSubscribe(awaited=p03, address=addr04)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=30 t=3000|3999 req="CallbackCreate(id=__resume:p02:p12, promiseId=p12, recv=\"dst\", mesg=Mesg(type=resume, root=p02, leaf=p12), timeout=856274)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=32 t=3000|3999 req="PromiseComplete(id=p02, state=REJECTED, value=Value(headers=map[], data=2))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=33 t=3000|3999 req="CallbackCreate(id=__notify:p09:cb14, promiseId=p09, recv=\"dst\", mesg=Mesg(type=notify, root=p09, leaf=), timeout=337793)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=29 t=3000|3999 req="PromiseGet(id=p15)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=35 t=3000|3999 req="PromiseGet(id=p03)" res="Response(status=20000, PromiseGet(promise=Promise(id=p03, state=PENDING, param=Value(headers=map[0:1.0 1:1.1], data=6), value=Value(headers=map[], data=), timeout=893649, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=15 t=0|3999 req="TaskCreate(promise=PromiseCreate(id=p05, param=Value(headers=map[0:11.0 1:11.1], data=2), timeout=708723, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p05, processId=p05, ttl=5000, timeout=708723))" res="Response(status=20000, TaskCreate(promise=Promise(id=p05, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=6), value=Value(headers=map[], data=), timeout=564388, tags=map[], createdOn=0, completedOn=0), task=<nil>))" err=<nil>
level=INFO msg=DST id=41 t=4000|4999 req="ScheduleDelete(id=s09)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=42 t=4000|4999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=43 t=4000|4999 req="CallbackCreate(id=__resume:p02:p14, promiseId=p14, recv=\"dst\", mesg=Mesg(type=resume, root=p02, leaf=p14), timeout=597051)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=47 t=4000|4999 req="ScheduleGet(id=s15)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=38 t=4000|4999 req="PromiseCreate(id=p05, param=Value(headers=map[], data=2), timeout=854936, tags=map[resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p05, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=6), value=Value(headers=map[], data=), timeout=564388, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=40 t=4000|4999 req="PromiseRegister(awaiter=p06, awaited=p02)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=48 t=5000|5999 req="CallbackCreate(id=__resume:p16:p08, promiseId=p08, recv=\"dst\", mesg=Mesg(type=resume, root=p16, leaf=p08), timeout=878651)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=49 t=5000|5999 req="ScheduleGet(id=s05)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=51 t=5000|5999 req="ScheduleDelete(id=s03)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=53 t=5000|5999 req="PromiseComplete(id=p12, state=REJECTED, value=Value(headers=map[], data=2))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=54 t=5000|5999 req="PromiseComplete(id=p09, state=REJECTED_CANCELED, value=Value(headers=map[0:7.0 1:7.1], data=1))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=55 t=5000|5999 req="ScheduleGet(id=s03)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=59 t=5000|5999 req="ScheduleDelete(id=s03)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=46 t=4000|5999 req="ScheduleCreate(id=s12, desc=, cron=7 * * * *, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], promiseId=s12.{{.timestamp}}, promiseTimeout=343422, promiseParam=Value(headers=map[0:7.0 1:7.1], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s12, desc=, cron=7 * * * *, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], promiseId=s12.{{.timestamp}}, promiseTimeout=343422, promiseParam=Value(headers=map[0:7.0 1:7.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=420000, createdOn=5000)))" err=<nil>
level=INFO msg=DST id=39 t=4000|5999 req="PromiseSubscribe(awaited=p04, address=addr12)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p04, state=PENDING, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=0)))" err=<nil>
...
level=INFO msg=DST id=12663 t=992000|992999 req="CallbackCreate(id=__resume:p08:p03, promiseId=p03, recv=\"dst\", mesg=Mesg(type=resume, root=p08, leaf=p03), timeout=998555)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[0:1.0 1:1.1], data=6), value=Value(headers=map[], data=), timeout=893649, tags=map[], createdOn=0, completedOn=8000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12664 t=992000|992999 req="PromiseComplete(id=p01, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=4), timeout=585770, tags=map[0:13.0 resonate:invoke:dst resonate:timeout:true], createdOn=4000, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12665 t=992000|992999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12667 t=992000|992999 req="PromiseComplete(id=p05, state=RESOLVED, value=Value(headers=map[0:12.0 1:12.1], data=3))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=6), value=Value(headers=map[], data=), timeout=564388, tags=map[], createdOn=0, completedOn=5000)))" err=<nil>
level=INFO msg=DST id=12669 t=992000|992999 req="CallbackCreate(id=__resume:p01:p12, promiseId=p12, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p12), timeout=996230)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:2.0 1:2.1], data=6), value=Value(headers=map[], data=), timeout=736747, tags=map[], createdOn=4000, completedOn=12000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12671 t=992000|992999 req="CallbackCreate(id=__notify:p04:cb15, promiseId=p04, recv=\"dst\", mesg=Mesg(type=notify, root=p04, leaf=), timeout=998861)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=4), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=13000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12649 t=991000|992999 req="ScheduleCreate(id=s04, desc=, cron=37 * * * *, tags=map[resonate:invoke:dst], promiseId=s04.{{.timestamp}}, promiseTimeout=997196, promiseParam=Value(headers=map[0:10.0 1:10.1 2:10.2], data=4), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=37 * * * *, tags=map[resonate:invoke:dst], promiseId=s04.{{.timestamp}}, promiseTimeout=997196, promiseParam=Value(headers=map[0:10.0 1:10.1 2:10.2], data=4), promiseTags=map[], lastRunTime=0, nextRunTime=2220000, createdOn=992000)))" err=<nil>
level=INFO msg=DST id=12656 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[], data=), timeout=718768, tags=map[0:10.0 resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=5000, timeout=718768))" res="Response(status=20000, TaskCreate(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=4), value=Value(headers=map[], data=0), timeout=264237, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=24000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12657 t=992000|992999 req="PromiseCreate(id=p07, param=Value(headers=map[], data=6), timeout=268839, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000)))" err=<nil>
level=INFO msg=DST id=12662 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p04, param=Value(headers=map[0:9.0 1:9.1], data=5), timeout=386223, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p04, processId=p04, ttl=9223372036854775807, timeout=386223))" res="Response(status=20000, TaskCreate(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=4), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=13000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12666 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p00, param=Value(headers=map[], data=), timeout=689912, tags=map[0:13.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p00, processId=p00, ttl=9223372036854775807, timeout=689912))" res="Response(status=20000, TaskCreate(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[], data=5), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=966820, tags=map[0:9.0 resonate:invoke:dst resonate:timeout:true], createdOn=18000, completedOn=28000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12668 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p05, param=Value(headers=map[0:12.0 1:12.1], data=), timeout=181751, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p05, processId=p05, ttl=5000, timeout=181751))" res="Response(status=20000, TaskCreate(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:4.0 1:4.1], data=6), value=Value(headers=map[], data=), timeout=564388, tags=map[], createdOn=0, completedOn=5000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12670 t=992000|992999 req="PromiseRegister(awaiter=p07, awaited=p02)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=1), timeout=530875, tags=map[resonate:invoke:dst], createdOn=9000, completedOn=22000)))" err=<nil>
level=INFO msg=DST id=12672 t=992000|992999 req="PromiseSubscribe(awaited=p08, address=addr10)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:0.0 1:0.1], data=), timeout=802606, tags=map[resonate:invoke:dst], createdOn=8000, completedOn=27000)))" err=<nil>
level=INFO msg=DST id=12676 t=993000|993999 req="ScheduleGet(id=s00)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s00, desc=, cron=16 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=999873, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4560000, createdOn=978000)))" err=<nil>
level=INFO msg=DST id=12679 t=993000|993999 req="ScheduleDelete(id=s13)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12673 t=993000|993999 req="PromiseRegister(awaiter=p08, awaited=p16)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=4), value=Value(headers=map[], data=0), timeout=264237, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=24000)))" err=<nil>
level=INFO msg=DST id=12675 t=993000|993999 req="PromiseGet(id=p14)" res="Response(status=20000, PromiseGet(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:10.0 1:10.1 2:10.2], data=), value=Value(headers=map[], data=0), timeout=125518, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12677 t=993000|993999 req="PromiseCreate(id=p15, param=Value(headers=map[0:5.0], data=5), timeout=341297, tags=map[0:14.0 1:14.1])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p15, state=RESOLVED, param=Value(headers=map[0:7.0 1:7.1], data=), value=Value(headers=map[], data=3), timeout=678386, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=63000)))" err=<nil>
level=INFO msg=DST id=12678 t=993000|993999 req="PromiseGet(id=p00)" res="Response(status=20000, PromiseGet(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[], data=5), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=966820, tags=map[0:9.0 resonate:invoke:dst resonate:timeout:true], createdOn=18000, completedOn=28000)))" err=<nil>
level=INFO msg=DST id=12680 t=993000|993999 req="PromiseSubscribe(awaited=p06, address=addr05)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=574664, tags=map[resonate:invoke:dst], createdOn=14000, completedOn=44000)))" err=<nil>
level=INFO msg=DST id=12685 t=994000|994999 req="CallbackCreate(id=__resume:p08:p09, promiseId=p09, recv=\"dst\", mesg=Mesg(type=resume, root=p08, leaf=p09), timeout=994021)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p09, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=3), value=Value(headers=map[], data=2), timeout=977198, tags=map[resonate:invoke:dst], createdOn=10000, completedOn=19000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12686 t=994000|994999 req="CallbackCreate(id=__notify:p07:cb09, promiseId=p07, recv=\"dst\", mesg=Mesg(type=notify, root=p07, leaf=), timeout=998439)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12674 t=993000|994999 req="ScheduleCreate(id=s06, desc=, cron=1 * * * *, tags=map[resonate:invoke:dst], promiseId=s06.{{.timestamp}}, promiseTimeout=999544, promiseParam=Value(headers=map[0:1.0 1:1.1], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s06, desc=, cron=1 * * * *, tags=map[resonate:invoke:dst], promiseId=s06.{{.timestamp}}, promiseTimeout=999544, promiseParam=Value(headers=map[0:1.0 1:1.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=3660000, createdOn=994000)))" err=<nil>
level=INFO msg=DST id=12681 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[0:8.0 1:8.1], data=), timeout=153473, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=1000, timeout=153473))" res="Response(status=20000, TaskCreate(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=4), value=Value(headers=map[], data=0), timeout=264237, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=24000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12682 t=994000|994999 req="PromiseSubscribe(awaited=p04, address=addr15)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=4), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12683 t=994000|994999 req="PromiseCreate(id=p04, param=Value(headers=map[], data=), timeout=194176, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=4), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12684 t=994000|994999 req="PromiseCreate(id=p03, param=Value(headers=map[], data=), timeout=65948, tags=map[resonate:invoke:dst])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[0:1.0 1:1.1], data=6), value=Value(headers=map[], data=), timeout=893649, tags=map[], createdOn=0, completedOn=8000)))" err=<nil>
level=INFO msg=DST id=12687 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p06, param=Value(headers=map[0:5.0], data=1), timeout=266353, tags=map[0:4.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p06, processId=p06, ttl=3000, timeout=266353))" res="Response(status=20000, TaskCreate(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=574664, tags=map[resonate:invoke:dst], createdOn=14000, completedOn=44000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12689 t=995000|995999 req="PromiseComplete(id=p04, state=REJECTED, value=Value(headers=map[0:12.0 1:12.1], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=4), timeout=543317, tags=map[resonate:invoke:dst], createdOn=0, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12691 t=995000|995999 req="PromiseComplete(id=p07, state=RESOLVED, value=Value(headers=map[0:11.0 1:11.1], data=0))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000)))" err=<nil>
level=INFO msg=DST id=12694 t=995000|995999 req="CallbackCreate(id=__resume:p06:p16, promiseId=p16, recv=\"dst\", mesg=Mesg(type=resume, root=p06, leaf=p16), timeout=998624)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=4), value=Value(headers=map[], data=0), timeout=264237, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=24000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12695 t=995000|995999 req="CallbackCreate(id=__notify:p07:cb12, promiseId=p07, recv=\"dst\", mesg=Mesg(type=notify, root=p07, leaf=), timeout=999796)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12701 t=995000|995999 req="ScheduleCreate(id=s04, desc=, cron=0 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=996842, promiseParam=Value(headers=map[], data=2), promiseTags=map[0:10.0])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=37 * * * *, tags=map[resonate:invoke:dst], promiseId=s04.{{.timestamp}}, promiseTimeout=997196, promiseParam=Value(headers=map[0:10.0 1:10.1 2:10.2], data=4), promiseTags=map[], lastRunTime=0, nextRunTime=2220000, createdOn=992000)))" err=<nil>
level=INFO msg=DST id=12688 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[0:10.0 1:10.1 2:10.2], data=4), timeout=944331, tags=map[0:14.0 1:14.1 resonate:invoke:dst]), task=CreateTask(promiseId=p10, processId=p10, ttl=2000, timeout=944331))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[0:11.0 1:11.1], data=), timeout=760394, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=25000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12690 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p02, param=Value(headers=map[0:12.0 1:12.1], data=), timeout=720356, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p02, processId=p02, ttl=2000, timeout=720356))" res="Response(status=20000, TaskCreate(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=1), timeout=530875, tags=map[resonate:invoke:dst], createdOn=9000, completedOn=22000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12692 t=995000|995999 req="PromiseRegister(awaiter=p04, awaited=p15)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p15, state=RESOLVED, param=Value(headers=map[0:7.0 1:7.1], data=), value=Value(headers=map[], data=3), timeout=678386, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=63000)))" err=<nil>
level=INFO msg=DST id=12696 t=995000|995999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000)))" err=<nil>
level=INFO msg=DST id=12697 t=995000|995999 req="PromiseGet(id=p08)" res="Response(status=20000, PromiseGet(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:0.0 1:0.1], data=), timeout=802606, tags=map[resonate:invoke:dst], createdOn=8000, completedOn=27000)))" err=<nil>
level=INFO msg=DST id=12698 t=995000|995999 req="PromiseRegister(awaiter=p01, awaited=p13)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=916402, tags=map[resonate:timeout:true], createdOn=6000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12699 t=995000|995999 req="PromiseGet(id=p06)" res="Response(status=20000, PromiseGet(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=574664, tags=map[resonate:invoke:dst], createdOn=14000, completedOn=44000)))" err=<nil>
level=INFO msg=DST id=12704 t=996000|996999 req="PromiseComplete(id=p02, state=REJECTED_CANCELED, value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=1), timeout=530875, tags=map[resonate:invoke:dst], createdOn=9000, completedOn=22000)))" err=<nil>
level=INFO msg=DST id=12707 t=996000|996999 req="ScheduleCreate(id=s02, desc=, cron=59 * * * *, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], promiseId=s02.{{.timestamp}}, promiseTimeout=996673, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s02, desc=, cron=30 * * * *, tags=map[resonate:invoke:dst], promiseId=s02.{{.timestamp}}, promiseTimeout=977010, promiseParam=Value(headers=map[], data=5), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=1800000, createdOn=964000)))" err=<nil>
level=INFO msg=DST id=12708 t=996000|996999 req="ScheduleGet(id=s11)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s11, desc=, cron=46 * * * *, tags=map[], promiseId=s11.{{.timestamp}}, promiseTimeout=986399, promiseParam=Value(headers=map[0:9.0 1:9.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2760000, createdOn=981000)))" err=<nil>
level=INFO msg=DST id=12709 t=996000|996999 req="CallbackCreate(id=__resume:p03:p00, promiseId=p00, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p00), timeout=999659)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[], data=5), value=Value(headers=map[0:6.0 1:6.1 2:6.2], data=), timeout=966820, tags=map[0:9.0 resonate:invoke:dst resonate:timeout:true], createdOn=18000, completedOn=28000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12710 t=996000|996999 req="ScheduleDelete(id=s01)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12711 t=996000|996999 req="ScheduleDelete(id=s04)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12693 t=995000|996999 req="ScheduleCreate(id=s14, desc=, cron=39 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=997750, promiseParam=Value(headers=map[0:0.0 1:0.1], data=), promiseTags=map[0:0.0 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s14, desc=, cron=39 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=997750, promiseParam=Value(headers=map[0:0.0 1:0.1], data=), promiseTags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=2340000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12700 t=995000|996999 req="ScheduleCreate(id=s05, desc=, cron=16 * * * *, tags=map[resonate:timeout:true], promiseId=s05.{{.timestamp}}, promiseTimeout=998487, promiseParam=Value(headers=map[0:5.0], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s05, desc=, cron=16 * * * *, tags=map[resonate:timeout:true], promiseId=s05.{{.timestamp}}, promiseTimeout=998487, promiseParam=Value(headers=map[0:5.0], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4560000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12702 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p15, param=Value(headers=map[0:1.0 1:1.1], data=6), timeout=433026, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p15, processId=p15, ttl=9223372036854775807, timeout=433026))" res="Response(status=20000, TaskCreate(promise=Promise(id=p15, state=RESOLVED, param=Value(headers=map[0:7.0 1:7.1], data=), value=Value(headers=map[], data=3), timeout=678386, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=63000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12703 t=996000|996999 req="PromiseSubscribe(awaited=p10, address=addr07)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p10, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[0:11.0 1:11.1], data=), timeout=760394, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12705 t=996000|996999 req="PromiseGet(id=p11)" res="Response(status=20000, PromiseGet(promise=Promise(id=p11, state=REJECTED_CANCELED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=), timeout=663535, tags=map[0:9.0 resonate:timeout:true], createdOn=11000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12706 t=996000|996999 req="PromiseGet(id=p08)" res="Response(status=20000, PromiseGet(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:0.0 1:0.1], data=), timeout=802606, tags=map[resonate:invoke:dst], createdOn=8000, completedOn=27000)))" err=<nil>
level=INFO msg=DST id=12712 t=996000|996999 req="PromiseSubscribe(awaited=p13, address=addr08)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=916402, tags=map[resonate:timeout:true], createdOn=6000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12713 t=997000|997999 req="ScheduleDelete(id=s06)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12714 t=997000|997999 req="CallbackCreate(id=__resume:p12:p07, promiseId=p07, recv=\"dst\", mesg=Mesg(type=resume, root=p12, leaf=p07), timeout=997159)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12716 t=997000|997999 req="ScheduleGet(id=s00)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s00, desc=, cron=16 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=999873, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4560000, createdOn=978000)))" err=<nil>
level=INFO msg=DST id=12718 t=997000|997999 req="ScheduleDelete(id=s01)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12725 t=997000|997999 req="ScheduleCreate(id=s14, desc=, cron=2 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=999015, promiseParam=Value(headers=map[], data=), promiseTags=map[0:6.0 resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s14, desc=, cron=39 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=997750, promiseParam=Value(headers=map[0:0.0 1:0.1], data=), promiseTags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], lastRunTime=0, nextRunTime=2340000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12727 t=997000|997999 req="CallbackCreate(id=__notify:p09:cb12, promiseId=p09, recv=\"dst\", mesg=Mesg(type=notify, root=p09, leaf=), timeout=997433)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p09, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=3), value=Value(headers=map[], data=2), timeout=977198, tags=map[resonate:invoke:dst], createdOn=10000, completedOn=19000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12730 t=997000|997999 req="ScheduleDelete(id=s01)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12731 t=997000|997999 req="CallbackCreate(id=__notify:p09:cb12, promiseId=p09, recv=\"dst\", mesg=Mesg(type=notify, root=p09, leaf=), timeout=997511)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p09, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=3), value=Value(headers=map[], data=2), timeout=977198, tags=map[resonate:invoke:dst], createdOn=10000, completedOn=19000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12715 t=997000|997999 req="PromiseCreate(id=p12, param=Value(headers=map[0:9.0 1:9.1], data=), timeout=691395, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:2.0 1:2.1], data=6), value=Value(headers=map[], data=), timeout=736747, tags=map[], createdOn=4000, completedOn=12000)))" err=<nil>
level=INFO msg=DST id=12717 t=997000|997999 req="PromiseGet(id=p06)" res="Response(status=20000, PromiseGet(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=574664, tags=map[resonate:invoke:dst], createdOn=14000, completedOn=44000)))" err=<nil>
level=INFO msg=DST id=12719 t=997000|997999 req="PromiseCreate(id=p14, param=Value(headers=map[], data=), timeout=295253, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:10.0 1:10.1 2:10.2], data=), value=Value(headers=map[], data=0), timeout=125518, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12720 t=997000|997999 req="PromiseRegister(awaiter=p08, awaited=p06)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=574664, tags=map[resonate:invoke:dst], createdOn=14000, completedOn=44000)))" err=<nil>
level=INFO msg=DST id=12722 t=997000|997999 req="PromiseCreate(id=p06, param=Value(headers=map[0:5.0], data=), timeout=504693, tags=map[0:13.0 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=0), value=Value(headers=map[], data=), timeout=574664, tags=map[resonate:invoke:dst], createdOn=14000, completedOn=44000)))" err=<nil>
level=INFO msg=DST id=12723 t=997000|997999 req="PromiseCreate(id=p08, param=Value(headers=map[], data=5), timeout=309251, tags=map[resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:0.0 1:0.1], data=), timeout=802606, tags=map[resonate:invoke:dst], createdOn=8000, completedOn=27000)))" err=<nil>
level=INFO msg=DST id=12724 t=997000|997999 req="PromiseGet(id=p14)" res="Response(status=20000, PromiseGet(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:10.0 1:10.1 2:10.2], data=), value=Value(headers=map[], data=0), timeout=125518, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12726 t=997000|997999 req="PromiseGet(id=p01)" res="Response(status=20000, PromiseGet(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=4), timeout=585770, tags=map[0:13.0 resonate:invoke:dst resonate:timeout:true], createdOn=4000, completedOn=33000)))" err=<nil>
level=INFO msg=DST id=12729 t=997000|997999 req="PromiseGet(id=p10)" res="Response(status=20000, PromiseGet(promise=Promise(id=p10, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[0:11.0 1:11.1], data=), timeout=760394, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12732 t=997000|997999 req="PromiseRegister(awaiter=p08, awaited=p16)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0 1:4.1], data=4), value=Value(headers=map[], data=0), timeout=264237, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=24000)))" err=<nil>
level=INFO msg=DST id=12735 t=998000|998999 req="CallbackCreate(id=__notify:p12:cb16, promiseId=p12, recv=\"dst\", mesg=Mesg(type=notify, root=p12, leaf=), timeout=999942)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:2.0 1:2.1], data=6), value=Value(headers=map[], data=), timeout=736747, tags=map[], createdOn=4000, completedOn=12000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12738 t=998000|998999 req="ScheduleCreate(id=s05, desc=, cron=16 * * * *, tags=map[], promiseId=s05.{{.timestamp}}, promiseTimeout=999972, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s05, desc=, cron=16 * * * *, tags=map[resonate:timeout:true], promiseId=s05.{{.timestamp}}, promiseTimeout=998487, promiseParam=Value(headers=map[0:5.0], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4560000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12739 t=998000|998999 req="ScheduleCreate(id=s02, desc=, cron=25 * * * *, tags=map[], promiseId=s02.{{.timestamp}}, promiseTimeout=999764, promiseParam=Value(headers=map[], data=0), promiseTags=map[resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s02, desc=, cron=30 * * * *, tags=map[resonate:invoke:dst], promiseId=s02.{{.timestamp}}, promiseTimeout=977010, promiseParam=Value(headers=map[], data=5), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=1800000, createdOn=964000)))" err=<nil>
level=INFO msg=DST id=12740 t=998000|998999 req="ScheduleCreate(id=s12, desc=, cron=21 * * * *, tags=map[0:9.0 resonate:timeout:true], promiseId=s12.{{.timestamp}}, promiseTimeout=999373, promiseParam=Value(headers=map[], data=5), promiseTags=map[0:6.0 resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s12, desc=, cron=13 * * * *, tags=map[], promiseId=s12.{{.timestamp}}, promiseTimeout=999459, promiseParam=Value(headers=map[0:4.0 1:4.1], data=0), promiseTags=map[], lastRunTime=0, nextRunTime=4380000, createdOn=987000)))" err=<nil>
level=INFO msg=DST id=12721 t=997000|998999 req="ScheduleCreate(id=s01, desc=, cron=16 * * * *, tags=map[], promiseId=s01.{{.timestamp}}, promiseTimeout=997311, promiseParam=Value(headers=map[], data=6), promiseTags=map[0:9.0 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s01, desc=, cron=16 * * * *, tags=map[], promiseId=s01.{{.timestamp}}, promiseTimeout=997311, promiseParam=Value(headers=map[], data=6), promiseTags=map[0:9.0 resonate:timeout:true], lastRunTime=0, nextRunTime=4560000, createdOn=998000)))" err=<nil>
level=INFO msg=DST id=12728 t=997000|998999 req="ScheduleCreate(id=s13, desc=, cron=0 * * * *, tags=map[], promiseId=s13.{{.timestamp}}, promiseTimeout=997129, promiseParam=Value(headers=map[], data=5), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s13, desc=, cron=0 * * * *, tags=map[], promiseId=s13.{{.timestamp}}, promiseTimeout=997129, promiseParam=Value(headers=map[], data=5), promiseTags=map[], lastRunTime=0, nextRunTime=3600000, createdOn=998000)))" err=<nil>
level=INFO msg=DST id=12733 t=998000|998999 req="PromiseGet(id=p02)" res="Response(status=20000, PromiseGet(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=1), timeout=530875, tags=map[resonate:invoke:dst], createdOn=9000, completedOn=22000)))" err=<nil>
level=INFO msg=DST id=12734 t=998000|998999 req="PromiseRegister(awaiter=p08, awaited=p15)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p15, state=RESOLVED, param=Value(headers=map[0:7.0 1:7.1], data=), value=Value(headers=map[], data=3), timeout=678386, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=63000)))" err=<nil>
level=INFO msg=DST id=12736 t=998000|998999 req="PromiseSubscribe(awaited=p15, address=addr00)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p15, state=RESOLVED, param=Value(headers=map[0:7.0 1:7.1], data=), value=Value(headers=map[], data=3), timeout=678386, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=63000)))" err=<nil>
level=INFO msg=DST id=12741 t=999000|1000999 req="CallbackCreate(id=__resume:p04:p08, promiseId=p08, recv=\"dst\", mesg=Mesg(type=resume, root=p04, leaf=p08), timeout=999319)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:0.0 1:0.1], data=), timeout=802606, tags=map[resonate:invoke:dst], createdOn=8000, completedOn=27000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12742 t=999000|1000999 req="ScheduleGet(id=s01)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s01, desc=, cron=16 * * * *, tags=map[], promiseId=s01.{{.timestamp}}, promiseTimeout=997311, promiseParam=Value(headers=map[], data=6), promiseTags=map[0:9.0 resonate:timeout:true], lastRunTime=0, nextRunTime=4560000, createdOn=998000)))" err=<nil>
level=INFO msg=DST id=12746 t=999000|1000999 req="ScheduleGet(id=s00)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s00, desc=, cron=16 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=999873, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4560000, createdOn=978000)))" err=<nil>
level=INFO msg=DST id=12748 t=999000|1000999 req="ScheduleDelete(id=s10)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12756 t=999000|1000999 req="ScheduleDelete(id=s02)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12737 t=998000|1000999 req="ScheduleCreate(id=s06, desc=, cron=15 * * * *, tags=map[], promiseId=s06.{{.timestamp}}, promiseTimeout=999992, promiseParam=Value(headers=map[], data=), promiseTags=map[0:6.0 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s06, desc=, cron=15 * * * *, tags=map[], promiseId=s06.{{.timestamp}}, promiseTimeout=999992, promiseParam=Value(headers=map[], data=), promiseTags=map[0:6.0 resonate:timeout:true], lastRunTime=0, nextRunTime=4500000, createdOn=999000)))" err=<nil>
level=INFO msg=DST id=12743 t=999000|1000999 req="PromiseRegister(awaiter=p16, awaited=p14)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:10.0 1:10.1 2:10.2], data=), value=Value(headers=map[], data=0), timeout=125518, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12744 t=999000|1000999 req="PromiseRegister(awaiter=p03, awaited=p10)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12745 t=999000|1000999 req="PromiseSubscribe(awaited=p11, address=addr09)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p11, state=REJECTED_CANCELED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=), timeout=663535, tags=map[0:9.0 resonate:timeout:true], createdOn=11000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12747 t=999000|1000999 req="TaskCreate(promise=PromiseCreate(id=p07, param=Value(headers=map[], data=), timeout=882994, tags=map[0:13.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p07, processId=p07, ttl=3000, timeout=882994))" res="Response(status=20000, TaskCreate(promise=Promise(id=p07, state=REJECTED, param=Value(headers=map[0:4.0 1:4.1], data=), value=Value(headers=map[], data=), timeout=626364, tags=map[resonate:invoke:dst], createdOn=0, completedOn=48000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12749 t=999000|1000999 req="PromiseGet(id=p14)" res="Response(status=20000, PromiseGet(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:10.0 1:10.1 2:10.2], data=), value=Value(headers=map[], data=0), timeout=125518, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12750 t=999000|1000999 req="PromiseSubscribe(awaited=p11, address=addr07)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p11, state=REJECTED_CANCELED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=), timeout=663535, tags=map[0:9.0 resonate:timeout:true], createdOn=11000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12751 t=999000|1000999 req="PromiseCreate(id=p03, param=Value(headers=map[], data=3), timeout=321064, tags=map[0:6.0 resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[0:1.0 1:1.1], data=6), value=Value(headers=map[], data=), timeout=893649, tags=map[], createdOn=0, completedOn=8000)))" err=<nil>
level=INFO msg=DST id=12752 t=999000|1000999 req="PromiseSubscribe(awaited=p10, address=addr00)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p10, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[0:11.0 1:11.1], data=), timeout=760394, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12753 t=999000|1000999 req="PromiseSubscribe(awaited=p13, address=addr00)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=916402, tags=map[resonate:timeout:true], createdOn=6000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12754 t=999000|1000999 req="PromiseRegister(awaiter=p15, awaited=p10)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p10, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[0:11.0 1:11.1], data=), timeout=760394, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], createdOn=3000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12755 t=999000|1000999 req="PromiseRegister(awaiter=p05, awaited=p02)" 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='12737'
DST Failed
DST run failed for seed=12737, ticks=1000, scenario=default, store=postgres.
Seed
Scenario
Store
Commit
Command
Logs
more details