puppet-wechat icon indicating copy to clipboard operation
puppet-wechat copied to clipboard

Published 20 hours ago • verified publisher icon wechaty

Infinite loop: `dirtyPayload`/`onDirty`/`__dirtyPayloadAwait`

Open xiaoxintang opened this issue 3 years ago • 8 comments

Important：Please file the issue follow the template, or we won't be able to help you to solve the problem.

1. Versions

What is your wechaty version?

Answer:1.10.4

Which puppet are you using for wechaty? (puppeteer/padlocal/service...)

Answer: 默认，web

What is your wechaty-puppet-XXX（padchat/puppeteer/） version?

Answer:"puppeteer": "^11.0.0", wechaty-puppet-wechat:1.10.4

What is your node version? (run node --version)

Answer:v16.13.0

What os are you using

Answer: macos && debian

2. Describe the bug

image 按照例子自动接受好友请求，代码陷入死循环，一直在输出这个log

3. To Reproduce

主要代码如下，基本跟官网一致

bot.on('friendship', async (friendship) => {
  try {
    log.info('received friend event.')
    console.log(friendship.type())
    console.log(friendship)
    // const concat = friendship.contact()
    switch (friendship.type()) {
      // 1. New Friend Request
      case bot.Friendship.Type.Receive:
        await friendship.accept()
        // await concat.say(`你好，${concat.name()}`)
        // await concat.say('要加入助手群，请跟我说，小助手')
        break
        // 2. Friend Ship Confirmed
      case bot.Friendship.Type.Confirm:
        log.info('friend ship confirmed')
        break
      default:
        log.error('other')
    }
  } catch (e) {
    console.error(e)
  }
})

4. Expected behavior

Give a clear and concise description of what you expected to happen.

接受好友申请后，不要出现死循环

5. Actual behavior

If applicable, add screenshots to help explain your problem. But do not paste log screenshots here.

6. Full Output Logs

Set env WECHATY_LOG=silly in order to set log level to silly, then we can get the full log (If you dosen't set log env, log level is info as default, we cannot get the full log)

We need full log instead of log screenshot or log fragments!

Show Logs 
$ WECHATY_LOG=silly node yourbot.js

-> PASTE YOUR FULL(DO NOT ONLY PROVIDE FRAGMENTS) LOG MESSAGES HERE

image

7. Additional context

Add any other context about the problem here.

xiaoxintang avatar Dec 02 '21 02:12 xiaoxintang

Thank you very much for reporting this problem!

I believe it's related to

  • https://github.com/wechaty/puppet/issues/158

Could you please copy/paste all the related log messages as plain text, instead of an image, so that I can read it more easily and see more information?

huan avatar Dec 02 '21 03:12 huan

Could you please try the latest [email protected] to see if this problem exists?

If so, please provide all the log messages as plain text and paste them here, so that we can try to see what happends.

huan avatar Dec 02 '21 04:12 huan

Could you please try the latest [email protected] to see if this problem exists?

If so, please provide all the log messages as plain text and paste them here, so that we can try to see what happends.

the same problem happen. @huan

13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 14.143183000385761 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 13.827599998563528 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 13.485334001481533 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 13.105239000171423 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 12.53684800118208 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 12.021193999797106 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 11.47545800358057 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 10.778184000402689 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 9.036467999219894 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 6.699397001415491 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 3.5577429980039597 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.2001749984920025 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.604442998766899 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9074029996991158 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.8348830007016659 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.3326159976422787 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.4044760018587112 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.4184929989278316 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.596169002354145 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.718368999660015 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.8181589990854263 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9644120000302792 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7048279978334904 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.164862997829914 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.0899070017039776 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.4764650017023087 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.4075989983975887 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.877665001899004 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.8036609999835491 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7952079996466637 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.592969000339508 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.5927000008523464 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.4672910012304783 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9909510016441345 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.4149820022284985 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.490157999098301 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.6783680021762848 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.802420001477003 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9460119977593422 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7952610030770302 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9284969978034496 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9549570009112358 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.785911999642849 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.073777999728918 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.4346430003643036 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.607404001057148 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.638856001198292 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.2370110005140305 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.1175859980285168 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9494309984147549 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.840940997004509 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9031659997999668 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7972999997437 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.5393400005996227 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.591864001005888 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.709986999630928 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9239859990775585 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.8205049969255924 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.6321999989449978 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7058360017836094 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.6643310002982616 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.1939929984509945 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.565294001251459 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.152665998786688 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.0494079999625683 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.9222080036997795 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.649007000029087 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.8540959991514683 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.8645679987967014 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.2910940013825893 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7288160026073456 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.6524949967861176 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7010559998452663 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.2963780015707016 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.3175790011882782 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.7922050021588802 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.124568000435829 ms
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 1.6735690012574196 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET
13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 2.9076279997825623 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
^C13:52:50 SILL Contact find({"id":"@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"})
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 SILL Contact ready() isReady() true
13:52:50 VERB PuppetCacheMixin onDirty(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL Contact ready() @ Puppet<PuppetWeChat>(rich-woman) with id="@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8"
13:52:50 VERB PuppetContactMixin contactPayloadDirty(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache MISS
13:52:50 SILL PuppetWeChat contactRawPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) @ Puppet<PuppetWeChat>(rich-woman)
13:52:50 SILL PuppetWeChatBridge proxyWechaty(getContact, @ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8)
13:52:50 SILL wechaty retry-policy getRetryPolicy policy.onSuccess(): retry call ran in 3.398065999150276 ms
13:52:50 SILL PuppetWeChat contactParseRawPayload(Object.keys(payload).length=30)
13:52:50 SILL PuppetContactMixin contactPayload(@ff8d575abef4fe3a9da0f68a1f44b2e82c688b6e2c0edd87e78c043e4ef73db8) cache SET

xiaoxintang avatar Dec 02 '21 05:12 xiaoxintang

Thanks for your log, it's very clear.

However, I tried to locate the problem today but I failed.

I hope we can find more clues for this bug later.

huan avatar Dec 02 '21 12:12 huan

replicated this issue using docker container, with wechaty:next.

MengxingLiu avatar Dec 07 '21 18:12 MengxingLiu

你的来信我已收到，我会尽快查收。谢谢！

artisth avatar Dec 07 '21 18:12 artisth

Reproduced with our standard getting started ding-dong bot:

08:28:26 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin onDirty(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetContactMixin contactPayloadDirty(@@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin onDirty(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetContactMixin contactPayloadDirty(@@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin onDirty(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetContactMixin contactPayloadDirty(@@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin onDirty(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetContactMixin contactPayloadDirty(@@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)
08:28:26 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@c00ec1a89247c1ddddcc0210adccb5ad8a9fe75af1637f675cf1cf7c2e0aa28b)

<--- Last few GCs --->

[1484665:0x6070ea0] 29091275 ms: Mark-sweep 4015.1 (4127.6) -> 4001.1 (4128.4) MB, 8142.7 / 0.3 ms  (average mu = 0.177, current mu = 0.058) task scavenge might not succeed
[1484665:0x6070ea0] 29099635 ms: Mark-sweep 4017.0 (4128.4) -> 4005.3 (4132.9) MB, 7820.1 / 0.3 ms  (average mu = 0.128, current mu = 0.065) allocation failure scavenge might not succeed


<--- JS stacktrace --->

FATAL ERROR: Ineffective mark-compacts near heap limit Allocation failed - JavaScript heap out of memory
 1: 0xb17ec0 node::Abort() [node]
 2: 0xa341f4 node::FatalError(char const*, char const*) [node]
 3: 0xcfe71e v8::Utils::ReportOOMFailure(v8::internal::Isolate*, char const*, bool) [node]
 4: 0xcfea97 v8::internal::V8::FatalProcessOutOfMemory(v8::internal::Isolate*, char const*, bool) [node]
 5: 0xee8d35  [node]
 6: 0xee987c  [node]
 7: 0xef77b1 v8::internal::Heap::CollectGarbage(v8::internal::AllocationSpace, v8::internal::GarbageCollectionReason, v8::GCCallbackFlags) [node]
 8: 0xefad0c v8::internal::Heap::AllocateRawWithRetryOrFailSlowPath(int, v8::internal::AllocationType, v8::internal::AllocationOrigin, v8::internal::AllocationAlignment) [node]
 9: 0xebebb5 v8::internal::Factory::AllocateRaw(int, v8::internal::AllocationType, v8::internal::AllocationAlignment) [node]
10: 0xeb7724 v8::internal::FactoryBase<v8::internal::Factory>::AllocateRawWithImmortalMap(int, v8::internal::AllocationType, v8::internal::Map, v8::internal::AllocationAlignment) [node]
11: 0xeb99d1 v8::internal::FactoryBase<v8::internal::Factory>::NewRawTwoByteString(int, v8::internal::AllocationType) [node]
12: 0x12caa8c v8::internal::IncrementalStringBuilder::Extend() [node]
13: 0xfe814e v8::internal::JsonStringifier::SerializeString(v8::internal::Handle<v8::internal::String>) [node]
14: 0xfea971 v8::internal::JsonStringifier::Result v8::internal::JsonStringifier::Serialize_<true>(v8::internal::Handle<v8::internal::Object>, bool, v8::internal::Handle<v8::internal::Object>) [node]
15: 0xfeb812 v8::internal::JsonStringifier::Result v8::internal::JsonStringifier::Serialize_<true>(v8::internal::Handle<v8::internal::Object>, bool, v8::internal::Handle<v8::internal::Object>) [node]
16: 0xfee8f1 v8::internal::JsonStringifier::Result v8::internal::JsonStringifier::Serialize_<false>(v8::internal::Handle<v8::internal::Object>, bool, v8::internal::Handle<v8::internal::Object>) [node]
17: 0xff025f v8::internal::JsonStringify(v8::internal::Isolate*, v8::internal::Handle<v8::internal::Object>, v8::internal::Handle<v8::internal::Object>, v8::internal::Handle<v8::internal::Object>) [node]
18: 0xda3c95 v8::internal::Builtin_JsonStringify(int, unsigned long*, v8::internal::Isolate*) [node]
19: 0x16148d9  [node]

huan avatar Dec 14 '21 05:12 huan

我遇到了同样的问题

03:37:33 VERB PuppetContactMixin contactPayloadDirty(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin onDirty(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetContactMixin contactPayloadDirty(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin onDirty(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetContactMixin contactPayloadDirty(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin onDirty(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetContactMixin contactPayloadDirty(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin onDirty(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetContactMixin contactPayloadDirty(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin onDirty(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetContactMixin contactPayloadDirty(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin __dirtyPayloadAwait(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetCacheMixin dirtyPayload(Contact<2>, @@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetWeChat roomRawPayloadParser([object Object])
03:37:33 VERB PuppetWeChat roommemberList(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)
03:37:33 VERB PuppetWeChat roomRawPayload(@@790f7a5afe38091f79a475231ecc07d323735cd710bcbf3eda5f0b7b822190f5)

<--- Last few GCs --->

[46:0x649d110] 176418927 ms: Mark-sweep 2017.6 (2082.7) -> 2001.8 (2086.9) MB, 2589.2 / 0.2 ms  (average mu = 0.223, current mu = 0.140) allocation failure scavenge might not succeed
[46:0x649d110] 176421740 ms: Mark-sweep 2017.6 (2086.9) -> 2005.4 (2086.9) MB, 2675.7 / 0.3 ms  (average mu = 0.144, current mu = 0.049) allocation failure scavenge might not succeed


<--- JS stacktrace --->

FATAL ERROR: Ineffective mark-compacts near heap limit Allocation failed - JavaScript heap out of memory
 1: 0xb02ec0 node::Abort() [node]
 2: 0xa181fb node::FatalError(char const*, char const*) [node]
 3: 0xced88e v8::Utils::ReportOOMFailure(v8::internal::Isolate*, char const*, bool) [node]
 4: 0xcedc07 v8::internal::V8::FatalProcessOutOfMemory(v8::internal::Isolate*, char const*, bool) [node]
 5: 0xea5ea5  [node]
 6: 0xea6986  [node]
 7: 0xeb48be  [node]
 8: 0xeb5300 v8::internal::Heap::CollectGarbage(v8::internal::AllocationSpace, v8::internal::GarbageCollectionReason, v8::GCCallbackFlags) [node]
 9: 0xf18844 v8::internal::ScavengeJob::Task::RunInternal() [node]
10: 0xdabcfb non-virtual thunk to v8::internal::CancelableTask::Run() [node]
11: 0xb707a4 node::PerIsolatePlatformData::RunForegroundTask(std::unique_ptr<v8::Task, std::default_delete<v8::Task> >) [node]
12: 0xb72609 node::PerIsolatePlatformData::FlushForegroundTasksInternal() [node]
13: 0x155a396  [node]
14: 0x156c804  [node]
15: 0x155acc8 uv_run [node]
16: 0xa3ffe5 node::SpinEventLoop(node::Environment*) [node]
17: 0xb45056 node::NodeMainInstance::Run(node::EnvSerializeInfo const*) [node]
18: 0xac67e2 node::Start(int, char**) [node]
19: 0x7fd1ec858d0a __libc_start_main [/lib/x86_64-linux-gnu/libc.so.6]
20: 0xa3bfbc  [node]
Aborted (core dumped)
npm notice 
npm notice New minor version of npm available! 8.1.0 -> 8.3.0
npm notice Changelog: https://github.com/npm/cli/releases/tag/v8.3.0
npm notice Run npm install -g [email protected] to update!
npm notice

Alexhuihui avatar Dec 16 '21 06:12 Alexhuihui