DST: 29591

[github-actions]

DST Failed

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

Seed

29591

Scenario

default

Store

sqlite

Commit

7b862640736628abad0dca49f3a762188f4f7e0b

Command

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

Logs

level=INFO msg=DST seed=29591 ticks=1000 reqsPerTick=1:25 dst="DST(ids=18, headers=24, data=11, tags=21, backchannel=316)" system="System(api=API(size=108, subsystems=[]), aio=AIODST(subsystems=[router sender store]), config=Config(cms=700, sbs=377, cbs=264, pbs=7, sbs=96, tbs=8))"
level=INFO msg=DST id=0 t=0|999 req="PromiseComplete(id=p03, state=REJECTED_CANCELED, value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=9))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=2 t=0|999 req="PromiseGet(id=p02)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=3 t=0|999 req="PromiseGet(id=p01)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=4 t=1000|1999 req="PromiseComplete(id=p10, state=REJECTED_CANCELED, value=Value(headers=map[], data=5))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=6 t=1000|1999 req="PromiseRegister(id=__resume:p13:p10, promiseId=p10, recv=\"dst\", mesg=Mesg(type=resume, root=p13, leaf=p10), timeout=423875)" res="Response(status=40400, PromiseRegister(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=7 t=1000|1999 req="PromiseComplete(id=p01, state=REJECTED_CANCELED, value=Value(headers=map[0:17.0 1:17.1 2:17.2], data=4))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=12 t=1000|1999 req="PromiseGet(id=p12)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=13 t=1000|1999 req="PromiseGet(id=p04)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=17 t=1000|1999 req="PromiseGet(id=p00)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=20 t=1000|1999 req="PromiseComplete(id=p04, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=21 t=1000|1999 req="PromiseRegister(id=__resume:p10:p13, promiseId=p13, recv=\"dst\", mesg=Mesg(type=resume, root=p10, leaf=p13), timeout=463907)" res="Response(status=40400, PromiseRegister(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=22 t=1000|1999 req="PromiseRegister(id=__resume:p16:p07, promiseId=p07, recv=\"dst\", mesg=Mesg(type=resume, root=p16, leaf=p07), timeout=489421)" res="Response(status=40400, PromiseRegister(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=25 t=1000|1999 req="ScheduleGet(id=s14)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=27 t=1000|1999 req="ScheduleGet(id=s14)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=29 t=2000|2999 req="PromiseComplete(id=p02, state=REJECTED_CANCELED, value=Value(headers=map[], data=2))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=30 t=2000|2999 req="PromiseGet(id=p03)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=31 t=2000|2999 req="PromiseGet(id=p03)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=32 t=2000|2999 req="PromiseComplete(id=p10, state=REJECTED_CANCELED, value=Value(headers=map[], data=9))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=10 t=1000|2999 req="ScheduleCreate(id=s08, desc=, cron=55 * * * *, tags=map[0:5.0 1:5.1], promiseId=s08.{{.timestamp}}, promiseTimeout=801551, promiseParam=Value(headers=map[0:0.0], data=10), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s08, desc=, cron=55 * * * *, tags=map[0:5.0 1:5.1], promiseId=s08.{{.timestamp}}, promiseTimeout=801551, promiseParam=Value(headers=map[0:0.0], data=10), promiseTags=map[], lastRunTime=0, nextRunTime=3300000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=14 t=1000|2999 req="ScheduleCreate(id=s00, desc=, cron=53 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=155962, promiseParam=Value(headers=map[0:22.0 1:22.1], data=3), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s00, desc=, cron=53 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=155962, promiseParam=Value(headers=map[0:22.0 1:22.1], data=3), promiseTags=map[], lastRunTime=0, nextRunTime=3180000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=15 t=1000|2999 req="ScheduleCreate(id=s14, desc=, cron=49 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=694481, promiseParam=Value(headers=map[0:18.0], data=), promiseTags=map[0:8.0 1:8.1 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s14, desc=, cron=49 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=694481, promiseParam=Value(headers=map[0:18.0], data=), promiseTags=map[0:8.0 1:8.1 resonate:timeout:true], lastRunTime=0, nextRunTime=2940000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=18 t=1000|2999 req="ScheduleCreate(id=s11, desc=, cron=48 * * * *, tags=map[0:3.0], promiseId=s11.{{.timestamp}}, promiseTimeout=256182, promiseParam=Value(headers=map[], data=0), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=48 * * * *, tags=map[0:3.0], promiseId=s11.{{.timestamp}}, promiseTimeout=256182, promiseParam=Value(headers=map[], data=0), promiseTags=map[], lastRunTime=0, nextRunTime=2880000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=26 t=1000|2999 req="ScheduleCreate(id=s09, desc=, cron=1 * * * *, tags=map[], promiseId=s09.{{.timestamp}}, promiseTimeout=281586, promiseParam=Value(headers=map[], data=8), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s09, desc=, cron=1 * * * *, tags=map[], promiseId=s09.{{.timestamp}}, promiseTimeout=281586, promiseParam=Value(headers=map[], data=8), promiseTags=map[], lastRunTime=0, nextRunTime=60000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=1 t=0|2999 req="PromiseCreate(id=p17, param=Value(headers=map[0:4.0], data=5), timeout=639456, tags=map[resonate:timeout:true])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p17, state=PENDING, param=Value(headers=map[0:4.0], data=5), value=Value(headers=map[], data=), timeout=639456, tags=map[resonate:timeout:true], createdOn=0, completedOn=0)))" err=<nil>
level=INFO msg=DST id=35 t=3000|3999 req="PromiseComplete(id=p13, state=REJECTED_CANCELED, value=Value(headers=map[0:20.0], data=10))" res="Response(status=40400, PromiseComplete(promise=<nil>))" err=<nil>
level=INFO msg=DST id=8 t=1000|3999 req="PromiseCreate(id=p07, param=Value(headers=map[0:18.0], data=), timeout=247863, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p07, state=PENDING, param=Value(headers=map[0:18.0], data=), value=Value(headers=map[], data=), timeout=247863, tags=map[], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=9 t=1000|3999 req="TaskCreate(promise=PromiseCreate(id=p00, param=Value(headers=map[0:8.0 1:8.1], data=), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p00, processId=p00, ttl=5000, timeout=192581))" res="Response(status=20100, TaskCreate(promise=Promise(id=p00, state=PENDING, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[], data=), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=0), task=Task(id=__invoke:p00, processId=p00, state=CLAIMED, rootPromiseId=p00, recv=\"dst\", mesg=Mesg(type=invoke, root=p00, leaf=p00), timeout=192581, counter=1, attempt=0, ttl=5000, expiresAt=6000, createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=16 t=1000|3999 req="PromiseCreate(id=p11, param=Value(headers=map[], data=9), timeout=634974, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p11, state=PENDING, param=Value(headers=map[], data=9), value=Value(headers=map[], data=), timeout=634974, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=19 t=1000|3999 req="PromiseCreate(id=p08, param=Value(headers=map[0:2.0 1:2.1], data=), timeout=254786, tags=map[0:5.0 1:5.1])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p08, state=PENDING, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[], data=), timeout=254786, tags=map[0:5.0 1:5.1], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=23 t=1000|3999 req="PromiseCreate(id=p12, param=Value(headers=map[0:12.0], data=3), timeout=244943, tags=map[resonate:timeout:true])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p12, state=PENDING, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=24 t=1000|3999 req="PromiseCreate(id=p06, param=Value(headers=map[], data=), timeout=523752, tags=map[resonate:timeout:true])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p06, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=523752, tags=map[resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=34 t=3000|3999 req="PromiseCreate(id=p06, param=Value(headers=map[0:9.0 1:9.1], data=9), timeout=493585, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p06, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=523752, tags=map[resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=37 t=4000|4999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=38 t=4000|4999 req="ScheduleGet(id=s06)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=28 t=2000|4999 req="PromiseCreate(id=p02, param=Value(headers=map[], data=4), timeout=944754, tags=map[])" res="Response(status=20100, PromiseCreate(promise=Promise(id=p02, state=PENDING, param=Value(headers=map[], data=4), value=Value(headers=map[], data=), timeout=944754, tags=map[], createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=33 t=2000|4999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[], data=7), timeout=497098, tags=map[0:20.0 resonate:invoke:dst]), task=CreateTask(promiseId=p16, processId=p16, ttl=2000, timeout=497098))" res="Response(status=20100, TaskCreate(promise=Promise(id=p16, state=PENDING, param=Value(headers=map[], data=7), value=Value(headers=map[], data=), timeout=497098, tags=map[0:20.0 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=497098, counter=1, attempt=0, ttl=2000, expiresAt=4000, createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=36 t=4000|4999 req="PromiseCreate(id=p00, param=Value(headers=map[0:13.0], data=), timeout=749588, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p00, state=PENDING, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[], data=), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=5 t=1000|4999 req="TaskCreate(promise=PromiseCreate(id=p06, param=Value(headers=map[], data=), timeout=212124, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p06, processId=p06, ttl=9223372036854775807, timeout=212124))" res="Response(status=20000, TaskCreate(promise=Promise(id=p06, state=PENDING, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=523752, tags=map[resonate:timeout:true], createdOn=1000, completedOn=0), task=<nil>))" err=<nil>
level=INFO msg=DST id=11 t=1000|4999 req="PromiseCreate(id=p11, param=Value(headers=map[0:10.0], data=2), timeout=80083, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p11, state=PENDING, param=Value(headers=map[], data=9), value=Value(headers=map[], data=), timeout=634974, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=39 t=5000|5999 req="ScheduleDelete(id=s01)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=40 t=5000|5999 req="ScheduleGet(id=s14)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s14, desc=, cron=49 * * * *, tags=map[], promiseId=s14.{{.timestamp}}, promiseTimeout=694481, promiseParam=Value(headers=map[0:18.0], data=), promiseTags=map[0:8.0 1:8.1 resonate:timeout:true], lastRunTime=0, nextRunTime=2940000, createdOn=2000)))" err=<nil>
level=INFO msg=DST id=44 t=5000|5999 req="PromiseGet(id=p15)" res="Response(status=40400, PromiseGet(promise=<nil>))" err=<nil>
level=INFO msg=DST id=45 t=5000|5999 req="PromiseGet(id=p12)" res="Response(status=20000, PromiseGet(promise=Promise(id=p12, state=PENDING, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=46 t=5000|5999 req="PromiseRegister(id=__resume:p14:p15, promiseId=p15, recv=\"dst\", mesg=Mesg(type=resume, root=p14, leaf=p15), timeout=667372)" res="Response(status=40400, PromiseRegister(promise=<nil>, callback=<nil>))" err=<nil>
level=INFO msg=DST id=47 t=5000|5999 req="ScheduleGet(id=s07)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=48 t=5000|5999 req="ScheduleDelete(id=s16)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=42 t=5000|5999 req="TaskCreate(promise=PromiseCreate(id=p12, param=Value(headers=map[], data=), timeout=446286, tags=map[0:20.0 resonate:invoke:dst]), task=CreateTask(promiseId=p12, processId=p12, ttl=5000, timeout=446286))" res="Response(status=20000, TaskCreate(promise=Promise(id=p12, state=PENDING, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=0), task=<nil>))" err=<nil>
level=INFO msg=DST id=43 t=5000|5999 req="PromiseCreate(id=p16, param=Value(headers=map[], data=10), timeout=375049, tags=map[0:17.0 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p16, state=PENDING, param=Value(headers=map[], data=7), value=Value(headers=map[], data=), timeout=497098, tags=map[0:20.0 resonate:invoke:dst], createdOn=2000, completedOn=0)))" err=<nil>
level=INFO msg=DST id=51 t=6000|6999 req="ScheduleDelete(id=s02)" res="Response(status=40401, ScheduleDelete())" err=<nil>

...

level=INFO msg=DST id=12337 t=992000|992999 req="PromiseRegister(id=__resume:p12:p13, promiseId=p13, recv=\"dst\", mesg=Mesg(type=resume, root=p12, leaf=p13), timeout=995455)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p13, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:18.0], data=7), timeout=214398, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=31000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12338 t=992000|992999 req="ScheduleGet(id=s16)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12342 t=992000|992999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:17.0 1:17.1 2:17.2], data=7), value=Value(headers=map[], data=5), timeout=46482, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true], createdOn=16000, completedOn=39000)))" err=<nil>
level=INFO msg=DST id=12343 t=992000|992999 req="PromiseGet(id=p13)" res="Response(status=20000, PromiseGet(promise=Promise(id=p13, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:18.0], data=7), timeout=214398, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=31000)))" err=<nil>
level=INFO msg=DST id=12344 t=992000|992999 req="ScheduleGet(id=s17)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12339 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p02, param=Value(headers=map[0:19.0 1:19.1], data=8), timeout=547337, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p02, processId=p02, ttl=9223372036854775807, timeout=547337))" res="Response(status=20000, TaskCreate(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=944754, tags=map[], createdOn=2000, completedOn=7000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12341 t=992000|992999 req="TaskCreate(promise=PromiseCreate(id=p03, param=Value(headers=map[], data=6), timeout=608985, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p03, processId=p03, ttl=1000, timeout=608985))" res="Response(status=20000, TaskCreate(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:6.0 1:6.1], data=), timeout=346386, tags=map[], createdOn=8000, completedOn=15000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12346 t=993000|993999 req="PromiseComplete(id=p10, state=RESOLVED, value=Value(headers=map[0:14.0 1:14.1], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1], data=), value=Value(headers=map[], data=3), timeout=916562, tags=map[], createdOn=7000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12349 t=993000|993999 req="PromiseRegister(id=__notify:p05:cb01, promiseId=p05, recv=\"dst\", mesg=Mesg(type=notify, root=p05, leaf=), timeout=993237)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:17.0 1:17.1 2:17.2], data=7), value=Value(headers=map[], data=5), timeout=46482, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true], createdOn=16000, completedOn=39000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12350 t=993000|993999 req="PromiseGet(id=p02)" res="Response(status=20000, PromiseGet(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=944754, tags=map[], createdOn=2000, completedOn=7000)))" err=<nil>
level=INFO msg=DST id=12351 t=993000|993999 req="PromiseGet(id=p02)" res="Response(status=20000, PromiseGet(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=944754, tags=map[], createdOn=2000, completedOn=7000)))" err=<nil>
level=INFO msg=DST id=12352 t=993000|993999 req="ScheduleGet(id=s11)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12354 t=993000|993999 req="PromiseRegister(id=__resume:p16:p00, promiseId=p00, recv=\"dst\", mesg=Mesg(type=resume, root=p16, leaf=p00), timeout=994586)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[0:12.0], data=8), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=40000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12355 t=993000|993999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12357 t=993000|993999 req="PromiseComplete(id=p16, state=RESOLVED, value=Value(headers=map[0:10.0], data=4))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[], data=7), value=Value(headers=map[0:15.0], data=), timeout=497098, tags=map[0:20.0 resonate:invoke:dst], createdOn=2000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12359 t=993000|993999 req="PromiseGet(id=p15)" res="Response(status=20000, PromiseGet(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12340 t=992000|993999 req="ScheduleCreate(id=s16, desc=, cron=26 * * * *, tags=map[0:3.0], promiseId=s16.{{.timestamp}}, promiseTimeout=994418, promiseParam=Value(headers=map[], data=), promiseTags=map[0:3.0])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s16, desc=, cron=26 * * * *, tags=map[0:3.0], promiseId=s16.{{.timestamp}}, promiseTimeout=994418, promiseParam=Value(headers=map[], data=), promiseTags=map[0:3.0], lastRunTime=0, nextRunTime=1560000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12345 t=993000|993999 req="PromiseCreate(id=p08, param=Value(headers=map[0:11.0], data=), timeout=17027, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[0:5.0 1:5.1], data=10), timeout=254786, tags=map[0:5.0 1:5.1], createdOn=1000, completedOn=8000)))" err=<nil>
level=INFO msg=DST id=12348 t=993000|993999 req="TaskCreate(promise=PromiseCreate(id=p16, param=Value(headers=map[0:5.0 1:5.1], data=2), timeout=115906, tags=map[0:6.0 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p16, processId=p16, ttl=5000, timeout=115906))" res="Response(status=20000, TaskCreate(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[], data=7), value=Value(headers=map[0:15.0], data=), timeout=497098, tags=map[0:20.0 resonate:invoke:dst], createdOn=2000, completedOn=16000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12353 t=993000|993999 req="PromiseCreate(id=p03, param=Value(headers=map[], data=0), timeout=647619, tags=map[0:17.0 resonate:invoke:dst resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:6.0 1:6.1], data=), timeout=346386, tags=map[], createdOn=8000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12356 t=993000|993999 req="PromiseCreate(id=p15, param=Value(headers=map[0:21.0], data=), timeout=874260, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12358 t=993000|993999 req="PromiseCreate(id=p01, param=Value(headers=map[0:13.0], data=), timeout=776314, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=9), value=Value(headers=map[], data=5), timeout=912846, tags=map[0:19.0 resonate:invoke:dst], createdOn=6000, completedOn=18000)))" err=<nil>
level=INFO msg=DST id=12360 t=994000|994999 req="ScheduleCreate(id=s03, desc=, cron=27 * * * *, tags=map[0:19.0], promiseId=s03.{{.timestamp}}, promiseTimeout=998880, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s03, desc=, cron=48 * * * *, tags=map[0:9.0 1:9.1 resonate:invoke:dst], promiseId=s03.{{.timestamp}}, promiseTimeout=993577, promiseParam=Value(headers=map[], data=6), promiseTags=map[], lastRunTime=0, nextRunTime=2880000, createdOn=973000)))" err=<nil>
level=INFO msg=DST id=12361 t=994000|994999 req="ScheduleGet(id=s00)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12362 t=994000|994999 req="PromiseComplete(id=p12, state=RESOLVED, value=Value(headers=map[0:2.0 1:2.1], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[0:11.0], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=28000)))" err=<nil>
level=INFO msg=DST id=12364 t=994000|994999 req="ScheduleGet(id=s07)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s07, desc=, cron=12 * * * *, tags=map[resonate:timeout:true], promiseId=s07.{{.timestamp}}, promiseTimeout=988309, promiseParam=Value(headers=map[0:22.0 1:22.1], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4320000, createdOn=981000)))" err=<nil>
level=INFO msg=DST id=12366 t=994000|994999 req="ScheduleCreate(id=s03, desc=, cron=52 * * * *, tags=map[], promiseId=s03.{{.timestamp}}, promiseTimeout=999235, promiseParam=Value(headers=map[], data=), promiseTags=map[resonate:invoke:dst])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s03, desc=, cron=48 * * * *, tags=map[0:9.0 1:9.1 resonate:invoke:dst], promiseId=s03.{{.timestamp}}, promiseTimeout=993577, promiseParam=Value(headers=map[], data=6), promiseTags=map[], lastRunTime=0, nextRunTime=2880000, createdOn=973000)))" err=<nil>
level=INFO msg=DST id=12367 t=994000|994999 req="ScheduleDelete(id=s12)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12368 t=994000|994999 req="PromiseGet(id=p00)" res="Response(status=20000, PromiseGet(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[0:12.0], data=8), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=40000)))" err=<nil>
level=INFO msg=DST id=12369 t=994000|994999 req="ScheduleCreate(id=s03, desc=, cron=38 * * * *, tags=map[0:2.0 1:2.1 resonate:timeout:true], promiseId=s03.{{.timestamp}}, promiseTimeout=999342, promiseParam=Value(headers=map[], data=6), promiseTags=map[0:13.0 resonate:timeout:true])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s03, desc=, cron=48 * * * *, tags=map[0:9.0 1:9.1 resonate:invoke:dst], promiseId=s03.{{.timestamp}}, promiseTimeout=993577, promiseParam=Value(headers=map[], data=6), promiseTags=map[], lastRunTime=0, nextRunTime=2880000, createdOn=973000)))" err=<nil>
level=INFO msg=DST id=12371 t=994000|994999 req="ScheduleDelete(id=s00)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12372 t=994000|994999 req="ScheduleGet(id=s04)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12373 t=994000|994999 req="PromiseRegister(id=__resume:p04:p10, promiseId=p10, recv=\"dst\", mesg=Mesg(type=resume, root=p04, leaf=p10), timeout=995329)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1], data=), value=Value(headers=map[], data=3), timeout=916562, tags=map[], createdOn=7000, completedOn=19000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12374 t=994000|994999 req="PromiseComplete(id=p02, state=REJECTED_CANCELED, value=Value(headers=map[], data=9))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=944754, tags=map[], createdOn=2000, completedOn=7000)))" err=<nil>
level=INFO msg=DST id=12375 t=994000|994999 req="ScheduleDelete(id=s14)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12376 t=994000|994999 req="ScheduleGet(id=s10)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s10, desc=, cron=20 * * * *, tags=map[resonate:invoke:dst], promiseId=s10.{{.timestamp}}, promiseTimeout=996175, promiseParam=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), promiseTags=map[0:7.0 1:7.1 2:7.2], lastRunTime=0, nextRunTime=1200000, createdOn=994000)))" err=<nil>
level=INFO msg=DST id=12379 t=994000|994999 req="PromiseRegister(id=__notify:p12:cb06, promiseId=p12, recv=\"dst\", mesg=Mesg(type=notify, root=p12, leaf=), timeout=994913)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[0:11.0], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=28000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12380 t=994000|994999 req="PromiseComplete(id=p16, state=REJECTED_CANCELED, value=Value(headers=map[0:11.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[], data=7), value=Value(headers=map[0:15.0], data=), timeout=497098, tags=map[0:20.0 resonate:invoke:dst], createdOn=2000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12347 t=993000|994999 req="ScheduleCreate(id=s10, desc=, cron=20 * * * *, tags=map[resonate:invoke:dst], promiseId=s10.{{.timestamp}}, promiseTimeout=996175, promiseParam=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), promiseTags=map[0:7.0 1:7.1 2:7.2])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s10, desc=, cron=20 * * * *, tags=map[resonate:invoke:dst], promiseId=s10.{{.timestamp}}, promiseTimeout=996175, promiseParam=Value(headers=map[0:1.0 1:1.1 2:1.2], data=), promiseTags=map[0:7.0 1:7.1 2:7.2], lastRunTime=0, nextRunTime=1200000, createdOn=994000)))" err=<nil>
level=INFO msg=DST id=12363 t=994000|994999 req="PromiseCreate(id=p10, param=Value(headers=map[], data=3), timeout=21713, tags=map[0:2.0 1:2.1 resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1], data=), value=Value(headers=map[], data=3), timeout=916562, tags=map[], createdOn=7000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12370 t=994000|994999 req="PromiseCreate(id=p10, param=Value(headers=map[0:6.0 1:6.1], data=9), timeout=743000, tags=map[0:3.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1], data=), value=Value(headers=map[], data=3), timeout=916562, tags=map[], createdOn=7000, completedOn=19000)))" err=<nil>
level=INFO msg=DST id=12377 t=994000|994999 req="TaskCreate(promise=PromiseCreate(id=p04, param=Value(headers=map[0:21.0], data=2), timeout=914416, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p04, processId=p04, ttl=4000, timeout=914416))" res="Response(status=20000, TaskCreate(promise=Promise(id=p04, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0], data=), value=Value(headers=map[0:16.0 1:16.1], data=10), timeout=251584, tags=map[0:16.0 resonate:invoke:dst], createdOn=31000, completedOn=36000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12378 t=994000|994999 req="PromiseCreate(id=p11, param=Value(headers=map[0:19.0 1:19.1], data=6), timeout=227204, tags=map[resonate:timeout:true])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p11, state=RESOLVED, param=Value(headers=map[], data=9), value=Value(headers=map[], data=), timeout=634974, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=43000)))" err=<nil>
level=INFO msg=DST id=12381 t=995000|995999 req="ScheduleGet(id=s02)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12383 t=995000|995999 req="PromiseComplete(id=p01, state=RESOLVED, value=Value(headers=map[], data=8))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=9), value=Value(headers=map[], data=5), timeout=912846, tags=map[0:19.0 resonate:invoke:dst], createdOn=6000, completedOn=18000)))" err=<nil>
level=INFO msg=DST id=12385 t=995000|995999 req="PromiseRegister(id=__resume:p12:p13, promiseId=p13, recv=\"dst\", mesg=Mesg(type=resume, root=p12, leaf=p13), timeout=999003)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p13, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:18.0], data=7), timeout=214398, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=31000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12386 t=995000|995999 req="PromiseRegister(id=__resume:p01:p03, promiseId=p03, recv=\"dst\", mesg=Mesg(type=resume, root=p01, leaf=p03), timeout=995322)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:6.0 1:6.1], data=), timeout=346386, tags=map[], createdOn=8000, completedOn=15000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12388 t=995000|995999 req="PromiseGet(id=p13)" res="Response(status=20000, PromiseGet(promise=Promise(id=p13, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:18.0], data=7), timeout=214398, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=31000)))" err=<nil>
level=INFO msg=DST id=12389 t=995000|995999 req="PromiseComplete(id=p15, state=REJECTED_CANCELED, value=Value(headers=map[0:15.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12365 t=994000|995999 req="ScheduleCreate(id=s13, desc=, cron=21 * * * *, tags=map[0:14.0 1:14.1 2:14.2 resonate:invoke:dst], promiseId=s13.{{.timestamp}}, promiseTimeout=997963, promiseParam=Value(headers=map[], data=6), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s13, desc=, cron=21 * * * *, tags=map[0:14.0 1:14.1 2:14.2 resonate:invoke:dst], promiseId=s13.{{.timestamp}}, promiseTimeout=997963, promiseParam=Value(headers=map[], data=6), promiseTags=map[], lastRunTime=0, nextRunTime=1260000, createdOn=995000)))" err=<nil>
level=INFO msg=DST id=12384 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p00, param=Value(headers=map[0:20.0], data=), timeout=622615, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p00, processId=p00, ttl=3000, timeout=622615))" res="Response(status=20000, TaskCreate(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[0:12.0], data=8), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=40000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12390 t=995000|995999 req="TaskCreate(promise=PromiseCreate(id=p08, param=Value(headers=map[], data=7), timeout=494113, tags=map[0:16.0 resonate:invoke:dst]), task=CreateTask(promiseId=p08, processId=p08, ttl=4000, timeout=494113))" res="Response(status=20000, TaskCreate(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[0:5.0 1:5.1], data=10), timeout=254786, tags=map[0:5.0 1:5.1], createdOn=1000, completedOn=8000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12392 t=996000|996999 req="PromiseComplete(id=p12, state=REJECTED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[0:11.0], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=28000)))" err=<nil>
level=INFO msg=DST id=12394 t=996000|996999 req="ScheduleDelete(id=s13)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12396 t=996000|996999 req="PromiseRegister(id=__resume:p02:p06, promiseId=p06, recv=\"dst\", mesg=Mesg(type=resume, root=p02, leaf=p06), timeout=998081)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1], data=), timeout=523752, tags=map[resonate:timeout:true], createdOn=1000, completedOn=17000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12397 t=996000|996999 req="ScheduleGet(id=s15)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12399 t=996000|996999 req="PromiseRegister(id=__resume:p05:p14, promiseId=p14, recv=\"dst\", mesg=Mesg(type=resume, root=p05, leaf=p14), timeout=999689)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=368358, tags=map[0:9.0 1:9.1 resonate:invoke:dst], createdOn=6000, completedOn=14000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12400 t=996000|996999 req="PromiseComplete(id=p00, state=REJECTED_CANCELED, value=Value(headers=map[0:14.0 1:14.1], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[0:12.0], data=8), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=40000)))" err=<nil>
level=INFO msg=DST id=12404 t=996000|996999 req="PromiseRegister(id=__resume:p14:p10, promiseId=p10, recv=\"dst\", mesg=Mesg(type=resume, root=p14, leaf=p10), timeout=998615)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1], data=), value=Value(headers=map[], data=3), timeout=916562, tags=map[], createdOn=7000, completedOn=19000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12405 t=996000|996999 req="ScheduleGet(id=s15)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12406 t=996000|996999 req="ScheduleGet(id=s01)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s01, desc=, cron=42 * * * *, tags=map[0:3.0], promiseId=s01.{{.timestamp}}, promiseTimeout=997106, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=2520000, createdOn=987000)))" err=<nil>
level=INFO msg=DST id=12408 t=996000|996999 req="ScheduleGet(id=s08)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s08, desc=, cron=53 * * * *, tags=map[0:5.0 1:5.1], promiseId=s08.{{.timestamp}}, promiseTimeout=997305, promiseParam=Value(headers=map[], data=), promiseTags=map[0:2.0 1:2.1 resonate:timeout:true], lastRunTime=0, nextRunTime=3180000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12382 t=995000|996999 req="ScheduleCreate(id=s00, desc=, cron=8 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=997853, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s00, desc=, cron=8 * * * *, tags=map[], promiseId=s00.{{.timestamp}}, promiseTimeout=997853, promiseParam=Value(headers=map[], data=), promiseTags=map[], lastRunTime=0, nextRunTime=4080000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12387 t=995000|996999 req="ScheduleCreate(id=s11, desc=, cron=26 * * * *, tags=map[0:19.0], promiseId=s11.{{.timestamp}}, promiseTimeout=997249, promiseParam=Value(headers=map[], data=8), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s11, desc=, cron=26 * * * *, tags=map[0:19.0], promiseId=s11.{{.timestamp}}, promiseTimeout=997249, promiseParam=Value(headers=map[], data=8), promiseTags=map[], lastRunTime=0, nextRunTime=1560000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12391 t=995000|996999 req="ScheduleCreate(id=s08, desc=, cron=53 * * * *, tags=map[0:5.0 1:5.1], promiseId=s08.{{.timestamp}}, promiseTimeout=997305, promiseParam=Value(headers=map[], data=), promiseTags=map[0:2.0 1:2.1 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s08, desc=, cron=53 * * * *, tags=map[0:5.0 1:5.1], promiseId=s08.{{.timestamp}}, promiseTimeout=997305, promiseParam=Value(headers=map[], data=), promiseTags=map[0:2.0 1:2.1 resonate:timeout:true], lastRunTime=0, nextRunTime=3180000, createdOn=996000)))" err=<nil>
level=INFO msg=DST id=12393 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p17, param=Value(headers=map[], data=2), timeout=764811, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p17, processId=p17, ttl=3000, timeout=764811))" res="Response(status=20000, TaskCreate(promise=Promise(id=p17, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=5), value=Value(headers=map[], data=6), timeout=639456, tags=map[resonate:timeout:true], createdOn=0, completedOn=7000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12395 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p14, param=Value(headers=map[], data=0), timeout=694665, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true]), task=CreateTask(promiseId=p14, processId=p14, ttl=5000, timeout=694665))" res="Response(status=20000, TaskCreate(promise=Promise(id=p14, state=REJECTED, param=Value(headers=map[], data=), value=Value(headers=map[], data=), timeout=368358, tags=map[0:9.0 1:9.1 resonate:invoke:dst], createdOn=6000, completedOn=14000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12398 t=996000|996999 req="PromiseCreate(id=p12, param=Value(headers=map[0:12.0], data=0), timeout=593968, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p12, state=REJECTED_CANCELED, param=Value(headers=map[0:12.0], data=3), value=Value(headers=map[0:11.0], data=), timeout=244943, tags=map[resonate:timeout:true], createdOn=1000, completedOn=28000)))" err=<nil>
level=INFO msg=DST id=12401 t=996000|996999 req="PromiseCreate(id=p01, param=Value(headers=map[0:0.0], data=), timeout=216435, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p01, state=REJECTED_CANCELED, param=Value(headers=map[], data=9), value=Value(headers=map[], data=5), timeout=912846, tags=map[0:19.0 resonate:invoke:dst], createdOn=6000, completedOn=18000)))" err=<nil>
level=INFO msg=DST id=12402 t=996000|996999 req="PromiseCreate(id=p15, param=Value(headers=map[0:13.0], data=), timeout=885276, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12407 t=996000|996999 req="TaskCreate(promise=PromiseCreate(id=p10, param=Value(headers=map[0:9.0 1:9.1], data=), timeout=409248, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p10, processId=p10, ttl=3000, timeout=409248))" res="Response(status=20000, TaskCreate(promise=Promise(id=p10, state=REJECTED_CANCELED, param=Value(headers=map[0:9.0 1:9.1], data=), value=Value(headers=map[], data=3), timeout=916562, tags=map[], createdOn=7000, completedOn=19000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12409 t=997000|997999 req="PromiseRegister(id=__resume:p06:p13, promiseId=p13, recv=\"dst\", mesg=Mesg(type=resume, root=p06, leaf=p13), timeout=998265)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p13, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:18.0], data=7), timeout=214398, tags=map[resonate:invoke:dst], createdOn=12000, completedOn=31000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12410 t=997000|997999 req="PromiseComplete(id=p08, state=RESOLVED, value=Value(headers=map[0:21.0], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p08, state=RESOLVED, param=Value(headers=map[0:2.0 1:2.1], data=), value=Value(headers=map[0:5.0 1:5.1], data=10), timeout=254786, tags=map[0:5.0 1:5.1], createdOn=1000, completedOn=8000)))" err=<nil>
level=INFO msg=DST id=12412 t=997000|997999 req="ScheduleGet(id=s13)" res="Response(status=40401, ScheduleGet(schedule=<nil>))" err=<nil>
level=INFO msg=DST id=12413 t=997000|997999 req="ScheduleGet(id=s16)" res="Response(status=20000, ScheduleGet(schedule=Schedule(id=s16, desc=, cron=26 * * * *, tags=map[0:3.0], promiseId=s16.{{.timestamp}}, promiseTimeout=994418, promiseParam=Value(headers=map[], data=), promiseTags=map[0:3.0], lastRunTime=0, nextRunTime=1560000, createdOn=993000)))" err=<nil>
level=INFO msg=DST id=12415 t=997000|997999 req="ScheduleDelete(id=s00)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12416 t=997000|997999 req="ScheduleCreate(id=s03, desc=, cron=15 * * * *, tags=map[], promiseId=s03.{{.timestamp}}, promiseTimeout=997589, promiseParam=Value(headers=map[], data=), promiseTags=map[])" res="Response(status=20000, ScheduleCreate(schedule=Schedule(id=s03, desc=, cron=48 * * * *, tags=map[0:9.0 1:9.1 resonate:invoke:dst], promiseId=s03.{{.timestamp}}, promiseTimeout=993577, promiseParam=Value(headers=map[], data=6), promiseTags=map[], lastRunTime=0, nextRunTime=2880000, createdOn=973000)))" err=<nil>
level=INFO msg=DST id=12403 t=996000|997999 req="ScheduleCreate(id=s14, desc=, cron=14 * * * *, tags=map[0:13.0 resonate:timeout:true], promiseId=s14.{{.timestamp}}, promiseTimeout=997577, promiseParam=Value(headers=map[], data=), promiseTags=map[0:2.0 1:2.1 resonate:timeout:true])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s14, desc=, cron=14 * * * *, tags=map[0:13.0 resonate:timeout:true], promiseId=s14.{{.timestamp}}, promiseTimeout=997577, promiseParam=Value(headers=map[], data=), promiseTags=map[0:2.0 1:2.1 resonate:timeout:true], lastRunTime=0, nextRunTime=4440000, createdOn=997000)))" err=<nil>
level=INFO msg=DST id=12411 t=997000|997999 req="PromiseCreate(id=p03, param=Value(headers=map[0:5.0 1:5.1], data=), timeout=489505, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:6.0 1:6.1], data=), timeout=346386, tags=map[], createdOn=8000, completedOn=15000)))" err=<nil>
level=INFO msg=DST id=12414 t=997000|997999 req="PromiseCreate(id=p02, param=Value(headers=map[], data=7), timeout=531332, tags=map[0:20.0])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p02, state=RESOLVED, param=Value(headers=map[], data=4), value=Value(headers=map[], data=6), timeout=944754, tags=map[], createdOn=2000, completedOn=7000)))" err=<nil>
level=INFO msg=DST id=12419 t=998000|998999 req="ScheduleDelete(id=s10)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12420 t=998000|998999 req="PromiseComplete(id=p15, state=REJECTED, value=Value(headers=map[], data=9))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12421 t=998000|998999 req="PromiseComplete(id=p16, state=RESOLVED, value=Value(headers=map[0:18.0], data=0))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p16, state=RESOLVED, param=Value(headers=map[], data=7), value=Value(headers=map[0:15.0], data=), timeout=497098, tags=map[0:20.0 resonate:invoke:dst], createdOn=2000, completedOn=16000)))" err=<nil>
level=INFO msg=DST id=12423 t=998000|998999 req="ScheduleDelete(id=s11)" res="Response(status=20400, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12418 t=998000|998999 req="PromiseCreate(id=p17, param=Value(headers=map[], data=), timeout=198453, tags=map[resonate:invoke:dst])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p17, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=5), value=Value(headers=map[], data=6), timeout=639456, tags=map[resonate:timeout:true], createdOn=0, completedOn=7000)))" err=<nil>
level=INFO msg=DST id=12422 t=998000|998999 req="TaskCreate(promise=PromiseCreate(id=p15, param=Value(headers=map[], data=1), timeout=525846, tags=map[resonate:invoke:dst]), task=CreateTask(promiseId=p15, processId=p15, ttl=4000, timeout=525846))" res="Response(status=20000, TaskCreate(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12425 t=999000|1000999 req="ScheduleDelete(id=s10)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12427 t=999000|1000999 req="PromiseGet(id=p07)" res="Response(status=20000, PromiseGet(promise=Promise(id=p07, state=RESOLVED, param=Value(headers=map[0:18.0], data=), value=Value(headers=map[], data=), timeout=247863, tags=map[], createdOn=1000, completedOn=27000)))" err=<nil>
level=INFO msg=DST id=12428 t=999000|1000999 req="PromiseGet(id=p06)" res="Response(status=20000, PromiseGet(promise=Promise(id=p06, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:8.0 1:8.1], data=), timeout=523752, tags=map[resonate:timeout:true], createdOn=1000, completedOn=17000)))" err=<nil>
level=INFO msg=DST id=12429 t=999000|1000999 req="PromiseComplete(id=p15, state=RESOLVED, value=Value(headers=map[], data=))" res="Response(status=20000, PromiseComplete(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12431 t=999000|1000999 req="PromiseRegister(id=__notify:p03:cb01, promiseId=p03, recv=\"dst\", mesg=Mesg(type=notify, root=p03, leaf=), timeout=999397)" res="Response(status=20000, PromiseRegister(promise=Promise(id=p03, state=RESOLVED, param=Value(headers=map[], data=), value=Value(headers=map[0:6.0 1:6.1], data=), timeout=346386, tags=map[], createdOn=8000, completedOn=15000), callback=<nil>))" err=<nil>
level=INFO msg=DST id=12433 t=999000|1000999 req="ScheduleDelete(id=s06)" res="Response(status=40401, ScheduleDelete())" err=<nil>
level=INFO msg=DST id=12434 t=999000|1000999 req="PromiseGet(id=p05)" res="Response(status=20000, PromiseGet(promise=Promise(id=p05, state=RESOLVED, param=Value(headers=map[0:17.0 1:17.1 2:17.2], data=7), value=Value(headers=map[], data=5), timeout=46482, tags=map[0:0.0 1:0.1 resonate:invoke:dst resonate:timeout:true], createdOn=16000, completedOn=39000)))" err=<nil>
level=INFO msg=DST id=12417 t=998000|1000999 req="ScheduleCreate(id=s13, desc=, cron=53 * * * *, tags=map[], promiseId=s13.{{.timestamp}}, promiseTimeout=999165, promiseParam=Value(headers=map[0:23.0 1:23.1 2:23.2], data=4), promiseTags=map[])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s13, desc=, cron=53 * * * *, tags=map[], promiseId=s13.{{.timestamp}}, promiseTimeout=999165, promiseParam=Value(headers=map[0:23.0 1:23.1 2:23.2], data=4), promiseTags=map[], lastRunTime=0, nextRunTime=3180000, createdOn=999000)))" err=<nil>
level=INFO msg=DST id=12424 t=999000|1000999 req="PromiseCreate(id=p15, param=Value(headers=map[0:14.0 1:14.1], data=4), timeout=354985, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p15, state=REJECTED_CANCELED, param=Value(headers=map[], data=), value=Value(headers=map[0:1.0 1:1.1 2:1.2], data=1), timeout=84196, tags=map[0:20.0], createdOn=10000, completedOn=38000)))" err=<nil>
level=INFO msg=DST id=12430 t=999000|1000999 req="PromiseCreate(id=p00, param=Value(headers=map[], data=), timeout=506416, tags=map[])" res="Response(status=20000, PromiseCreate(promise=Promise(id=p00, state=REJECTED_CANCELED, param=Value(headers=map[0:8.0 1:8.1], data=), value=Value(headers=map[0:12.0], data=8), timeout=192581, tags=map[resonate:invoke:dst resonate:timeout:true], createdOn=1000, completedOn=40000)))" err=<nil>
level=INFO msg=DST id=12432 t=999000|1000999 req="TaskCreate(promise=PromiseCreate(id=p17, param=Value(headers=map[0:20.0], data=7), timeout=532694, tags=map[0:20.0 resonate:invoke:dst]), task=CreateTask(promiseId=p17, processId=p17, ttl=4000, timeout=532694))" res="Response(status=20000, TaskCreate(promise=Promise(id=p17, state=REJECTED_CANCELED, param=Value(headers=map[0:4.0], data=5), value=Value(headers=map[], data=6), timeout=639456, tags=map[resonate:timeout:true], createdOn=0, completedOn=7000), task=<nil>))" err=<nil>
level=INFO msg=DST id=12426 t=999000|1001999 req="ScheduleCreate(id=s10, desc=, cron=9 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=999859, promiseParam=Value(headers=map[], data=10), promiseTags=map[0:3.0])" res="Response(status=20100, ScheduleCreate(schedule=Schedule(id=s10, desc=, cron=9 * * * *, tags=map[], promiseId=s10.{{.timestamp}}, promiseTimeout=999859, promiseParam=Value(headers=map[], data=10), promiseTags=map[0:3.0], lastRunTime=0, nextRunTime=4140000, 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='29591'

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.