DST: 28987 #1053
Description
DST Failed
DST run failed for seed=28987, ticks=1000, scenario=default, store=sqlite.
Seed
28987
Scenario
default
Store
sqlite
Commit
5514db3b6a3a0334fb74c30c5fba44f7af6dcee8
Command
go run ./... dst run --seed 28987 --ticks 1000 --scenario default --aio-store-sqlite-enable
Logs
level=INFO msg=DST seed=28987 ticks=1000 reqsPerTick=1:25 dst="DST(ids=24, headers=6, data=21, tags=24, backchannel=388)" system="System(api=API(size=686, subsystems=[]), aio=AIODST(subsystems=[router sender store]), config=Config(cms=649, sbs=215, cbs=540, pbs=29, sbs=70, tbs=44))"
level=INFO msg=DST id=2 t=0|999 req="ScheduleDelete(id=s12)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=0 t=0|999 req="PromiseSubscribe(awaited=p03, address=addr10)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=3 t=0|999 req="PromiseGet(id=p07)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=4 t=0|999 req="PromiseRegister(awaiter=p04, awaited=p13)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=6 t=1000|1999 req="CallbackCreate(id=__resume:p13:p10, promiseId=p10, recv=\"dst\", mesg=Mesg(type=resume, root=p13, leaf=p10), timeout=178863)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=7 t=1000|1999 req="ScheduleGet(id=s12)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=8 t=1000|1999 req="ScheduleGet(id=s15)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=41 t=3000|3000 req="CallbackCreate(id=__resume:p01:p01, promiseId=p01, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p01), timeout=702177)" res=<nil> err="The request is invalid"
level=INFO msg=DST id=12 t=2000|2999 req="ScheduleDelete(id=s23)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=14 t=2000|2999 req="CallbackCreate(id=__notify:p11:cb03, promiseId=p11, recv=\"dst\", mesg=Mesg(type=notify, root=p11, leaf=), timeout=976672)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=16 t=2000|2999 req="CallbackCreate(id=__resume:p01:p00, promiseId=p00, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p00), timeout=690415)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=18 t=2000|2999 req="ScheduleGet(id=s05)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=21 t=2000|2999 req="ScheduleDelete(id=s22)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=22 t=2000|2999 req="ScheduleDelete(id=s20)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=25 t=2000|2999 req="ScheduleDelete(id=s18)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=27 t=2000|2999 req="CallbackCreate(id=__notify:p14:cb03, promiseId=p14, recv=\"dst\", mesg=Mesg(type=notify, root=p14, leaf=), timeout=420191)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=1 t=0|2999 req="PromiseCreate(id=p10, param=Value(headers=map[0:5.0 1:5.1], data=), timeout=530545, tags=map[0:22.0 1:22.1])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p10, state=PENDING, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[], data=), timeout=530545, tags=map[0:22.0 1:22.1], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=10 t=2000|2999 req="PromiseGet(id=p18)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=17 t=2000|2999 req="PromiseGet(id=p18)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=23 t=2000|2999 req="PromiseSubscribe(awaited=p06, address=addr05)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=24 t=2000|2999 req="PromiseGet(id=p19)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=26 t=2000|2999 req="PromiseSubscribe(awaited=p21, address=addr16)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=31 t=3000|3999 req="ScheduleGet(id=s10)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=34 t=3000|3999 req="ScheduleGet(id=s23)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=37 t=3000|3999 req="ScheduleGet(id=s01)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=42 t=3000|3999 req="ScheduleGet(id=s19)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=43 t=3000|3999 req="PromiseComplete(id=p08, state=REJECTED_CANCELED, value=Value(headers=map[0:4.0], data=1))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=19 t=2000|3999 req="ScheduleCreate(id=s11, desc=, cron=45 * * * *, tags=map[resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=318939, promiseParam=Value(headers=map[], data=), promiseTags=map[resonate:invoke:dst])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=45 * * * *, tags=map[resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=318939, promiseParam=Value(headers=map[], data=), promiseTags=map[resonate:invoke:dst], lastRunTime=0, nextRunTime=2700000, createdOn=3000)))" err=<nil>
level=INFO msg=DST id=5 t=1000|3999 req="PromiseCreate(id=p05, param=Value(headers=map[0:4.0], data=), timeout=289290, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p05, state=PENDING, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=), timeout=289290, tags=map[], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=28 t=3000|3999 req="PromiseGet(id=p13)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=29 t=3000|3999 req="PromiseGet(id=p21)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=32 t=3000|3999 req="PromiseRegister(awaiter=p10, awaited=p00)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=35 t=3000|3999 req="PromiseRegister(awaiter=p16, awaited=p14)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=45 t=4000|4999 req="ScheduleDelete(id=s23)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=48 t=4000|4999 req="ScheduleGet(id=s16)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=50 t=4000|4999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=52 t=4000|4999 req="CallbackCreate(id=__resume:p11:p03, promiseId=p03, recv=\"dst\", mesg=Mesg(type=resume, root=p11, leaf=p03), timeout=357118)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=40 t=3000|4999 req="ScheduleCreate(id=s17, desc=, cron=0 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=709410, promiseParam=Value(headers=map[], data=15), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s17, desc=, cron=0 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=709410, promiseParam=Value(headers=map[], data=15), promiseTags=map[], lastRunTime=0, nextRunTime=3600000, createdOn=4000)))" err=<nil>
level=INFO msg=DST id=9 t=2000|4999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[], data=), timeout=209000, tags=map[0:2.0 1:2.1 2:2.2 resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=9223372036854775807, timeout=209000))" res="Response(status=20100, TaskCreate(promise=Promise(id=p16, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=209000, tags=map[0:2.0 1:2.1 2:2.2 resonate:invoke:dst], createdOn=2000, completedOn=0), task=Task(id=__invoke:p16, processId=p16, state=CLAIMED, rootPromiseId=p16, recv=\"dst\", mesg=Mesg(type=invoke, root=p16, leaf=p16), timeout=209000, counter=1, attempt=0, ttl=9223372036854775807, expiresAt=9223372036854775807, createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=11 t=2000|4999 req="TaskCreate(promise=PromiseCreate(id=p07, param=Value(headers=map[0:0.0 1:0.1], data=7), timeout=29342, tags=map[0:12.0 resonate:invoke:dst]), task=CreateTask(promiseId=p07, processId=p07, ttl=1000, timeout=29342))" res="Response(status=20100, TaskCreate(promise=Promise(id=p07, state=PENDING, param=Value(headers=map[0:0.0 1:0.1], data=7), value=Value(headers=map[], data=), timeout=29342, tags=map[0:12.0 resonate:invoke:dst], createdOn=2000, completedOn=0), task=Task(id=__invoke:p07, processId=p07, state=CLAIMED, rootPromiseId=p07, recv=\"dst\", mesg=Mesg(type=invoke, root=p07, leaf=p07), timeout=29342, counter=1, attempt=0, ttl=1000, expiresAt=3000, createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=13 t=2000|4999 req="PromiseCreate(id=p12, param=Value(headers=map[], data=), timeout=52222, tags=map[0:2.0 1:2.1 2:2.2])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p12, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=52222, tags=map[0:2.0 1:2.1 2:2.2], createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=15 t=2000|4999 req="PromiseCreate(id=p21, param=Value(headers=map[0:5.0 1:5.1], data=), timeout=990463, tags=map[0:1.0 1:1.1])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p21, state=PENDING, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[], data=), timeout=990463, tags=map[0:1.0 1:1.1], createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=20 t=2000|4999 req="TaskCreate(promise=PromiseCreate(id=p00, param=Value(headers=map[], data=), timeout=160221, tags=map[0:12.0 resonate:invoke:dst]), task=CreateTask(promiseId=p00, processId=p00, ttl=5000, timeout=160221))" res="Response(status=20100, TaskCreate(promise=Promise(id=p00, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=160221, tags=map[0:12.0 resonate:invoke:dst], createdOn=2000, completedOn=0), task=Task(id=__invoke:p00, processId=p00, state=CLAIMED, rootPromiseId=p00, recv=\"dst\", mesg=Mesg(type=invoke, root=p00, leaf=p00), timeout=160221, counter=1, attempt=0, ttl=5000, expiresAt=7000, createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=44 t=4000|4999 req="PromiseGet(id=p08)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=47 t=4000|4999 req="PromiseGet(id=p19)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=53 t=4000|4999 req="PromiseSubscribe(awaited=p06, address=addr20)" res="Response(status=40400, PromiseSubscribe(promise=<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=60 t=5000|5999 req="CallbackCreate(id=__notify:p15:cb02, promiseId=p15, recv=\"dst\", mesg=Mesg(type=notify, root=p15, leaf=), timeout=97196)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=63 t=5000|5999 req="ScheduleGet(id=s17)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s17, desc=, cron=0 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=709410, promiseParam=Value(headers=map[], data=15), promiseTags=map[], lastRunTime=0, nextRunTime=3600000, createdOn=4000)))" err=<nil>
...
level=INFO msg=DST id=12545 t=991000|991999 req="PromiseCreate(id=p10, param=Value(headers=map[], data=12), timeout=204413, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=9), timeout=530545, tags=map[0:22.0 1:22.1], createdOn=0, completedOn=12000)))" err=<nil>
level=INFO msg=DST id=12550 t=992000|992999 req="ScheduleDelete(id=s02)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12551 t=992000|992999 req="CallbackCreate(id=__notify:p10:cb08, promiseId=p10, recv=\"dst\", mesg=Mesg(type=notify, root=p10, leaf=), timeout=997781)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=9), timeout=530545, tags=map[0:22.0 1:22.1], createdOn=0, completedOn=12000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12552 t=992000|992999 req="ScheduleDelete(id=s04)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12553 t=992000|992999 req="ScheduleGet(id=s22)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12554 t=992000|992999 req="PromiseComplete(id=p15, state=REJECTED, value=Value(headers=map[0:3.0 1:3.1 2:3.2], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=3), value=Value(headers=map[], data=), timeout=883108, tags=map[resonate:invoke:dst], createdOn=55000, completedOn=94000)))" err=<nil>
level=INFO msg=DST id=12555 t=992000|992999 req="ScheduleGet(id=s08)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s08, desc=, cron=53 * * * *, tags=map[], promiseId=s08.{{.timestamp}}, promiseTimeout=980288, promiseParam=Value(headers=map[0:5.0 1:5.1], data=14), promiseTags=map[resonate:timeout:true], lastRunTime=0, nextRunTime=3180000, createdOn=980000)))" err=<nil>
level=INFO msg=DST id=12558 t=992000|992999 req="PromiseComplete(id=p11, state=REJECTED, value=Value(headers=map[], data=12))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=17), timeout=130667, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12560 t=992000|992999 req="ScheduleGet(id=s14)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s14, desc=, cron=12 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=985046, promiseParam=Value(headers=map[], data=), promiseTags=map[0:13.0 1:13.1 2:13.2], lastRunTime=0, nextRunTime=4320000, createdOn=985000)))" err=<nil>
level=INFO msg=DST id=12561 t=992000|992999 req="ScheduleGet(id=s17)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s17, desc=, cron=35 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=986102, promiseParam=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2100000, createdOn=973000)))" err=<nil>
level=INFO msg=DST id=12562 t=992000|992999 req="ScheduleGet(id=s19)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s19, desc=, cron=48 * * * *, tags=map[0:0.0 resonate:invoke:dst resonate:timeout:true], promiseId=s19.{{.timestamp}}, promiseTimeout=989543, promiseParam=Value(headers=map[], data=7), promiseTags=map[], lastRunTime=0, nextRunTime=2880000, createdOn=957000)))" err=<nil>
level=INFO msg=DST id=12547 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p15, param=Value(headers=map[], data=15), timeout=49389, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p15, processId=p15, ttl=2000, timeout=49389))" res="Response(status=20000, TaskCreate(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=3), value=Value(headers=map[], data=), timeout=883108, tags=map[resonate:invoke:dst], createdOn=55000, completedOn=94000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12548 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p14, param=Value(headers=map[], data=2), timeout=116742, tags=map[0:20.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p14, processId=p14, ttl=5000, timeout=116742))" res="Response(status=20000, TaskCreate(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=327110, tags=map[], createdOn=3000, completedOn=16000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12549 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p05, param=Value(headers=map[0:4.0], data=6), timeout=187019, tags=map[0:16.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p05, processId=p05, ttl=4000, timeout=187019))" res="Response(status=20000, TaskCreate(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=10), timeout=289290, tags=map[], createdOn=1000, completedOn=34000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12556 t=992000|992999 req="PromiseSubscribe(awaited=p15, address=addr05)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=3), value=Value(headers=map[], data=), timeout=883108, tags=map[resonate:invoke:dst], createdOn=55000, completedOn=94000)))" err=<nil>
level=INFO msg=DST id=12557 t=992000|992999 req="PromiseCreate(id=p14, param=Value(headers=map[], data=), timeout=110698, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=327110, tags=map[], createdOn=3000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12559 t=992000|992999 req="PromiseRegister(awaiter=p18, awaited=p04)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p04, state=RESOLVED, param=Value(headers=map[0:4.0], data=18), value=Value(headers=map[], data=), timeout=598780, tags=map[0:18.0 1:18.1 resonate:invoke:dst resonate:timeout:true], createdOn=8000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12563 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p00, param=Value(headers=map[0:5.0 1:5.1], data=2), timeout=731492, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p00, processId=p00, ttl=1000, timeout=731492))" res="Response(status=20000, TaskCreate(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=1), timeout=160221, tags=map[0:12.0 resonate:invoke:dst], createdOn=2000, completedOn=80000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12564 t=993000|993999 req="ScheduleDelete(id=s15)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12565 t=993000|993999 req="ScheduleDelete(id=s03)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12568 t=993000|993999 req="PromiseComplete(id=p02, state=REJECTED, value=Value(headers=map[0:4.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=17), value=Value(headers=map[], data=), timeout=910564, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12570 t=993000|993999 req="PromiseComplete(id=p08, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:1.0 1:1.1 2:1.2], data=12), value=Value(headers=map[], data=11), timeout=221442, tags=map[0:22.0 1:22.1 resonate:invoke:dst], createdOn=4000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12572 t=993000|993999 req="PromiseComplete(id=p21, state=REJECTED, value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p21, state=RESOLVED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[], data=16), timeout=990463, tags=map[0:1.0 1:1.1], createdOn=2000, completedOn=31000)))" err=<nil>
level=INFO msg=DST id=12575 t=993000|993999 req="ScheduleGet(id=s18)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12576 t=993000|993999 req="CallbackCreate(id=__resume:p18:p17, promiseId=p17, recv=\"dst\", mesg=Mesg(type=resume, root=p18, leaf=p17), timeout=995517)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p17, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=353863, tags=map[0:18.0 1:18.1 resonate:timeout:true], createdOn=4000, completedOn=13000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12578 t=993000|993999 req="ScheduleDelete(id=s08)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12580 t=993000|993999 req="ScheduleDelete(id=s16)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12583 t=993000|993999 req="PromiseComplete(id=p15, state=RESOLVED, value=Value(headers=map[], data=13))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=3), value=Value(headers=map[], data=), timeout=883108, tags=map[resonate:invoke:dst], createdOn=55000, completedOn=94000)))" err=<nil>
level=INFO msg=DST id=12584 t=993000|993999 req="PromiseComplete(id=p10, state=REJECTED_CANCELED, value=Value(headers=map[0:2.0 1:2.1], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=9), timeout=530545, tags=map[0:22.0 1:22.1], createdOn=0, completedOn=12000)))" err=<nil>
level=INFO msg=DST id=12585 t=993000|993999 req="ScheduleDelete(id=s15)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12586 t=993000|993999 req="PromiseComplete(id=p16, state=RESOLVED, value=Value(headers=map[], data=16))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p16, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=20), timeout=209000, tags=map[0:2.0 1:2.1 2:2.2 resonate:invoke:dst], createdOn=2000, completedOn=5000)))" err=<nil>
level=INFO msg=DST id=12566 t=993000|993999 req="PromiseCreate(id=p07, param=Value(headers=map[0:2.0 1:2.1], data=18), timeout=382791, tags=map[resonate:invoke:dst])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p07, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:0.0 1:0.1], data=7), value=Value(headers=map[], data=), timeout=29342, tags=map[0:12.0 resonate:invoke:dst], createdOn=2000, completedOn=29342)))" err=<nil>
level=INFO msg=DST id=12567 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p17, param=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), timeout=676788, tags=map[0:1.0 1:1.1 resonate:invoke:dst]), task=CreateTask(promiseId=p17, processId=p17, ttl=9223372036854775807, timeout=676788))" res="Response(status=20000, TaskCreate(promise=Promise(id=p17, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=353863, tags=map[0:18.0 1:18.1 resonate:timeout:true], createdOn=4000, completedOn=13000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12569 t=993000|993999 req="PromiseCreate(id=p08, param=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), timeout=755766, tags=map[0:13.0 1:13.1 2:13.2])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:1.0 1:1.1 2:1.2], data=12), value=Value(headers=map[], data=11), timeout=221442, tags=map[0:22.0 1:22.1 resonate:invoke:dst], createdOn=4000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12571 t=993000|993999 req="PromiseGet(id=p16)" res="Response(status=20000, PromiseGet(promise=Promise(id=p16, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=20), timeout=209000, tags=map[0:2.0 1:2.1 2:2.2 resonate:invoke:dst], createdOn=2000, completedOn=5000)))" err=<nil>
level=INFO msg=DST id=12574 t=993000|993999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:0.0 1:0.1], data=7), value=Value(headers=map[], data=), timeout=29342, tags=map[0:12.0 resonate:invoke:dst], createdOn=2000, completedOn=29342)))" err=<nil>
level=INFO msg=DST id=12577 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p19, param=Value(headers=map[0:2.0 1:2.1], data=), timeout=180660, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p19, processId=p19, ttl=2000, timeout=180660))" res="Response(status=20000, TaskCreate(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[], data=15), value=Value(headers=map[], data=), timeout=460084, tags=map[], createdOn=36000, completedOn=41000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12579 t=993000|993999 req="PromiseRegister(awaiter=p05, awaited=p12)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12581 t=993000|993999 req="PromiseGet(id=p02)" res="Response(status=20000, PromiseGet(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=17), value=Value(headers=map[], data=), timeout=910564, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12582 t=993000|993999 req="PromiseRegister(awaiter=p23, awaited=p01)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), value=Value(headers=map[], data=), timeout=735969, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=85000)))" err=<nil>
level=INFO msg=DST id=12589 t=994000|994999 req="CallbackCreate(id=__notify:p09:cb22, promiseId=p09, recv=\"dst\", mesg=Mesg(type=notify, root=p09, leaf=), timeout=999117)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p09, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:4.0], data=7), timeout=306786, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=51000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12590 t=994000|994999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12591 t=994000|994999 req="ScheduleGet(id=s16)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12573 t=993000|994999 req="ScheduleCreate(id=s08, desc=, cron=56 * * * *, tags=map[], promiseId=s08.{{.timestamp}}, promiseTimeout=999603, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s08, desc=, cron=56 * * * *, tags=map[], promiseId=s08.{{.timestamp}}, promiseTimeout=999603, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=3360000, createdOn=994000)))" err=<nil>
level=INFO msg=DST id=12587 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p02, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), timeout=857898, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p02, processId=p02, ttl=2000, timeout=857898))" res="Response(status=20000, TaskCreate(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=17), value=Value(headers=map[], data=), timeout=910564, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=19000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12588 t=994000|994999 req="PromiseSubscribe(awaited=p18, address=addr05)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p18, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=83027, tags=map[0:22.0 1:22.1 resonate:invoke:dst], createdOn=21000, completedOn=58000)))" err=<nil>
level=INFO msg=DST id=12592 t=995000|995999 req="PromiseComplete(id=p02, state=REJECTED_CANCELED, value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=10))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=17), value=Value(headers=map[], data=), timeout=910564, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12595 t=995000|995999 req="ScheduleCreate(id=s21, desc=, cron=29 * * * *, tags=map[resonate:invoke:dst], promiseId=s21.{{.timestamp}}, promiseTimeout=996441, promiseParam=Value(headers=map[], data=), promiseTags=map[0:11.0 1:11.1])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s21, desc=, cron=30 * * * *, tags=map[0:22.0 1:22.1], promiseId=s21.{{.timestamp}}, promiseTimeout=984447, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=2), promiseTags=map[], lastRunTime=0, nextRunTime=1800000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12596 t=995000|995999 req="ScheduleGet(id=s15)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12598 t=995000|995999 req="ScheduleGet(id=s03)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12593 t=995000|995999 req="PromiseGet(id=p11)" res="Response(status=20000, PromiseGet(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=17), timeout=130667, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12594 t=995000|995999 req="PromiseRegister(awaiter=p20, awaited=p12)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12597 t=995000|995999 req="PromiseCreate(id=p06, param=Value(headers=map[], data=14), timeout=972685, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p06, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=279339, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12601 t=996000|996999 req="CallbackCreate(id=__resume:p06:p12, promiseId=p12, recv=\"dst\", mesg=Mesg(type=resume, root=p06, leaf=p12), timeout=997983)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p12, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=17), timeout=52222, tags=map[0:2.0 1:2.1 2:2.2], createdOn=2000, completedOn=48000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12603 t=996000|996999 req="ScheduleDelete(id=s05)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12604 t=996000|996999 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[], data=), value=Value(headers=map[], data=), timeout=279339, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12607 t=996000|996999 req="ScheduleGet(id=s02)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12608 t=996000|996999 req="ScheduleGet(id=s06)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12609 t=996000|996999 req="ScheduleCreate(id=s21, desc=, cron=41 * * * *, tags=map[], promiseId=s21.{{.timestamp}}, promiseTimeout=997427, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s21, desc=, cron=30 * * * *, tags=map[0:22.0 1:22.1], promiseId=s21.{{.timestamp}}, promiseTimeout=984447, promiseParam=Value(headers=map[0:3.0 1:3.1 2:3.2], data=2), promiseTags=map[], lastRunTime=0, nextRunTime=1800000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12599 t=996000|996999 req="PromiseSubscribe(awaited=p22, address=addr22)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p22, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=4), timeout=983411, tags=map[0:6.0 resonate:invoke:dst resonate:timeout:true], createdOn=5000, completedOn=14000)))" err=<nil>
level=INFO msg=DST id=12600 t=996000|996999 req="PromiseCreate(id=p02, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), timeout=214217, tags=map[0:4.0 1:4.1 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=17), value=Value(headers=map[], data=), timeout=910564, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12602 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p14, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=0), timeout=359306, tags=map[0:13.0 1:13.1 2:13.2 resonate:invoke:dst]), task=CreateTask(promiseId=p14, processId=p14, ttl=2000, timeout=359306))" res="Response(status=20000, TaskCreate(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=327110, tags=map[], createdOn=3000, completedOn=16000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12605 t=996000|996999 req="PromiseGet(id=p17)" res="Response(status=20000, PromiseGet(promise=Promise(id=p17, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=353863, tags=map[0:18.0 1:18.1 resonate:timeout:true], createdOn=4000, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12610 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p05, param=Value(headers=map[], data=), timeout=669977, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p05, processId=p05, ttl=5000, timeout=669977))" res="Response(status=20000, TaskCreate(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=10), timeout=289290, tags=map[], createdOn=1000, completedOn=34000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12612 t=997000|997999 req="PromiseComplete(id=p10, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=9), timeout=530545, tags=map[0:22.0 1:22.1], createdOn=0, completedOn=12000)))" err=<nil>
level=INFO msg=DST id=12613 t=997000|997999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12614 t=997000|997999 req="ScheduleDelete(id=s22)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12606 t=996000|997999 req="ScheduleCreate(id=s18, desc=, cron=43 * * * *, tags=map[0:11.0 1:11.1], promiseId=s18.{{.timestamp}}, promiseTimeout=998969, promiseParam=Value(headers=map[], data=), promiseTags=map[0:8.0])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s18, desc=, cron=43 * * * *, tags=map[0:11.0 1:11.1], promiseId=s18.{{.timestamp}}, promiseTimeout=998969, promiseParam=Value(headers=map[], data=), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=2580000, createdOn=997000)))" err=<nil>
level=INFO msg=DST id=12611 t=997000|997999 req="TaskCreate(promise=PromiseCreate(id=p17, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), timeout=26704, tags=map[resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p17, processId=p17, ttl=9223372036854775807, timeout=26704))" res="Response(status=20000, TaskCreate(promise=Promise(id=p17, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=353863, tags=map[0:18.0 1:18.1 resonate:timeout:true], createdOn=4000, completedOn=13000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12615 t=997000|997999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[0:2.0 1:2.1], data=19), timeout=676995, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=5000, timeout=676995))" res="Response(status=20000, TaskCreate(promise=Promise(id=p16, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=20), timeout=209000, tags=map[0:2.0 1:2.1 2:2.2 resonate:invoke:dst], createdOn=2000, completedOn=5000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12616 t=997000|997999 req="PromiseCreate(id=p13, param=Value(headers=map[0:2.0 1:2.1], data=), timeout=385604, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p13, state=REJECTED, param=Value(headers=map[], data=5), value=Value(headers=map[], data=), timeout=276868, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=7000, completedOn=39000)))" err=<nil>
level=INFO msg=DST id=12619 t=998000|998999 req="CallbackCreate(id=__notify:p05:cb06, promiseId=p05, recv=\"dst\", mesg=Mesg(type=notify, root=p05, leaf=), timeout=999608)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=10), timeout=289290, tags=map[], createdOn=1000, completedOn=34000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12620 t=998000|998999 req="CallbackCreate(id=__resume:p15:p09, promiseId=p09, recv=\"dst\", mesg=Mesg(type=resume, root=p15, leaf=p09), timeout=998563)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p09, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:4.0], data=7), timeout=306786, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=51000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12617 t=998000|998999 req="PromiseCreate(id=p11, param=Value(headers=map[], data=3), timeout=190252, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=17), timeout=130667, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12618 t=998000|998999 req="PromiseGet(id=p22)" res="Response(status=20000, PromiseGet(promise=Promise(id=p22, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=4), timeout=983411, tags=map[0:6.0 resonate:invoke:dst resonate:timeout:true], createdOn=5000, completedOn=14000)))" err=<nil>
level=INFO msg=DST id=12621 t=998000|998999 req="TaskCreate(promise=PromiseCreate(id=p01, param=Value(headers=map[0:4.0], data=), timeout=10365, tags=map[0:21.0 1:21.1 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p01, processId=p01, ttl=2000, timeout=10365))" res="Response(status=20000, TaskCreate(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), value=Value(headers=map[], data=), timeout=735969, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=85000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12622 t=998000|998999 req="PromiseRegister(awaiter=p07, awaited=p17)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p17, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=353863, tags=map[0:18.0 1:18.1 resonate:timeout:true], createdOn=4000, completedOn=13000)))" err=<nil>
level=INFO msg=DST id=12625 t=999000|1000999 req="ScheduleDelete(id=s22)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12627 t=999000|1000999 req="ScheduleDelete(id=s22)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12628 t=999000|1000999 req="ScheduleGet(id=s16)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12632 t=999000|1000999 req="ScheduleGet(id=s06)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12636 t=999000|1000999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12640 t=999000|1000999 req="PromiseComplete(id=p21, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p21, state=RESOLVED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[], data=16), timeout=990463, tags=map[0:1.0 1:1.1], createdOn=2000, completedOn=31000)))" err=<nil>
level=INFO msg=DST id=12643 t=999000|1000999 req="PromiseComplete(id=p15, state=REJECTED_CANCELED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED, param=Value(headers=map[0:3.0 1:3.1 2:3.2], data=3), value=Value(headers=map[], data=), timeout=883108, tags=map[resonate:invoke:dst], createdOn=55000, completedOn=94000)))" err=<nil>
level=INFO msg=DST id=12623 t=999000|1000999 req="PromiseRegister(awaiter=p12, awaited=p05)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12626 t=999000|1000999 req="PromiseCreate(id=p06, param=Value(headers=map[0:5.0 1:5.1], data=), timeout=79989, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p06, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=279339, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=29000)))" err=<nil>
level=INFO msg=DST id=12629 t=999000|1000999 req="PromiseRegister(awaiter=p09, awaited=p11)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p11, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=17), timeout=130667, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=20000)))" err=<nil>
level=INFO msg=DST id=12630 t=999000|1000999 req="TaskCreate(promise=PromiseCreate(id=p23, param=Value(headers=map[0:5.0 1:5.1], data=), timeout=566006, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p23, processId=p23, ttl=9223372036854775807, timeout=566006))" res="Response(status=20000, TaskCreate(promise=Promise(id=p23, state=RESOLVED, param=Value(headers=map[0:0.0 1:0.1], data=9), value=Value(headers=map[], data=15), timeout=88171, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=25000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12631 t=999000|1000999 req="PromiseRegister(awaiter=p02, awaited=p00)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p00, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=1), timeout=160221, tags=map[0:12.0 resonate:invoke:dst], createdOn=2000, completedOn=80000)))" err=<nil>
level=INFO msg=DST id=12633 t=999000|1000999 req="PromiseGet(id=p14)" res="Response(status=20000, PromiseGet(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=327110, tags=map[], createdOn=3000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12634 t=999000|1000999 req="PromiseSubscribe(awaited=p04, address=addr21)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p04, state=RESOLVED, param=Value(headers=map[0:4.0], data=18), value=Value(headers=map[], data=), timeout=598780, tags=map[0:18.0 1:18.1 resonate:invoke:dst resonate:timeout:true], createdOn=8000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12635 t=999000|1000999 req="PromiseRegister(awaiter=p14, awaited=p12)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12637 t=999000|1000999 req="PromiseGet(id=p23)" res="Response(status=20000, PromiseGet(promise=Promise(id=p23, state=RESOLVED, param=Value(headers=map[0:0.0 1:0.1], data=9), value=Value(headers=map[], data=15), timeout=88171, tags=map[resonate:invoke:dst], createdOn=11000, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12638 t=999000|1000999 req="PromiseSubscribe(awaited=p14, address=addr03)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=327110, tags=map[], createdOn=3000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12639 t=999000|1000999 req="PromiseSubscribe(awaited=p18, address=addr16)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p18, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=83027, tags=map[0:22.0 1:22.1 resonate:invoke:dst], createdOn=21000, completedOn=58000)))" err=<nil>
level=INFO msg=DST id=12641 t=999000|1000999 req="PromiseSubscribe(awaited=p02, address=addr22)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p02, state=REJECTED, param=Value(headers=map[], data=17), value=Value(headers=map[], data=), timeout=910564, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12642 t=999000|1000999 req="PromiseSubscribe(awaited=p21, address=addr10)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p21, state=RESOLVED, param=Value(headers=map[0:5.0 1:5.1], data=), value=Value(headers=map[], data=16), timeout=990463, tags=map[0:1.0 1:1.1], createdOn=2000, completedOn=31000)))" err=<nil>
level=INFO msg=DST id=12624 t=999000|1001999 req="ScheduleCreate(id=s00, desc=, cron=22 * * * *, tags=map[0:20.0 resonate:timeout:true], promiseId=s00.{{.timestamp}}, promiseTimeout=999186, promiseParam=Value(headers=map[0:5.0 1:5.1], data=2), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s00, desc=, cron=22 * * * *, tags=map[0:20.0 resonate:timeout:true], promiseId=s00.{{.timestamp}}, promiseTimeout=999186, promiseParam=Value(headers=map[0:5.0 1:5.1], data=2), promiseTags=map[], lastRunTime=0, nextRunTime=1320000, createdOn=1001000)))" err=<nil>
level=ERROR msg="DST is non linearizable, run with -v flag for more information" v=false
Error: DST failed for seed='28987'