DST: 29144

[github-actions]

DST Failed

DST run failed for seed=29144, ticks=1000, scenario=default, store=sqlite.

Seed

29144

Scenario

default

Store

sqlite

Commit

3625ce749e237177e4f992881950c6457aae3275

Command

go run ./... dst run --seed 29144 --ticks 1000 --scenario default --aio-store-sqlite-enable

Logs

level=INFO msg=DST seed=29144 ticks=1000 reqsPerTick=1:25 dst="DST(ids=24, headers=20, data=5, tags=15, backchannel=937)" system="System(api=API(size=587, subsystems=[]), aio=AIODST(subsystems=[router sender store]), config=Config(cms=939, sbs=110, cbs=893, pbs=32, sbs=19, tbs=52))"
level=INFO msg=DST id=1 t=0|999 req="CallbackCreate(id=__resume:p12:p02, promiseId=p02, recv=\"dst\", mesg=Mesg(type=resume, root=p12, leaf=p02), timeout=629847)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=2 t=0|999 req="PromiseComplete(id=p21, state=RESOLVED, value=Value(headers=map[], data=4))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=8 t=0|999 req="ScheduleDelete(id=s11)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12 t=0|999 req="ScheduleGet(id=s04)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=13 t=0|999 req="PromiseComplete(id=p08, state=REJECTED, value=Value(headers=map[], data=3))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=19 t=0|999 req="ScheduleDelete(id=s04)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=4 t=0|999 req="PromiseRegister(awaiter=p04, awaited=p21)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=6 t=0|999 req="PromiseSubscribe(awaited=p16, address=addr04)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=7 t=0|999 req="PromiseGet(id=p21)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=10 t=0|999 req="PromiseRegister(awaiter=p06, awaited=p14)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=14 t=0|999 req="PromiseSubscribe(awaited=p07, address=addr04)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=16 t=0|999 req="PromiseGet(id=p06)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=20 t=1000|1999 req="ScheduleGet(id=s23)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=21 t=1000|1999 req="ScheduleDelete(id=s18)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=22 t=1000|1999 req="ScheduleDelete(id=s07)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=25 t=1000|1999 req="CallbackCreate(id=__notify:p12:cb22, promiseId=p12, recv=\"dst\", mesg=Mesg(type=notify, root=p12, leaf=), timeout=3125)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=27 t=1000|1999 req="ScheduleDelete(id=s13)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=0 t=0|1999 req="ScheduleCreate(id=s04, desc=, cron=30 * * * *, tags=map[0:12.0], promiseId=s04.{{.timestamp}}, promiseTimeout=997700, promiseParam=Value(headers=map[0:16.0 1:16.1 2:16.2], data=), promiseTags=map[0:10.0 resonate:invoke:dst])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=30 * * * *, tags=map[0:12.0], promiseId=s04.{{.timestamp}}, promiseTimeout=997700, promiseParam=Value(headers=map[0:16.0 1:16.1 2:16.2], data=), promiseTags=map[0:10.0 resonate:invoke:dst], lastRunTime=0, nextRunTime=1800000, createdOn=1000)))" err=<nil>
level=INFO msg=DST id=18 t=0|1999 req="ScheduleCreate(id=s19, desc=, cron=10 * * * *, tags=map[0:5.0 resonate:timeout:true], promiseId=s19.{{.timestamp}}, promiseTimeout=955500, promiseParam=Value(headers=map[], data=0), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s19, desc=, cron=10 * * * *, tags=map[0:5.0 resonate:timeout:true], promiseId=s19.{{.timestamp}}, promiseTimeout=955500, promiseParam=Value(headers=map[], data=0), promiseTags=map[], lastRunTime=0, nextRunTime=600000, createdOn=1000)))" err=<nil>
level=INFO msg=DST id=24 t=1000|1999 req="PromiseGet(id=p18)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=26 t=1000|1999 req="PromiseRegister(awaiter=p08, awaited=p21)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=28 t=1000|1999 req="PromiseGet(id=p16)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=29 t=2000|2999 req="ScheduleDelete(id=s03)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=30 t=2000|2999 req="CallbackCreate(id=__resume:p03:p08, promiseId=p08, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p08), timeout=68392)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=31 t=2000|2999 req="CallbackCreate(id=__resume:p13:p16, promiseId=p16, recv=\"dst\", mesg=Mesg(type=resume, root=p13, leaf=p16), timeout=410042)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=34 t=2000|2999 req="ScheduleGet(id=s13)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=3 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p14, param=Value(headers=map[0:4.0], data=), timeout=804575, tags=map[0:10.0 resonate:invoke:dst]), task=CreateTask(promiseId=p14, processId=p14, ttl=5000, timeout=804575))" res="Response(status=20100, TaskCreate(promise=Promise(id=p14, state=PENDING, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=), timeout=804575, tags=map[0:10.0 resonate:invoke:dst], createdOn=0, completedOn=0), task=Task(id=__invoke:p14, processId=p14, state=CLAIMED, rootPromiseId=p14, recv=\"dst\", mesg=Mesg(type=invoke, root=p14, leaf=p14), timeout=804575, counter=1, attempt=0, ttl=5000, expiresAt=5000, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=5 t=0|2999 req="PromiseCreate(id=p23, param=Value(headers=map[0:19.0 1:19.1], data=2), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p23, state=PENDING, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=9 t=0|2999 req="PromiseCreate(id=p10, param=Value(headers=map[0:19.0 1:19.1], data=2), timeout=13821, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p10, state=PENDING, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=11 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p06, param=Value(headers=map[], data=0), timeout=561390, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p06, processId=p06, ttl=3000, timeout=561390))" res="Response(status=20100, TaskCreate(promise=Promise(id=p06, state=PENDING, param=Value(headers=map[], data=0), value=Value(headers=map[], data=), timeout=561390, tags=map[resonate:invoke:dst], createdOn=0, completedOn=0), task=Task(id=__invoke:p06, processId=p06, state=CLAIMED, rootPromiseId=p06, recv=\"dst\", mesg=Mesg(type=invoke, root=p06, leaf=p06), timeout=561390, counter=1, attempt=0, ttl=3000, expiresAt=3000, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=15 t=0|2999 req="PromiseCreate(id=p05, param=Value(headers=map[], data=4), timeout=202940, tags=map[0:7.0 resonate:invoke:dst])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p05, state=PENDING, param=Value(headers=map[], data=4), value=Value(headers=map[], data=), timeout=202940, tags=map[0:7.0 resonate:invoke:dst], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=17 t=0|2999 req="TaskCreate(promise=PromiseCreate(id=p18, param=Value(headers=map[], data=1), timeout=132919, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p18, processId=p18, ttl=4000, timeout=132919))" res="Response(status=20100, TaskCreate(promise=Promise(id=p18, state=PENDING, param=Value(headers=map[], data=1), value=Value(headers=map[], data=), timeout=132919, tags=map[resonate:invoke:dst], createdOn=0, completedOn=0), task=Task(id=__invoke:p18, processId=p18, state=CLAIMED, rootPromiseId=p18, recv=\"dst\", mesg=Mesg(type=invoke, root=p18, leaf=p18), timeout=132919, counter=1, attempt=0, ttl=4000, expiresAt=4000, createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=32 t=2000|2999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[], data=3), timeout=611357, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p10, processId=p10, ttl=3000, timeout=611357))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=PENDING, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=0), task=<nil>))" err=<nil>
level=INFO msg=DST id=33 t=2000|2999 req="PromiseSubscribe(awaited=p21, address=addr19)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=38 t=3000|3999 req="ScheduleGet(id=s00)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=43 t=3000|3999 req="ScheduleGet(id=s10)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=46 t=3000|3999 req="PromiseComplete(id=p07, state=RESOLVED, value=Value(headers=map[], data=0))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=48 t=3000|3999 req="CallbackCreate(id=__resume:p03:p00, promiseId=p00, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p00), timeout=815554)" res="Response(status=40400, CallbackCreate(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=23 t=1000|3999 req="TaskCreate(promise=PromiseCreate(id=p20, param=Value(headers=map[], data=4), timeout=486039, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p20, processId=p20, ttl=9223372036854775807, timeout=486039))" res="Response(status=20100, TaskCreate(promise=Promise(id=p20, state=PENDING, param=Value(headers=map[], data=4), value=Value(headers=map[], data=), timeout=486039, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=0), task=Task(id=__invoke:p20, processId=p20, state=CLAIMED, rootPromiseId=p20, recv=\"dst\", mesg=Mesg(type=invoke, root=p20, leaf=p20), timeout=486039, counter=1, attempt=0, ttl=9223372036854775807, expiresAt=9223372036854775807, createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=36 t=3000|3999 req="PromiseSubscribe(awaited=p13, address=addr19)" res="Response(status=40400, PromiseSubscribe(promise=<nil>))" err=<nil>
level=INFO msg=DST id=39 t=3000|3999 req="PromiseRegister(awaiter=p00, awaited=p11)" res="Response(status=40400, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=40 t=3000|3999 req="PromiseGet(id=p20)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=41 t=3000|3999 req="PromiseGet(id=p02)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=42 t=3000|3999 req="PromiseGet(id=p07)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=49 t=3000|3999 req="PromiseCreate(id=p14, param=Value(headers=map[], data=0), timeout=198256, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p14, state=PENDING, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=), timeout=804575, tags=map[0:10.0 resonate:invoke:dst], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=50 t=3000|3999 req="PromiseGet(id=p13)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=53 t=4000|4999 req="ScheduleDelete(id=s09)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=54 t=4000|4999 req="ScheduleDelete(id=s02)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=58 t=4000|4999 req="PromiseComplete(id=p12, state=REJECTED, value=Value(headers=map[], data=0))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>

...

level=INFO msg=DST id=12249 t=991000|991999 req="PromiseGet(id=p14)" res="Response(status=20000, PromiseGet(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=), timeout=804575, tags=map[0:10.0 resonate:invoke:dst], createdOn=0, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12251 t=991000|991999 req="PromiseCreate(id=p21, param=Value(headers=map[], data=), timeout=434931, tags=map[0:12.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p21, state=REJECTED, param=Value(headers=map[0:6.0], data=3), value=Value(headers=map[], data=4), timeout=166102, tags=map[resonate:invoke:dst], createdOn=33000, completedOn=118000)))" err=<nil>
level=INFO msg=DST id=12253 t=991000|991999 req="TaskCreate(promise=PromiseCreate(id=p09, param=Value(headers=map[0:5.0], data=), timeout=813982, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p09, processId=p09, ttl=5000, timeout=813982))" res="Response(status=20000, TaskCreate(promise=Promise(id=p09, state=REJECTED, param=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), value=Value(headers=map[0:17.0], data=2), timeout=754873, tags=map[], createdOn=3000, completedOn=10000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12254 t=991000|991999 req="PromiseCreate(id=p01, param=Value(headers=map[0:14.0 1:14.1], data=), timeout=461137, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p01, state=RESOLVED, param=Value(headers=map[], data=1), value=Value(headers=map[], data=), timeout=686811, tags=map[0:5.0 resonate:invoke:dst resonate:timeout:true], createdOn=11000, completedOn=32000)))" err=<nil>
level=INFO msg=DST id=12256 t=991000|991999 req="PromiseSubscribe(awaited=p10, address=addr03)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821)))" err=<nil>
level=INFO msg=DST id=12257 t=991000|991999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[], data=2), timeout=143969, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p10, processId=p10, ttl=1000, timeout=143969))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821), task=<nil>))" err=<nil>
level=INFO msg=DST id=12259 t=991000|991999 req="PromiseGet(id=p21)" res="Response(status=20000, PromiseGet(promise=Promise(id=p21, state=REJECTED, param=Value(headers=map[0:6.0], data=3), value=Value(headers=map[], data=4), timeout=166102, tags=map[resonate:invoke:dst], createdOn=33000, completedOn=118000)))" err=<nil>
level=INFO msg=DST id=12264 t=991000|991999 req="PromiseSubscribe(awaited=p11, address=addr14)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p11, state=REJECTED_CANCELED, param=Value(headers=map[0:3.0 1:3.1], data=), value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=2), timeout=455263, tags=map[], createdOn=15000, completedOn=21000)))" err=<nil>
level=INFO msg=DST id=12265 t=991000|991999 req="PromiseGet(id=p13)" res="Response(status=20000, PromiseGet(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[0:14.0 1:14.1], data=), value=Value(headers=map[0:19.0 1:19.1], data=), timeout=896220, tags=map[resonate:invoke:dst], createdOn=7000, completedOn=61000)))" err=<nil>
level=INFO msg=DST id=12269 t=992000|992999 req="PromiseComplete(id=p02, state=RESOLVED, value=Value(headers=map[], data=0))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[0:17.0], data=), value=Value(headers=map[], data=), timeout=511802, tags=map[0:8.0], createdOn=31000, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12260 t=991000|992999 req="ScheduleCreate(id=s04, desc=, cron=58 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=996580, promiseParam=Value(headers=map[], data=0), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=58 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=996580, promiseParam=Value(headers=map[], data=0), promiseTags=map[], lastRunTime=0, nextRunTime=3480000, createdOn=992000)))" err=<nil>
level=INFO msg=DST id=12266 t=992000|992999 req="PromiseGet(id=p09)" res="Response(status=20000, PromiseGet(promise=Promise(id=p09, state=REJECTED, param=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), value=Value(headers=map[0:17.0], data=2), timeout=754873, tags=map[], createdOn=3000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12267 t=992000|992999 req="PromiseGet(id=p04)" res="Response(status=20000, PromiseGet(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), timeout=665372, tags=map[resonate:invoke:dst], createdOn=18000, completedOn=59000)))" err=<nil>
level=INFO msg=DST id=12268 t=992000|992999 req="PromiseSubscribe(awaited=p05, address=addr08)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p05, state=REJECTED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=4), timeout=202940, tags=map[0:7.0 resonate:invoke:dst], createdOn=0, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12270 t=993000|993999 req="ScheduleGet(id=s19)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s19, desc=, cron=45 * * * *, tags=map[], promiseId=s19.{{.timestamp}}, promiseTimeout=989721, promiseParam=Value(headers=map[], data=0), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=2700000, createdOn=898000)))" err=<nil>
level=INFO msg=DST id=12273 t=993000|993999 req="CallbackCreate(id=__notify:p13:cb22, promiseId=p13, recv=\"dst\", mesg=Mesg(type=notify, root=p13, leaf=), timeout=995110)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[0:14.0 1:14.1], data=), value=Value(headers=map[0:19.0 1:19.1], data=), timeout=896220, tags=map[resonate:invoke:dst], createdOn=7000, completedOn=61000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12275 t=993000|993999 req="ScheduleCreate(id=s16, desc=, cron=35 * * * *, tags=map[resonate:timeout:true], promiseId=s16.{{.timestamp}}, promiseTimeout=996048, promiseParam=Value(headers=map[0:15.0 1:15.1], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s16, desc=, cron=35 * * * *, tags=map[], promiseId=s16.{{.timestamp}}, promiseTimeout=991149, promiseParam=Value(headers=map[0:3.0 1:3.1], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true], lastRunTime=0, nextRunTime=2100000, createdOn=972000)))" err=<nil>
level=INFO msg=DST id=12272 t=993000|993999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=REJECTED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=4), timeout=202940, tags=map[0:7.0 resonate:invoke:dst], createdOn=0, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12274 t=993000|993999 req="PromiseRegister(awaiter=p08, awaited=p20)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p20, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=4), timeout=486039, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=37000)))" err=<nil>
level=INFO msg=DST id=12280 t=994000|994999 req="CallbackCreate(id=__resume:p00:p07, promiseId=p07, recv=\"dst\", mesg=Mesg(type=resume, root=p00, leaf=p07), timeout=996056)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p07, state=REJECTED_CANCELED, param=Value(headers=map[], data=0), value=Value(headers=map[0:7.0 1:7.1], data=), timeout=710424, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=43000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12282 t=994000|994999 req="CallbackCreate(id=__notify:p05:cb13, promiseId=p05, recv=\"dst\", mesg=Mesg(type=notify, root=p05, leaf=), timeout=995539)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p05, state=REJECTED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=4), timeout=202940, tags=map[0:7.0 resonate:invoke:dst], createdOn=0, completedOn=25000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12284 t=994000|994999 req="PromiseComplete(id=p19, state=REJECTED, value=Value(headers=map[], data=3))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=811719, tags=map[], createdOn=3000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12285 t=994000|994999 req="PromiseComplete(id=p12, state=REJECTED_CANCELED, value=Value(headers=map[0:13.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p12, state=RESOLVED, param=Value(headers=map[0:11.0], data=4), value=Value(headers=map[0:11.0], data=4), timeout=321169, tags=map[], createdOn=11000, completedOn=21000)))" err=<nil>
level=INFO msg=DST id=12288 t=994000|994999 req="PromiseComplete(id=p08, state=REJECTED_CANCELED, value=Value(headers=map[0:16.0 1:16.1 2:16.2], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=0), value=Value(headers=map[], data=), timeout=740887, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=65000)))" err=<nil>
level=INFO msg=DST id=12290 t=994000|994999 req="ScheduleDelete(id=s14)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12292 t=994000|994999 req="ScheduleGet(id=s08)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s08, desc=, cron=13 * * * *, tags=map[0:9.0], promiseId=s08.{{.timestamp}}, promiseTimeout=995650, promiseParam=Value(headers=map[0:7.0 1:7.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4380000, createdOn=982000)))" err=<nil>
level=INFO msg=DST id=12271 t=993000|994999 req="ScheduleCreate(id=s15, desc=, cron=46 * * * *, tags=map[], promiseId=s15.{{.timestamp}}, promiseTimeout=997911, promiseParam=Value(headers=map[], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s15, desc=, cron=46 * * * *, tags=map[], promiseId=s15.{{.timestamp}}, promiseTimeout=997911, promiseParam=Value(headers=map[], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true], lastRunTime=0, nextRunTime=2760000, createdOn=994000)))" err=<nil>
level=INFO msg=DST id=12276 t=994000|994999 req="PromiseCreate(id=p14, param=Value(headers=map[0:8.0 1:8.1 2:8.2], data=3), timeout=353310, tags=map[0:0.0 1:0.1 resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=), timeout=804575, tags=map[0:10.0 resonate:invoke:dst], createdOn=0, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12277 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p08, param=Value(headers=map[], data=), timeout=687279, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p08, processId=p08, ttl=2000, timeout=687279))" res="Response(status=20000, TaskCreate(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=0), value=Value(headers=map[], data=), timeout=740887, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=65000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12278 t=994000|994999 req="PromiseGet(id=p10)" res="Response(status=20000, PromiseGet(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821)))" err=<nil>
level=INFO msg=DST id=12281 t=994000|994999 req="PromiseRegister(awaiter=p20, awaited=p23)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p23, state=REJECTED_CANCELED, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=0), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12283 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p15, param=Value(headers=map[], data=4), timeout=258790, tags=map[resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p15, processId=p15, ttl=9223372036854775807, timeout=258790))" res="Response(status=20000, TaskCreate(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=740720, tags=map[], createdOn=7000, completedOn=33000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12286 t=994000|994999 req="PromiseCreate(id=p14, param=Value(headers=map[0:16.0 1:16.1 2:16.2], data=1), timeout=20385, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p14, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=), timeout=804575, tags=map[0:10.0 resonate:invoke:dst], createdOn=0, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12287 t=994000|994999 req="PromiseSubscribe(awaited=p10, address=addr22)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821)))" err=<nil>
level=INFO msg=DST id=12289 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p23, param=Value(headers=map[], data=2), timeout=78639, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p23, processId=p23, ttl=5000, timeout=78639))" res="Response(status=20000, TaskCreate(promise=Promise(id=p23, state=REJECTED_CANCELED, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=0), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=10000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12291 t=994000|994999 req="PromiseCreate(id=p05, param=Value(headers=map[], data=), timeout=689704, tags=map[resonate:invoke:dst])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p05, state=REJECTED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=4), timeout=202940, tags=map[0:7.0 resonate:invoke:dst], createdOn=0, completedOn=25000)))" err=<nil>
level=INFO msg=DST id=12293 t=994000|994999 req="PromiseRegister(awaiter=p00, awaited=p19)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12294 t=994000|994999 req="PromiseRegister(awaiter=p06, awaited=p22)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p22, state=REJECTED_CANCELED, param=Value(headers=map[], data=3), value=Value(headers=map[], data=1), timeout=621936, tags=map[], createdOn=9000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12295 t=994000|994999 req="PromiseGet(id=p21)" res="Response(status=20000, PromiseGet(promise=Promise(id=p21, state=REJECTED, param=Value(headers=map[0:6.0], data=3), value=Value(headers=map[], data=4), timeout=166102, tags=map[resonate:invoke:dst], createdOn=33000, completedOn=118000)))" err=<nil>
level=INFO msg=DST id=12296 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p13, param=Value(headers=map[], data=2), timeout=729472, tags=map[0:8.0 resonate:invoke:dst]), task=CreateTask(promiseId=p13, processId=p13, ttl=3000, timeout=729472))" res="Response(status=20000, TaskCreate(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[0:14.0 1:14.1], data=), value=Value(headers=map[0:19.0 1:19.1], data=), timeout=896220, tags=map[resonate:invoke:dst], createdOn=7000, completedOn=61000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12297 t=994000|994999 req="PromiseGet(id=p18)" res="Response(status=20000, PromiseGet(promise=Promise(id=p18, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1 2:16.2], data=), timeout=132919, tags=map[resonate:invoke:dst], createdOn=0, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12299 t=995000|995999 req="ScheduleDelete(id=s16)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12301 t=995000|995999 req="ScheduleDelete(id=s15)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12303 t=995000|995999 req="ScheduleCreate(id=s17, desc=, cron=4 * * * *, tags=map[0:12.0], promiseId=s17.{{.timestamp}}, promiseTimeout=996681, promiseParam=Value(headers=map[0:0.0], data=1), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s17, desc=, cron=46 * * * *, tags=map[], promiseId=s17.{{.timestamp}}, promiseTimeout=997589, promiseParam=Value(headers=map[0:1.0 1:1.1], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true], lastRunTime=0, nextRunTime=2760000, createdOn=991000)))" err=<nil>
level=INFO msg=DST id=12304 t=995000|995999 req="CallbackCreate(id=__resume:p16:p23, promiseId=p23, recv=\"dst\", mesg=Mesg(type=resume, root=p16, leaf=p23), timeout=998193)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p23, state=REJECTED_CANCELED, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=0), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=10000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12306 t=995000|995999 req="CallbackCreate(id=__notify:p02:cb01, promiseId=p02, recv=\"dst\", mesg=Mesg(type=notify, root=p02, leaf=), timeout=999565)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[0:17.0], data=), value=Value(headers=map[], data=), timeout=511802, tags=map[0:8.0], createdOn=31000, completedOn=47000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12307 t=995000|995999 req="ScheduleCreate(id=s11, desc=, cron=44 * * * *, tags=map[0:3.0 1:3.1 resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=996554, promiseParam=Value(headers=map[], data=), promiseTags=map[0:5.0 resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=6 * * * *, tags=map[resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=997181, promiseParam=Value(headers=map[0:11.0], data=), promiseTags=map[resonate:timeout:true], lastRunTime=0, nextRunTime=3960000, createdOn=995000)))" err=<nil>
level=INFO msg=DST id=12308 t=995000|995999 req="ScheduleDelete(id=s13)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12311 t=995000|995999 req="CallbackCreate(id=__resume:p12:p19, promiseId=p19, recv=\"dst\", mesg=Mesg(type=resume, root=p12, leaf=p19), timeout=998010)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=811719, tags=map[], createdOn=3000, completedOn=10000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12312 t=995000|995999 req="ScheduleDelete(id=s14)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12279 t=994000|995999 req="ScheduleCreate(id=s11, desc=, cron=6 * * * *, tags=map[resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=997181, promiseParam=Value(headers=map[0:11.0], data=), promiseTags=map[resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=6 * * * *, tags=map[resonate:timeout:true], promiseId=s11.{{.timestamp}}, promiseTimeout=997181, promiseParam=Value(headers=map[0:11.0], data=), promiseTags=map[resonate:timeout:true], lastRunTime=0, nextRunTime=3960000, createdOn=995000)))" err=<nil>
level=INFO msg=DST id=12298 t=995000|995999 req="PromiseSubscribe(awaited=p18, address=addr02)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p18, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1 2:16.2], data=), timeout=132919, tags=map[resonate:invoke:dst], createdOn=0, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12300 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p04, param=Value(headers=map[], data=3), timeout=480616, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p04, processId=p04, ttl=1000, timeout=480616))" res="Response(status=20000, TaskCreate(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), timeout=665372, tags=map[resonate:invoke:dst], createdOn=18000, completedOn=59000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12302 t=995000|995999 req="PromiseRegister(awaiter=p17, awaited=p03)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12309 t=995000|995999 req="PromiseCreate(id=p13, param=Value(headers=map[], data=), timeout=155018, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[0:14.0 1:14.1], data=), value=Value(headers=map[0:19.0 1:19.1], data=), timeout=896220, tags=map[resonate:invoke:dst], createdOn=7000, completedOn=61000)))" err=<nil>
level=INFO msg=DST id=12313 t=995000|995999 req="PromiseRegister(awaiter=p17, awaited=p18)" res="Response(status=40404, PromiseRegister(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12314 t=995000|995999 req="PromiseGet(id=p02)" res="Response(status=20000, PromiseGet(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[0:17.0], data=), value=Value(headers=map[], data=), timeout=511802, tags=map[0:8.0], createdOn=31000, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12315 t=995000|995999 req="PromiseCreate(id=p02, param=Value(headers=map[0:6.0], data=0), timeout=699348, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[0:17.0], data=), value=Value(headers=map[], data=), timeout=511802, tags=map[0:8.0], createdOn=31000, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12318 t=996000|996999 req="ScheduleGet(id=s12)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s12, desc=, cron=22 * * * *, tags=map[], promiseId=s12.{{.timestamp}}, promiseTimeout=981637, promiseParam=Value(headers=map[0:10.0 1:10.1], data=0), promiseTags=map[], lastRunTime=0, nextRunTime=1320000, createdOn=970000)))" err=<nil>
level=INFO msg=DST id=12319 t=996000|996999 req="PromiseComplete(id=p09, state=RESOLVED, value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p09, state=REJECTED, param=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), value=Value(headers=map[0:17.0], data=2), timeout=754873, tags=map[], createdOn=3000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12320 t=996000|996999 req="ScheduleCreate(id=s04, desc=, cron=44 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=998775, promiseParam=Value(headers=map[0:6.0], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s04, desc=, cron=58 * * * *, tags=map[], promiseId=s04.{{.timestamp}}, promiseTimeout=996580, promiseParam=Value(headers=map[], data=0), promiseTags=map[], lastRunTime=0, nextRunTime=3480000, createdOn=992000)))" err=<nil>
level=INFO msg=DST id=12323 t=996000|996999 req="PromiseComplete(id=p04, state=RESOLVED, value=Value(headers=map[], data=2))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), timeout=665372, tags=map[resonate:invoke:dst], createdOn=18000, completedOn=59000)))" err=<nil>
level=INFO msg=DST id=12305 t=995000|996999 req="ScheduleCreate(id=s21, desc=, cron=25 * * * *, tags=map[0:1.0 1:1.1 resonate:timeout:true], promiseId=s21.{{.timestamp}}, promiseTimeout=997549, promiseParam=Value(headers=map[0:14.0 1:14.1], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s21, desc=, cron=25 * * * *, tags=map[0:1.0 1:1.1 resonate:timeout:true], promiseId=s21.{{.timestamp}}, promiseTimeout=997549, promiseParam=Value(headers=map[0:14.0 1:14.1], data=), promiseTags=map[0:1.0 1:1.1 resonate:timeout:true], lastRunTime=0, nextRunTime=1500000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12310 t=995000|996999 req="ScheduleCreate(id=s05, desc=, cron=14 * * * *, tags=map[], promiseId=s05.{{.timestamp}}, promiseTimeout=996532, promiseParam=Value(headers=map[], data=3), promiseTags=map[0:8.0])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s05, desc=, cron=14 * * * *, tags=map[], promiseId=s05.{{.timestamp}}, promiseTimeout=996532, promiseParam=Value(headers=map[], data=3), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=4440000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12316 t=996000|996999 req="PromiseRegister(awaiter=p18, awaited=p12)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p12, state=RESOLVED, param=Value(headers=map[0:11.0], data=4), value=Value(headers=map[0:11.0], data=4), timeout=321169, tags=map[], createdOn=11000, completedOn=21000)))" err=<nil>
level=INFO msg=DST id=12317 t=996000|996999 req="PromiseGet(id=p02)" res="Response(status=20000, PromiseGet(promise=Promise(id=p02, state=REJECTED_CANCELED, param=Value(headers=map[0:17.0], data=), value=Value(headers=map[], data=), timeout=511802, tags=map[0:8.0], createdOn=31000, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12321 t=996000|996999 req="PromiseRegister(awaiter=p14, awaited=p10)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821)))" err=<nil>
level=INFO msg=DST id=12322 t=996000|996999 req="PromiseGet(id=p08)" res="Response(status=20000, PromiseGet(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=0), value=Value(headers=map[], data=), timeout=740887, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=65000)))" err=<nil>
level=INFO msg=DST id=12324 t=997000|997999 req="ScheduleDelete(id=s02)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12326 t=997000|997999 req="ScheduleGet(id=s19)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s19, desc=, cron=45 * * * *, tags=map[], promiseId=s19.{{.timestamp}}, promiseTimeout=989721, promiseParam=Value(headers=map[], data=0), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=2700000, createdOn=898000)))" err=<nil>
level=INFO msg=DST id=12329 t=997000|997999 req="CallbackCreate(id=__notify:p13:cb10, promiseId=p13, recv=\"dst\", mesg=Mesg(type=notify, root=p13, leaf=), timeout=997984)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[0:14.0 1:14.1], data=), value=Value(headers=map[0:19.0 1:19.1], data=), timeout=896220, tags=map[resonate:invoke:dst], createdOn=7000, completedOn=61000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12325 t=997000|997999 req="PromiseCreate(id=p10, param=Value(headers=map[], data=), timeout=376148, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821)))" err=<nil>
level=INFO msg=DST id=12327 t=997000|997999 req="PromiseGet(id=p18)" res="Response(status=20000, PromiseGet(promise=Promise(id=p18, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1 2:16.2], data=), timeout=132919, tags=map[resonate:invoke:dst], createdOn=0, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12328 t=997000|997999 req="PromiseCreate(id=p16, param=Value(headers=map[], data=1), timeout=432752, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=3), timeout=583271, tags=map[], createdOn=6000, completedOn=44000)))" err=<nil>
level=INFO msg=DST id=12330 t=997000|997999 req="PromiseSubscribe(awaited=p23, address=addr05)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p23, state=REJECTED_CANCELED, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=0), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12331 t=998000|998999 req="ScheduleGet(id=s05)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s05, desc=, cron=14 * * * *, tags=map[], promiseId=s05.{{.timestamp}}, promiseTimeout=996532, promiseParam=Value(headers=map[], data=3), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=4440000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12334 t=998000|998999 req="ScheduleCreate(id=s05, desc=, cron=31 * * * *, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], promiseId=s05.{{.timestamp}}, promiseTimeout=999760, promiseParam=Value(headers=map[0:3.0 1:3.1], data=0), promiseTags=map[0:2.0])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s05, desc=, cron=14 * * * *, tags=map[], promiseId=s05.{{.timestamp}}, promiseTimeout=996532, promiseParam=Value(headers=map[], data=3), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=4440000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12336 t=998000|998999 req="ScheduleDelete(id=s07)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12332 t=998000|998999 req="PromiseSubscribe(awaited=p08, address=addr08)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=0), value=Value(headers=map[], data=), timeout=740887, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=65000)))" err=<nil>
level=INFO msg=DST id=12333 t=998000|998999 req="PromiseGet(id=p04)" res="Response(status=20000, PromiseGet(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), timeout=665372, tags=map[resonate:invoke:dst], createdOn=18000, completedOn=59000)))" err=<nil>
level=INFO msg=DST id=12335 t=998000|998999 req="TaskCreate(promise=PromiseCreate(id=p19, param=Value(headers=map[], data=), timeout=115980, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p19, processId=p19, ttl=2000, timeout=115980))" res="Response(status=20000, TaskCreate(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=811719, tags=map[], createdOn=3000, completedOn=10000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12337 t=999000|1000999 req="CallbackCreate(id=__resume:p03:p16, promiseId=p16, recv=\"dst\", mesg=Mesg(type=resume, root=p03, leaf=p16), timeout=999312)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p16, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=), value=Value(headers=map[], data=3), timeout=583271, tags=map[], createdOn=6000, completedOn=44000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12340 t=999000|1000999 req="CallbackCreate(id=__notify:p06:cb17, promiseId=p06, recv=\"dst\", mesg=Mesg(type=notify, root=p06, leaf=), timeout=999132)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p06, state=RESOLVED, param=Value(headers=map[], data=0), value=Value(headers=map[0:10.0 1:10.1], data=), timeout=561390, tags=map[resonate:invoke:dst], createdOn=0, completedOn=14000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12343 t=999000|1000999 req="CallbackCreate(id=__resume:p20:p23, promiseId=p23, recv=\"dst\", mesg=Mesg(type=resume, root=p20, leaf=p23), timeout=999196)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p23, state=REJECTED_CANCELED, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=0), timeout=708572, tags=map[0:11.0 resonate:invoke:dst resonate:timeout:true], createdOn=0, completedOn=10000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12348 t=999000|1000999 req="PromiseComplete(id=p13, state=REJECTED_CANCELED, value=Value(headers=map[], data=0))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p13, state=REJECTED_CANCELED, param=Value(headers=map[0:14.0 1:14.1], data=), value=Value(headers=map[0:19.0 1:19.1], data=), timeout=896220, tags=map[resonate:invoke:dst], createdOn=7000, completedOn=61000)))" err=<nil>
level=INFO msg=DST id=12350 t=999000|1000999 req="CallbackCreate(id=__resume:p01:p08, promiseId=p08, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p08), timeout=999131)" res="Response(status=20000, CallbackCreate(promise=Promise(id=p08, state=REJECTED, param=Value(headers=map[], data=0), value=Value(headers=map[], data=), timeout=740887, tags=map[resonate:invoke:dst], createdOn=5000, completedOn=65000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12353 t=999000|1000999 req="PromiseComplete(id=p09, state=REJECTED, value=Value(headers=map[0:16.0 1:16.1 2:16.2], data=3))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p09, state=REJECTED, param=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), value=Value(headers=map[0:17.0], data=2), timeout=754873, tags=map[], createdOn=3000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12338 t=999000|1000999 req="PromiseGet(id=p20)" res="Response(status=20000, PromiseGet(promise=Promise(id=p20, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=4), timeout=486039, tags=map[resonate:invoke:dst], createdOn=1000, completedOn=37000)))" err=<nil>
level=INFO msg=DST id=12339 t=999000|1000999 req="PromiseGet(id=p04)" res="Response(status=20000, PromiseGet(promise=Promise(id=p04, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), timeout=665372, tags=map[resonate:invoke:dst], createdOn=18000, completedOn=59000)))" err=<nil>
level=INFO msg=DST id=12342 t=999000|1000999 req="PromiseCreate(id=p19, param=Value(headers=map[0:15.0 1:15.1], data=), timeout=994679, tags=map[0:10.0 resonate:invoke:dst])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p19, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=811719, tags=map[], createdOn=3000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12344 t=999000|1000999 req="PromiseCreate(id=p10, param=Value(headers=map[0:14.0 1:14.1], data=), timeout=772137, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p10, state=REJECTED_TIMEDOUT, param=Value(headers=map[0:19.0 1:19.1], data=2), value=Value(headers=map[], data=), timeout=13821, tags=map[], createdOn=0, completedOn=13821)))" err=<nil>
level=INFO msg=DST id=12345 t=999000|1000999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=REJECTED_CANCELED, param=Value(headers=map[], data=0), value=Value(headers=map[0:7.0 1:7.1], data=), timeout=710424, tags=map[resonate:invoke:dst], createdOn=3000, completedOn=43000)))" err=<nil>
level=INFO msg=DST id=12347 t=999000|1000999 req="PromiseSubscribe(awaited=p09, address=addr15)" res="Response(status=20000, PromiseSubscribe(promise=Promise(id=p09, state=REJECTED, param=Value(headers=map[0:8.0 1:8.1 2:8.2], data=), value=Value(headers=map[0:17.0], data=2), timeout=754873, tags=map[], createdOn=3000, completedOn=10000)))" err=<nil>
level=INFO msg=DST id=12349 t=999000|1000999 req="PromiseGet(id=p11)" res="Response(status=20000, PromiseGet(promise=Promise(id=p11, state=REJECTED_CANCELED, param=Value(headers=map[0:3.0 1:3.1], data=), value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=2), timeout=455263, tags=map[], createdOn=15000, completedOn=21000)))" err=<nil>
level=INFO msg=DST id=12351 t=999000|1000999 req="PromiseCreate(id=p11, param=Value(headers=map[0:2.0 1:2.1], data=), timeout=947917, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p11, state=REJECTED_CANCELED, param=Value(headers=map[0:3.0 1:3.1], data=), value=Value(headers=map[0:9.0 1:9.1 2:9.2], data=2), timeout=455263, tags=map[], createdOn=15000, completedOn=21000)))" err=<nil>
level=INFO msg=DST id=12352 t=999000|1000999 req="PromiseCreate(id=p18, param=Value(headers=map[], data=0), timeout=60015, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p18, state=REJECTED_CANCELED, param=Value(headers=map[], data=1), value=Value(headers=map[0:16.0 1:16.1 2:16.2], data=), timeout=132919, tags=map[resonate:invoke:dst], createdOn=0, completedOn=47000)))" err=<nil>
level=INFO msg=DST id=12341 t=999000|1001999 req="ScheduleCreate(id=s07, desc=, cron=46 * * * *, tags=map[resonate:invoke:dst], promiseId=s07.{{.timestamp}}, promiseTimeout=999382, promiseParam=Value(headers=map[0:7.0 1:7.1], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s07, desc=, cron=46 * * * *, tags=map[resonate:invoke:dst], promiseId=s07.{{.timestamp}}, promiseTimeout=999382, promiseParam=Value(headers=map[0:7.0 1:7.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2760000, createdOn=1001000)))" err=<nil>
level=INFO msg=DST id=12346 t=999000|1001999 req="ScheduleCreate(id=s09, desc=, cron=43 * * * *, tags=map[], promiseId=s09.{{.timestamp}}, promiseTimeout=999884, promiseParam=Value(headers=map[], data=0), promiseTags=map[0:8.0])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s09, desc=, cron=43 * * * *, tags=map[], promiseId=s09.{{.timestamp}}, promiseTimeout=999884, promiseParam=Value(headers=map[], data=0), promiseTags=map[0:8.0], lastRunTime=0, nextRunTime=2580000, 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='29144'

more details

[flossypurse]

Closing as part of a maintenance sweep — this appears to be an automated DST failure record. If this represents an active bug, please reopen with reproduction steps.

[flossypurse]

automated accidental close, reopening.