search

wechaty / puppet-wechat

Wechaty Puppet Provider for WeChat
https://www.npmjs.com/package/wechaty-puppet-wechat
Apache License 2.0
408 stars 68 forks source link

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

Open xiaoxintang opened 2 years ago

xiaoxintang commented 2 years ago

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 ```shell $ WECHATY_LOG=silly node yourbot.js -> PASTE YOUR FULL(DO NOT ONLY PROVIDE FRAGMENTS) LOG MESSAGES HERE ``` ![image](https://user-images.githubusercontent.com/24927686/144349160-3a943d52-80f7-49e9-9ddd-15a631573e96.png)

7. Additional context

Add any other context about the problem here.

huan commented 2 years ago

Thank you very much for reporting this problem!

I believe it's related to

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 commented 2 years ago

Could you please try the latest wechaty@1.11.22 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.

xiaoxintang commented 2 years ago

Could you please try the latest wechaty@1.11.22 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

```text 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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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(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 ```
huan commented 2 years ago

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.

MengxingLiu commented 2 years ago

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

artisth commented 2 years ago

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

huan commented 2 years ago

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]
Alexhuihui commented 2 years ago

我遇到了同样的问题

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 npm@8.3.0 to update!
npm notice