room.has() 方法返回结果不准确

[KAWATAAAAAA]

1. Versions

• What is your wechaty version?
  Answer: "@juzi/wechaty": "1.0.47"

• What is your wechaty-puppet-padplus version?
  Answer: "@juzi/wechaty-puppet-padplus": "1.2.4",

• What is your node version? (run node --version)
  Answer: v16.17.0

• What os are you using
  Answer: Mac Os

2. Describe the bug

room.has() 方法返回结果不准确

3. To Reproduce

步骤

0. WECHATY_LOG = silly

1. 本地启动node服务，使用token登录bot

2. 新建群，然后再拉机器人进去

3. 重新命名群为 777

4. 调用 room.has(bot.currentUser) 返回 true， 且能看到群名称更改为777

5. 关闭本地服务，保持ipad端登录（未在手机上点退出登录，此时padplus远程应该是登录了的）

6. 群主在777的群中踢掉 bot

7. 重新启动本地node服务，本地存在 memory-card.json ，自动登录成功

8. 直接查询 777 的群， 调用 room.has(bot.currentUser) 依然返回 true

4. Expected behavior

调用 room.has(bot.currentUser) 返回 false

5. Actual behavior

调用 room.has(bot.currentUser) 返回 true

6. Full Output Logs

无可利用的详细log

Show Logs

7. Additional context

  const instance = _getRobot(ctx, params);
  let room = null
	room = instance.Room.load(roomId)
  await room.sync()
  let member = await room.memberAll()  // 
  let has = await room.has(instance.currentUser) // 返回结果错误

步骤遵循文档中的先 load、再sync，memberAll 与 has 返回的都是错误的结果

[KAWATAAAAAA]

补充一点，这个操作试了很多次，稳定复现，且控制台会输出如下数据：

15:20:25 SILL Puppet Config: WECHATY_LOG set level to silly
15:20:25 INFO Config registering process.on("unhandledRejection") for development/debug
15:20:25 VERB Config constructor()
15:20:25 VERB PoolifyMixin poolifyMixin(EventEmitter)
15:20:25 VERB WechatifyMixin wechatifyMixin(AbstractPoolifyMixin)
15:20:25 VERB ValidationMixin validationMixin(ContactMixin)
15:20:25 VERB ValidationMixin validationMixin(ContactImplBase)
15:20:25 VERB PoolifyMixin poolifyMixin(ContactImpl)
15:20:25 VERB ValidationMixin validationMixin(ContactSelfMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(FavoriteMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EventEmitter)
15:20:25 VERB ValidationMixin validationMixin(FriendshipMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(ImageMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(LocationMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(PostMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EventEmitter)
15:20:25 VERB ValidationMixin validationMixin(MessageMixin)
15:20:25 VERB ValidationMixin validationMixin(MessageImplBase)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(MiniProgramMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(MomentMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(MoneyMixin)
15:20:25 VERB PoolifyMixin poolifyMixin(EventEmitter)
15:20:25 VERB WechatifyMixin wechatifyMixin(AbstractPoolifyMixin)
15:20:25 VERB ValidationMixin validationMixin(RoomMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(RoomInvitationMixin)
15:20:25 VERB PoolifyMixin poolifyMixin(Object)
15:20:25 VERB WechatifyMixin wechatifyMixin(AbstractPoolifyMixin)
15:20:25 VERB ValidationMixin validationMixin(TagMixin)
15:20:25 VERB ValidationMixin validationMixin(TagImplBase)
15:20:25 VERB PoolifyMixin poolifyMixin(Object)
15:20:25 VERB WechatifyMixin wechatifyMixin(AbstractPoolifyMixin)
15:20:25 VERB ValidationMixin validationMixin(TagGroupMixin)
15:20:25 VERB ValidationMixin validationMixin(TagGroupImplBase)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(UrlLinkMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(DelayMixin)
15:20:25 VERB WechatifyMixin wechatifyMixin(EmptyBase)
15:20:25 VERB ValidationMixin validationMixin(ChannelMixin)
15:20:25 VERB WechatyGErrorMixin gErrorMixin(WechatySkeleton)
15:20:25 VERB WechatifyUserModuleMixin wechatifyUserModuleMixin(GErrorMixin)
15:20:25 VERB WechatyIoMixin ioMixin(WechatifyUserModuleMixin)
15:20:25 VERB WechatyPuppetMixin puppetMixin(IoMixin)
15:20:26 VERB WechatyLoginMixin loginMixin(PuppetMixin)
15:20:26 VERB WechatyMiscMixin miscMixin(LoginMixin)
15:20:26 VERB WechatyPluginMixin pluginMixin(MiscMixin)
15:20:26 VERB ValidationMixin validationMixin(WechatyBase)
15:20:26 VERB ValidationMixin validationMixin(WechatyImplBase)
15:20:26 VERB wechaty-puppet-service monkeyPatchMetadataFromHttp2Headers()
15:20:26 SILL Config PADPLUS_LOG set level to silly
15:20:27 SILL Puppet Config: WECHATY_LOG set level to silly
15:20:27 VERB Wechaty init() Raven disabled (import("raven") failed)

15:20:27 VERB PuppetSkeleton constructor({"token":"puppet_padplus_803f4f5edcc8f6a7","endpoint":"52.82.24.73:50666","tls":{"disable":true}})
15:20:27 VERB PuppetMemoryMixin constructor()
15:20:27 VERB MemoryCard constructor(undefined)
15:20:27 VERB PuppetLoginMixin constructor()
15:20:27 VERB PuppetCacheMixin constructor()
15:20:27 VERB PuppetCacheAgent constructor()
15:20:27 VERB PuppetContactMixin constructor()
15:20:27 VERB PuppetRoomMemberMixin constructor()
15:20:27 VERB PuppetRoomMixin constructor()
15:20:27 VERB PuppetFriendshipMixin constructor()
15:20:27 VERB PuppetTagMixin constructor()
15:20:27 VERB PuppetRoomInvitationMixin constructor()
15:20:27 VERB PuppetMessageMixin constructor()
15:20:27 VERB PuppetMiscMixin constructor()
15:20:27 VERB ServiceCtl<PuppetServiceMixin> constructor()
15:20:27 VERB StateSwitch constructor(PuppetServiceMixin, "{"log":{"enableTimestamp":true,"logLevel":5,"prefixFilter":{}}}")
15:20:27 VERB StateSwitch constructor(PuppetServiceMixinReset, "{"log":{"enableTimestamp":true,"logLevel":5,"prefixFilter":{}}}")
15:20:27 VERB PuppetServiceMixin constructor() #0
15:20:27 VERB PuppetWatchdogAgent constructor(337d9bb4-6e3c-4498-8628-cc25a48b2ad2)
15:20:27 VERB PuppetWatchdogAgent constructor() timeout 60 seconds
15:20:27 VERB Watchdog <Puppet>: constructor(name=Puppet, defaultTimeout=60000)
15:20:27 VERB ReadyMixin constructor()
15:20:27 VERB PuppetPostMixin constructor()
15:20:27 VERB PuppetSayableMixin constructor()
15:20:27 VERB TapMixin constructor()
15:20:27 VERB PuppetMomentMixin constructor()
15:20:27 VERB Puppet constructor({"token":"puppet_padplus_803f4f5edcc8f6a7","endpoint":"52.82.24.73:50666","tls":{"disable":true}})
15:20:27 VERB PadplusManager constructor()
15:20:27 VERB WechatyBuilder singleton()
15:20:27 VERB WechatyBuilder newInstance()
15:20:27 VERB WechatyLoginMixin constructor()
15:20:27 VERB WechatyPuppetMixin construct()
15:20:27 VERB WechatyIoMixin constructor()
15:20:27 VERB WechatifyUserModuleMixin constructor()
15:20:27 VERB WechatySkeleton constructor()
15:20:27 VERB StateSwitch constructor(WechatyReady, "{"log":{"enableTimestamp":true,"logLevel":5,"prefixFilter":{}}}")
15:20:27 VERB ServiceCtl<Wechaty> constructor()
15:20:27 VERB StateSwitch constructor(Wechaty, "{"log":{"enableTimestamp":true,"logLevel":5,"prefixFilter":{}}}")
15:20:27 VERB StateSwitch constructor(WechatyReset, "{"log":{"enableTimestamp":true,"logLevel":5,"prefixFilter":{}}}")
15:20:27 VERB Wechaty constructor()
15:20:27 VERB WechatySkeleton on(logout, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(message, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(friendship, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(room-invite, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(room-join, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(room-topic, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(room-leave, listener) registering... listenerCount: 0
15:20:27 VERB WechatySkeleton on(error, listener) registering... listenerCount: 0
启动机器人...
15:20:27 VERB Wechaty start()
15:20:27 VERB WechatyLoginMixin init()
15:20:27 VERB WechatyPuppetMixin init()
15:20:27 VERB WechatifyUserModuleMixin init()
15:20:27 VERB WechatySkeleton init()
15:20:27 VERB MemoryCard constructor("./remember-me/puppet_padplus_803f4f5edcc8f6a7")
15:20:27 VERB MemoryCard load() from storage: N/A
15:20:27 VERB MemoryCard getStorage() for storage type: N/A
15:20:27 VERB getStorage name: ./remember-me/puppet_padplus_803f4f5edcc8f6a7, options: {"type":"file"}
15:20:27 VERB WechatySkeleton on(login, listener) registering... listenerCount: 0
====唤醒开始====
15:20:27 VERB StorageFile constructor(./remember-me/puppet_padplus_803f4f5edcc8f6a7, ...)
15:20:27 VERB StorageBackend constructor(./remember-me/puppet_padplus_803f4f5edcc8f6a7, { type: file })
15:20:27 VERB StorageFile load() from /Users/wuyuwei/MyWork/wechaty-robot/remember-me/puppet_padplus_803f4f5edcc8f6a7.memory-card.json
15:20:27 VERB WechatifyUserModuleMixin init() initializing Wechaty User Module (WUM) ...
15:20:27 VERB WechatifyMixin wechatifyUserModule(ContactImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(ContactImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(ContactSelfImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(ContactSelfImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(DelayImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(DelayImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(FriendshipImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(FriendshipImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(ImageImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(ImageImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(LocationImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(LocationImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(MessageImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(MessageImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(MiniProgramImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(MiniProgramImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(PostImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(PostImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(RoomImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(RoomImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(RoomInvitationImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(RoomInvitationImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(TagImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(TagImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(TagGroupImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(TagGroupImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(UrlLinkImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(UrlLinkImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(ChannelImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(ChannelImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyMixin wechatifyUserModule(MomentImpl)
15:20:27 VERB WechatifyMixin wechatifyUserModule(MomentImpl)(Wechaty#b1b32f1b-da2d-4428-a40b-191c0643aaaa<Puppet<PuppetPadplus>(NONAME)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB WechatifyUserModuleMixin init() initializing Wechaty User Module (WUM) ... done
15:20:27 VERB WechatyPuppetMixin init() instanciating puppet instance ...
15:20:27 VERB Puppet resolvePuppet({puppet: Puppet<PuppetPadplus>(NONAME), puppetOptions: {}})
15:20:27 VERB WechatyPuppetMixin init() instanciating puppet instance ... done
15:20:27 VERB WechatyPuppetMixin init() setting memory ...
15:20:27 VERB MemoryCard multiplex(puppet)
15:20:27 VERB MemoryCard static multiplex(MemoryCard<./remember-me/puppet_padplus_803f4f5edcc8f6a7>, puppet)
15:20:27 VERB MemoryCard constructor({"name":"./remember-me/puppet_padplus_803f4f5edcc8f6a7","multiplex":{"name":"puppet","parent":{"name":"./remember-me/puppet_padplus_803f4f5edcc8f6a7","payload":{"\rpuppet\nWECHATY_PUPPET_PADPLUS":{"qrcodeId":"","uin":"1103245599","userName":"wxid_00j4zrd47usu12"}},"storage":{"name":"./remember-me/puppet_padplus_803f4f5edcc8f6a7","options":{"type":"file"},"absFileName":"/Users/wuyuwei/MyWork/wechaty-robot/remember-me/puppet_padplus_803f4f5edcc8f6a7.memory-card.json"},"multiplexNameList":[],"options":{"name":"./remember-me/puppet_padplus_803f4f5edcc8f6a7"}}}})
15:20:27 VERB PuppetMemoryMixin setMemory(./remember-me/puppet_padplus_803f4f5edcc8f6a7)
15:20:27 VERB WechatyPuppetMixin init() setting memory ... done
15:20:27 VERB WechatyPuppetMixin init() setting up events ...
15:20:27 VERB WechatyPuppetMixin __setupPuppetEvents(Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7))
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(friendship) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(login) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(logout) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(message) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(post) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(room-invite) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(room-join) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(room-leave) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(room-topic) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(room-announce) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(scan) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(tag) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(tag-group) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(post-comment) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(post-tap) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(dirty) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(dong) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(error) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(heartbeat) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(ready) (listenerCount:0) registering...
15:20:27 VERB PuppetMixin __setupPuppetEvents() puppet.on(reset) (listenerCount:0) registering...
15:20:27 VERB WechatyPuppetMixin __setupPuppetEvents() ... done
15:20:27 VERB WechatyPuppetMixin init() setting up events ... done
15:20:27 VERB WechatyPuppetMixin init() emitting "puppet" event ...
15:20:27 VERB WechatyPuppetMixin init() emitting "puppet" event ... done
15:20:27 VERB ServiceCtl<Wechaty> start()
15:20:27 SILL StateSwitch <Wechaty> active() is false
15:20:27 SILL StateSwitch <Wechaty> inactive() is true
15:20:27 SILL StateSwitch <Wechaty> active() is false
15:20:27 VERB StateSwitch <Wechaty> active(pending) <- (false)
15:20:27 VERB ServiceCtl<Wechaty> start() super.start() ...
15:20:27 VERB WechatyPuppetMixin start()
15:20:27 VERB WechatyPuppetMixin start() super.start() ...
15:20:27 VERB WechatyIoMixin start()
15:20:27 VERB WechatySkeleton start()
15:20:27 VERB WechatyLoginMixin init()
15:20:27 VERB WechatyPuppetMixin init()
15:20:27 VERB WechatifyUserModuleMixin init()
15:20:27 VERB WechatySkeleton init()
15:20:27 VERB WechatifyUserModuleMixin init() Wechaty User Module (WUM)s have already wechatified: skip
15:20:27 VERB WechatyPuppetMixin init() skipped because this puppet has already been inited before.
15:20:27 VERB WechatyPuppetMixin start() super.start() ... done
15:20:27 SILL StateSwitch <WechatyReady> active() is false
15:20:27 VERB WechatyPuppetMixin start() starting puppet ...
15:20:27 VERB ReadyMixin start()
15:20:27 VERB PuppetServiceMixin start()
15:20:27 VERB ServiceCtl<PuppetServiceMixin> start()
15:20:27 SILL StateSwitch <PuppetServiceMixin> active() is false
15:20:27 SILL StateSwitch <PuppetServiceMixin> inactive() is true
15:20:27 SILL StateSwitch <PuppetServiceMixin> active() is false
15:20:27 VERB StateSwitch <PuppetServiceMixin> active(pending) <- (false)
15:20:27 VERB ServiceCtl<PuppetServiceMixin> start() super.start() ...
15:20:27 VERB PuppetCacheMixin start()
15:20:27 VERB PuppetLoginMixin start()
15:20:27 VERB PuppetMemoryMixin start()
15:20:27 VERB MemoryCard load() from storage: N/A
15:20:27 VERB MemoryCard load() should not be called on a multiplex MemoryCard. NOOP
15:20:27 VERB PuppetSkeleton start()
15:20:27 VERB PuppetCacheAgent start()
15:20:27 VERB PuppetCacheMixin start() "dirty" event listener added
15:20:27 VERB ServiceCtl<PuppetServiceMixin> start() super.start() ... done
15:20:27 VERB ServiceCtl<PuppetServiceMixin> start() this.onStart() ...
15:20:27 INFO PuppetPadplus onStart()
15:20:27 VERB PadplusManager on(scan, function) registered
15:20:27 VERB PadplusManager on(login, function) registered
15:20:27 VERB PadplusManager on(message, function) registered
15:20:27 VERB PadplusManager on(ready, function) registered
15:20:27 VERB PadplusManager on(reset, function) registered
15:20:27 VERB PadplusManager on(heartbeat, function) registered
15:20:27 VERB PadplusManager on(logout, function) registered
15:20:27 VERB PadplusManager on(room-leave, function) registered
15:20:27 VERB PadplusManager on(error, function) registered
15:20:27 SILL PadplusManager start()
15:20:27 SILL GRPC_GATEWAY init()
15:20:27 SILL GRPC_GATEWAY initSelf()
15:20:27 SILL GRPC_GATEWAY initGrpcGateway()
15:20:27 VERB MemoryCard <puppet> get(WECHATY_PUPPET_PADPLUS)
15:20:28 SILL PadplusUser initInstance()
15:20:28 SILL RequestClient request()
15:20:28 SILL DedupeApi dedupe() no need to dedupe api INIT.
15:20:28 SILL GRPC_GATEWAY GRPC Request ApiType: INIT
15:20:28 SILL ==P==A==D==P==L==U==S==<GRPC DATA>==P==A==D==P==L==U==S== 
15:20:28 SILL GRPC_GATEWAY responseType: AUTO_LOGIN, data : {"uin":1103245599,"online":true,"wechatUser":{"headImgUrl":"http://wx.qlogo.cn/mmhead/ver_1/OxllzjTf55qDecZiaNaEyvjReD39I6CyKTkxic3kp568b4F4CZo0XGrdX8onvbhFA3ibTicXBJ8ItibSdxXz3iaxSs9teVxdqNz2h3Z6Z4lEaHtlk/0","nickName":"yuwei","uin":1103245599,"userName":"wxid_00j4zrd47usu12","alias":"","verifyFlag":0}}
15:20:28 SILL ==P==A==D==P==L==U==S==<GRPC DATA>==P==A==D==P==L==U==S==
 
15:20:28 SILL GRPC_GATEWAY throttleQueue emit heartbeat.
15:20:28 SILL PadplusManager setContactAndRoomData()
15:20:28 VERB PadplusManager setContactAndRoomData() can not proceed due to no cache.
15:20:28 SILL PadplusManager user name : {
  uin: 1103245599,
  online: true,
  wechatUser: {
    headImgUrl: 'http://wx.qlogo.cn/mmhead/ver_1/OxllzjTf55qDecZiaNaEyvjReD39I6CyKTkxic3kp568b4F4CZo0XGrdX8onvbhFA3ibTicXBJ8ItibSdxXz3iaxSs9teVxdqNz2h3Z6Z4lEaHtlk/0',
    nickName: 'yuwei',
    uin: 1103245599,
    userName: 'wxid_00j4zrd47usu12',
    alias: '',
    verifyFlag: 0
  }
}
15:20:28 VERB PadplusManager init cache manager
15:20:28 VERB CacheManager init()
15:20:28 VERB CacheManager initCache(wxid_00j4zrd47usu12,{"baseDir":"/Users/wuyuwei/MyWork/wechaty-robot","type":"flashStore"})
15:20:28 VERB CacheManager initCache() inited [object Promise] Contacts,  cachedir="/Users/wuyuwei/.wechaty/puppet-padplus-cache/flash-store-v0.14/"
15:20:28 VERB PuppetLoginMixin login(wxid_00j4zrd47usu12)
15:20:28 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:20:28 SILL PuppetLoginMixin get currentUserId()
15:20:28 VERB PoolifyMixin get pool() init pool
15:20:28 SILL Contact constructor(wxid_00j4zrd47usu12)
15:20:28 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:20:28 SILL PuppetContactMixin contactPayload(wxid_00j4zrd47usu12) cache MISS
15:20:28 SILL PadplusManager contactSelfInfo()
15:20:28 VERB PadplusContact getContactSelfInfo()
15:20:28 SILL RequestClient request()
15:20:28 SILL DedupeApi dedupe() no need to dedupe api GET_CONTACT_SELF_INFO.
15:20:28 SILL GRPC_GATEWAY GRPC Request ApiType: GET_CONTACT_SELF_INFO
15:20:28 SILL PadplusManager syncContacts()
15:20:28 VERB PadplusContact syncContacts()
15:20:28 SILL RequestClient request()
15:20:28 SILL DedupeApi dedupe() no need to dedupe api SYNC_CONTACT.
15:20:28 SILL GRPC_GATEWAY GRPC Request ApiType: SYNC_CONTACT
15:20:28 SILL PuppetPadplus contactRawPayloadParser()
15:20:28 SILL PuppetContactMixin contactPayload(wxid_00j4zrd47usu12) cache SET
15:20:28 SILL PuppetLoginMixin get currentUserId()
====唤醒完成====
====获取未推送完成的数据====
[2023-03-27T15:20:28.806+08:00] [DEBUG] [85475] [] [wuyuweideMacBook-Pro.local] [172.21.144.49] [wechaty-robot] [] [] [] [] [[
  "Executing (default): SELECT `id`, `taskId`, `roomId`, `topic`, `wxid`, `expires`, `messages`, `delivered`, `experienced`, `createdAt`, `updatedAt` FROM `task` AS `task` WHERE `task`.`delivered` = false AND `task`.`expires` > '2023-03-27 07:20:28' AND `task`.`experienced` = false;",
  {
    plain: false,
    raw: false,
    logging: [Function: logging],
    showWarnings: false,
    where: "`task`.`delivered` = false AND `task`.`expires` > '2023-03-27 07:20:28' AND `task`.`experienced` = false",
    hooks: true,
    rejectOnEmpty: false,
    originalAttributes: [
      'id',          'taskId',
      'roomId',      'topic',
      'wxid',        'expires',
      'messages',    'delivered',
      'experienced', 'createdAt',
      'updatedAt'
    ],
    attributes: [
      'id',          'taskId',
      'roomId',      'topic',
      'wxid',        'expires',
      'messages',    'delivered',
      'experienced', 'createdAt',
      'updatedAt'
    ],
    tableNames: [ 'task' ],
    type: 'SELECT',
    model: task
  }
]] ## '' 

15:20:29 SILL ==P==A==D==P==L==U==S==<GRPC DATA>==P==A==D==P==L==U==S== 
15:20:29 SILL GRPC_GATEWAY responseType: CONTACT_SELF_INFO_GET, data : {"status":0,"message":"","loginer":"puppet_padplus_803f4f5edcc8f6a7","uin":"1103245599","userName":"wxid_00j4zrd47usu12","queueName":"queue.getContactSelfInfo.changsha","bindEmail":"","country":"","smallHeadImg":"https://wx.qlogo.cn/mmhead/ver_1/OxllzjTf55qDecZiaNaEyvjReD39I6CyKTkxic3kp568b4F4CZo0XGrdX8onvbhFA3ibTicXBJ8ItibSdxXz3iaxSs9teVxdqNz2h3Z6Z4lEaHtlk/132","bigHeadImg":"https://wx.qlogo.cn/mmhead/ver_1/OxllzjTf55qDecZiaNaEyvjReD39I6CyKTkxic3kp568b4F4CZo0XGrdX8onvbhFA3ibTicXBJ8ItibSdxXz3iaxSs9teVxdqNz2h3Z6Z4lEaHtlk/0","city":"","signature":"","nickName":"yuwei","sex":0,"bindQQ":0,"bindMobile":"15116352237","snsBGImg":"","province":"","alias":""}
15:20:29 SILL ==P==A==D==P==L==U==S==<GRPC DATA>==P==A==D==P==L==U==S==
 
15:20:29 SILL ==P==A==D==P==L==U==S==<GRPC DATA>==P==A==D==P==L==U==S== 
15:20:29 SILL GRPC_GATEWAY responseType: MESSAGE_RECEIVE, data : {"Status":4,"CreateTime":1679901610000,"FileName":"<msgsource>\n\t<signature>v1_IYYvXEnd</signature>\n\t<tmp_node>\n\t\t<publisher-id>&lt;![CDATA[]]&gt;</publisher-id>\n\t</tmp_node>\n</msgsource>\n","NewMsgId":"781378694909067672","msgSourceCd":2,"MsgType":10000,"ToUserName":"wxid_00j4zrd47usu12","ImgStatus":1,"L1MsgType":5,"wechatUserName":"wxid_00j4zrd47usu12","MsgId":"781378694909067672","MsgSource":"<msgsource>\n\t<signature>v1_IYYvXEnd</signature>\n\t<tmp_node>\n\t\t<publisher-id>&lt;![CDATA[]]&gt;</publisher-id>\n\t</tmp_node>\n</msgsource>\n","PushContent":"","ImgBuf":"","Content":"你被\"宇威ya\"移出群聊","msgKey":"791812533","FromUserName":"34404981130@chatroom","Uin":1103245599}
15:20:29 SILL ==P==A==D==P==L==U==S==<GRPC DATA>==P==A==D==P==L==U==S==
 
15:20:29 SILL PuppetPadplus roomRawPayload(34404981130@chatroom)
15:20:29 SILL PuppetPadplus onRoomJoinEvent(781378694909067672)
15:20:29 SILL PuppetPadplus onRoomLeaveEvent(781378694909067672)
15:20:29 SILL PuppetPadplus onRoomTopicEvent(781378694909067672)
15:20:29 SILL PuppetPadplus onPadplusMessageFriendshipEvent({id=781378694909067672})
15:20:29 SILL PuppetPadplus receive room-leave event : {
  leaverIdList: [ Symbol(YOU@wechaty-puppet) ],
  removerId: '宇威ya',
  roomId: '34404981130@chatroom',
  timestamp: 1679901610000
}
15:20:29 VERB PuppetRoomMemberMixin roomMemberSearch(34404981130@chatroom, undefined)
15:20:29 SILL PuppetLoginMixin get currentUserId()
15:20:29 VERB PuppetRoomMemberMixin roomMemberSearch(34404981130@chatroom, "宇威ya")
15:20:29 VERB PuppetRoomMemberMixin roomMemberSearch(34404981130@chatroom, {"roomAlias":"宇威ya"})
15:20:29 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=sugarorz)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, sugarorz)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=wxid_mi88feubut7822)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, wxid_mi88feubut7822)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=q1195862472)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, q1195862472)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=wxid_00j4zrd47usu12)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, wxid_00j4zrd47usu12)
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 VERB PuppetRoomMemberMixin roomMemberSearch(34404981130@chatroom, {"name":"宇威ya"})
15:20:29 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:29 VERB PuppetContactMixin contactSearch(query={"name":"宇威ya"}, idList.length = 4)
15:20:29 SILL PuppetContactMixin contactSearch() searchIdList.length = 4
15:20:29 VERB PuppetContactMixin contactQueryFilterFactory({"name":"宇威ya"})
15:20:29 SILL PuppetContactMixin contactPayload(sugarorz) cache MISS
15:20:29 SILL PuppetContactMixin contactPayload(wxid_mi88feubut7822) cache MISS
15:20:29 SILL PuppetContactMixin contactPayload(q1195862472) cache MISS
15:20:29 SILL PuppetPadplus contactRawPayloadParser()
15:20:29 SILL PuppetContactMixin contactPayload(sugarorz) cache SET
15:20:29 SILL PuppetPadplus contactRawPayloadParser()
15:20:29 SILL PuppetContactMixin contactPayload(wxid_mi88feubut7822) cache SET
15:20:29 SILL PuppetPadplus contactRawPayloadParser()
15:20:29 SILL PuppetContactMixin contactPayload(q1195862472) cache SET
15:20:29 SILL PuppetContactMixin contactSearch() searchContactPayloadList.length = 1
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=sugarorz)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, sugarorz)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=wxid_mi88feubut7822)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, wxid_mi88feubut7822)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=q1195862472)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, q1195862472)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=wxid_00j4zrd47usu12)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, wxid_00j4zrd47usu12)
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 VERB PuppetRoomMemberMixin roomMemberSearch(34404981130@chatroom, {"contactAlias":"宇威ya"})
15:20:29 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:29 VERB PuppetContactMixin contactSearch(query={"alias":"宇威ya"}, idList.length = 4)
15:20:29 SILL PuppetContactMixin contactSearch() searchIdList.length = 4
15:20:29 VERB PuppetContactMixin contactQueryFilterFactory({"alias":"宇威ya"})
15:20:29 SILL PuppetContactMixin contactSearch() searchContactPayloadList.length = 0
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=sugarorz)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, sugarorz)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=wxid_mi88feubut7822)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, wxid_mi88feubut7822)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=q1195862472)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, q1195862472)
15:20:29 VERB PuppetRoomMemberMixin roomMemberPayload(roomId=34404981130@chatroom, memberId=wxid_00j4zrd47usu12)
15:20:29 SILL PuppetPadplus roomMemberRawPayload(34404981130@chatroom, wxid_00j4zrd47usu12)
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberRawPayloadParser()
15:20:29 SILL PuppetRoomMemberMixin roomMemberPayload(34404981130@chatroom) cache SET
15:20:29 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:30 SILL PadplusUser init success
15:20:30 VERB MemoryCard <puppet> get(WECHATY_PUPPET_PADPLUS)
15:20:30 VERB ServiceCtl<PuppetServiceMixin> start() this.onStart() ... done
15:20:30 SILL StateSwitch <PuppetServiceMixin> active() is pending
15:20:30 VERB StateSwitch <PuppetServiceMixin> active(true) <- (pending)
15:20:30 VERB ServiceCtl<PuppetServiceMixin> start() ... done
15:20:30 VERB PuppetWatchdogAgent start() "heartbeat" event listener added
15:20:30 VERB Watchdog <Puppet> on(reset, listener) registered.
15:20:30 VERB PuppetWatchdogAgent start() "reset" event listener added
15:20:30 VERB WechatyPuppetMixin start() starting puppet ... done
15:20:30 VERB ServiceCtl<Wechaty> start() super.start() ... done
15:20:30 VERB ServiceCtl<Wechaty> start() this.onStart() ...
15:20:30 VERB Wechaty onStart()
15:20:30 VERB Wechaty <Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7)>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) onStart() v1.0.47 is starting...
15:20:30 VERB Wechaty id: b1b32f1b-da2d-4428-a40b-191c0643aaaa
15:20:30 VERB Wechaty onStart() ... done
15:20:30 VERB ServiceCtl<Wechaty> start() this.onStart() ... done
15:20:30 SILL StateSwitch <Wechaty> active() is pending
15:20:30 VERB StateSwitch <Wechaty> active(true) <- (pending)
15:20:30 VERB ServiceCtl<Wechaty> start() ... done
15:20:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:31 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:32 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:33 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:34 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:35 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:36 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:37 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:38 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:39 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:40 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:41 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:42 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:43 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:44 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:45 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:46 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:48 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:49 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:50 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:51 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:52 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:53 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:54 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:55 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:56 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:57 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:58 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:20:59 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:00 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:00 SILL GRPC_GATEWAY keepHeartbeat()
15:21:00 SILL GRPC_GATEWAY GRPC Request ApiType: HEARTBEAT
15:21:00 SILL GRPC_GATEWAY throttleQueue emit heartbeat.
15:21:00 VERB Watchdog <Puppet> feed({"data":"8d7ca239-7998-4230-b556-6ed4f6db6e52"})
15:21:00 VERB Watchdog <Puppet> timerLeft() first feed, left=0
15:21:00 VERB Watchdog <Puppet> stopTimer()
15:21:00 VERB Watchdog <Puppet> stopTimer() first run(or after sleep)
15:21:00 VERB Watchdog <Puppet> startTimer()
15:21:00 SILL PadplusManager setContactAndRoomData()
15:21:00 SILL PadplusManager setContactAndRoomData() initialize contact and room data.
15:21:01 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:02 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:03 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:04 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:05 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:06 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:07 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:08 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:09 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:10 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:11 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:12 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:13 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:14 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:15 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:16 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:17 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:18 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:19 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:20 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:21 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:22 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:23 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:24 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:25 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:26 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:27 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:28 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:29 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL deduplicateRoomLeaveEvent({"removeeIdList":["wxid_00j4zrd47usu12"],"removerId":"q1195862472","roomId":"34404981130@chatroom","timestamp":1679901610000}) 
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 VERB PoolifyMixin get pool() init pool
15:21:30 SILL Room constructor(34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 VERB PuppetRoomMixin roomPayload(34404981130@chatroom)
15:21:30 SILL PuppetRoomMixin roomPayloadCache(34404981130@chatroom) cache MISS
15:21:30 SILL PuppetPadplus roomRawPayload(34404981130@chatroom)
15:21:30 SILL PuppetPadplus roomRawPayloadParser()
15:21:30 SILL PuppetRoomMixin roomPayload(34404981130@chatroom) cache SET
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 VERB PoolifyMixin get pool() init pool
15:21:30 SILL Contact constructor(sugarorz)
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact constructor(wxid_mi88feubut7822)
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact constructor(q1195862472)
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 VERB PuppetRoomMixin roomPayloadDirty(34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin __dirtyPayloadAwait(Room<3>, 34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin dirtyPayload(Room<3>, 34404981130@chatroom)
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 SILL Room ready()
15:21:30 VERB PuppetCacheMixin onDirty(Room<3>, 34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 VERB PuppetRoomMixin roomPayload(34404981130@chatroom)
15:21:30 SILL PuppetRoomMixin roomPayloadCache(34404981130@chatroom) cache MISS
15:21:30 SILL PuppetPadplus roomRawPayload(34404981130@chatroom)
15:21:30 VERB PuppetRoomMemberMixin roomMemberPayloadDirty(34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin __dirtyPayloadAwait(RoomMember<4>, 34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin dirtyPayload(RoomMember<4>, 34404981130@chatroom)
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 SILL Room ready()
15:21:30 VERB PuppetCacheMixin onDirty(RoomMember<4>, 34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 SILL Room ready()
15:21:30 VERB PuppetRoomMixin roomPayload(34404981130@chatroom)
15:21:30 SILL PuppetRoomMixin roomPayloadCache(34404981130@chatroom) cache MISS
15:21:30 SILL PuppetPadplus roomRawPayload(34404981130@chatroom)
15:21:30 SILL PuppetPadplus roomRawPayloadParser()
15:21:30 SILL PuppetRoomMixin roomPayload(34404981130@chatroom) cache SET
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL PuppetPadplus roomRawPayloadParser()
15:21:30 SILL PuppetRoomMixin roomPayload(34404981130@chatroom) cache SET
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 INFO on room-leave, room:Room<888>, leaverList:Contact<yuwei>, remover:Contact<宇威ya>, date:Mon Mar 27 2023 15:20:10 GMT+0800 (China Standard Time) 
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 VERB PuppetRoomMixin roomPayloadDirty(34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin __dirtyPayloadAwait(Room<3>, 34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin dirtyPayload(Room<3>, 34404981130@chatroom)
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 SILL Room ready()
15:21:30 VERB PuppetCacheMixin onDirty(Room<3>, 34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 VERB PuppetRoomMixin roomPayload(34404981130@chatroom)
15:21:30 SILL PuppetRoomMixin roomPayloadCache(34404981130@chatroom) cache MISS
15:21:30 SILL PuppetPadplus roomRawPayload(34404981130@chatroom)
15:21:30 VERB PuppetRoomMemberMixin roomMemberPayloadDirty(34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin __dirtyPayloadAwait(RoomMember<4>, 34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin dirtyPayload(RoomMember<4>, 34404981130@chatroom)
15:21:30 SILL PuppetPadplus roomRawPayloadParser()
15:21:30 SILL PuppetRoomMixin roomPayload(34404981130@chatroom) cache SET
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 SILL Room ready()
15:21:30 VERB PuppetCacheMixin onDirty(RoomMember<4>, 34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL GRPC_GATEWAY keepHeartbeat()
15:21:30 SILL GRPC_GATEWAY GRPC Request ApiType: HEARTBEAT
15:21:30 VERB PuppetRoomMixin roomPayloadDirty(34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin __dirtyPayloadAwait(Room<3>, 34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin dirtyPayload(Room<3>, 34404981130@chatroom)
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 SILL Room ready()
15:21:30 VERB PuppetCacheMixin onDirty(Room<3>, 34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 VERB PuppetRoomMixin roomPayload(34404981130@chatroom)
15:21:30 SILL PuppetRoomMixin roomPayloadCache(34404981130@chatroom) cache MISS
15:21:30 SILL PuppetPadplus roomRawPayload(34404981130@chatroom)
15:21:30 VERB PuppetRoomMemberMixin roomMemberPayloadDirty(34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin __dirtyPayloadAwait(RoomMember<4>, 34404981130@chatroom)
15:21:30 VERB PuppetCacheMixin dirtyPayload(RoomMember<4>, 34404981130@chatroom)
15:21:30 SILL Room find({"id":"34404981130@chatroom"})
15:21:30 SILL Room ready()
15:21:30 VERB PuppetCacheMixin onDirty(RoomMember<4>, 34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 SILL PuppetPadplus roomRawPayloadParser()
15:21:30 SILL PuppetRoomMixin roomPayload(34404981130@chatroom) cache SET
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Room ready()
15:21:30 VERB PuppetRoomMixin roomPayload(34404981130@chatroom)
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Room memberAll()
15:21:30 VERB Room memberList()
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
15:21:30 SILL Contact find({"id":"sugarorz"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="sugarorz"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_mi88feubut7822"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_mi88feubut7822"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"q1195862472"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="q1195862472"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL Contact find({"id":"wxid_00j4zrd47usu12"})
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL Contact ready() @ Puppet<PuppetPadplus>(./remember-me/puppet_padplus_803f4f5edcc8f6a7) with id="wxid_00j4zrd47usu12"
15:21:30 SILL Contact ready() isReady() true
15:21:30 SILL PuppetLoginMixin get currentUserId()
15:21:30 SILL PuppetPadplus roomMemberList(34404981130@chatroom)
[2023-03-27T15:21:30.375+08:00] [INFO] [85475] [] [wuyuweideMacBook-Pro.local] [172.21.144.49] [wechaty-robot] [] [] [] [] [47ms , requestInfo:{"method":"GET","url":"/robot/getRoomInfo?wxid=wxid_00j4zrd47usu12&roomId=34404981130@chatroom","host":"127.0.0.1:3000"}] ## '' 

15:21:30 SILL GRPC_GATEWAY throttleQueue emit heartbeat.
15:21:30 VERB Watchdog <Puppet> feed({"data":"37a475cd-2af4-4705-8954-7c7afc03fcfa"})
15:21:30 VERB Watchdog <Puppet> timerLeft() = 29882
15:21:30 VERB Watchdog <Puppet> stopTimer()
15:21:30 VERB Watchdog <Puppet> startTimer()
15:21:30 SILL PadplusManager setContactAndRoomData()
15:21:30 SILL PadplusManager setContactAndRoomData() found contact, room, friend data no change.
15:22:00 SILL GRPC_GATEWAY keepHeartbeat()
15:22:00 SILL GRPC_GATEWAY GRPC Request ApiType: HEARTBEAT
15:22:00 SILL GRPC_GATEWAY throttleQueue emit heartbeat.
15:22:00 VERB Watchdog <Puppet> feed({"data":"04f4f522-94bd-4691-8089-b910b26f594b"})
15:22:00 VERB Watchdog <Puppet> timerLeft() = 29943
15:22:00 VERB Watchdog <Puppet> stopTimer()
15:22:00 VERB Watchdog <Puppet> startTimer()
15:22:00 SILL PadplusManager setContactAndRoomData()
15:22:00 SILL PadplusManager setContactAndRoomData() found contact, room, friend data no change.
15:22:30 SILL GRPC_GATEWAY keepHeartbeat()
15:22:30 SILL GRPC_GATEWAY GRPC Request ApiType: HEARTBEAT
15:22:30 SILL GRPC_GATEWAY throttleQueue emit heartbeat.
15:22:30 VERB Watchdog <Puppet> feed({"data":"dc049433-ef18-4dfb-b349-38f4a188bbea"})
15:22:30 VERB Watchdog <Puppet> timerLeft() = 29945
15:22:30 VERB Watchdog <Puppet> stopTimer()
15:22:30 VERB Watchdog <Puppet> startTimer()
15:22:30 SILL PadplusManager setContactAndRoomData()
15:22:30 SILL PadplusManager setContactAndRoomData() found contact, room, friend data no change.
15:23:00 SILL GRPC_GATEWAY keepHeartbeat()
15:23:00 SILL GRPC_GATEWAY GRPC Request ApiType: HEARTBEAT
15:23:00 SILL GRPC_GATEWAY throttleQueue emit heartbeat.
15:23:00 VERB Watchdog <Puppet> feed({"data":"fe586506-8539-4899-a591-dff9d54dcb85"})
15:23:00 VERB Watchdog <Puppet> timerLeft() = 29553
15:23:00 VERB Watchdog <Puppet> stopTimer()
15:23:00 VERB Watchdog <Puppet> startTimer()
15:23:00 SILL PadplusManager setContactAndRoomData()
15:23:01 SILL PadplusManager setContactAndRoomData() found contact, room, friend data no change.