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

PuppetPuppeteerFier fireRoomJoin() resolve payload fail #35

Open huan opened 6 years ago

huan commented 6 years ago

Moved from https://github.com/Chatie/wechaty/issues/1491 author: @suntong

Caught one, after just logging in on second attempt (was trying to give up as I haven't caught anything for a day, but decided to give it another try). Anyway:

22:12:51 INFO Messenger: Contact<me> login
22:13:03 SILL Wechaty memoryCheck() free: 701 MB, require: 4 MB
22:13:03 VERB Wechaty start() throttleQueue.subscribe() new item: [object Object]
22:13:03 VERB MemoryCard <puppeteer> set(PUPPET_PUPPETEER, [object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object])
22:13:03 VERB MemoryCard <puppeteer> save() file: /path/to/default.memory-card.json
22:13:03 VERB MemoryCard <> save() file: /path/to/default.memory-card.json
22:13:18 SILL Wechaty memoryCheck() free: 701 MB, require: 4 MB
22:13:33 SILL Wechaty memoryCheck() free: 701 MB, require: 4 MB
22:13:34 VERB PuppetPuppeteerFirer parseRoomJoin("Melody"邀请"louis"加入了群聊)
22:13:34 SILL PuppetPuppeteerFirer checkRoomJoin() inviteeList: louis, inviter: Melody
22:13:34 SILL PuppetPuppeteerFirer fireRoomJoin() retry() ttl 59
22:13:34 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, "louis")
22:13:34 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, {"roomAlias":"louis"})
22:13:34 VERB PuppetPuppeteer roommemberList(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2)
22:13:34 VERB PuppetPuppeteer roomRawPayload(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2)
22:13:34 SILL PuppetPuppeteerBridge getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry attempt 1
22:13:34 SILL PuppetPuppeteerBridge proxyWechaty(getContact, @@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2)
22:13:34 VERB PuppetPuppeteerFirer parseRoomJoin("louis"与群里其他人都不是微信朋友关系，请注意隐私安全)
22:13:34 SILL PuppetPuppeteerFirer checkRoomJoin() ""louis"与群里其他人都不是微信朋友关系，请注意隐私安全" is not a join message
22:13:34 VERB PuppetPuppeteerFirer fireRoomLeave("louis"与群里其他人都不是微信朋友关系，请注意隐私安全)
22:13:34 SILL PuppetPuppeteerFirer fireRoomLeave() no match
/home/u/wechaty1.log:22:13:34 WARN PuppetPuppeteerEvent checkRoomSystem message: <"louis"与群里其他人都不是微信朋友关系，请注意隐私安全> not found

I noticed that checkRoomJoin() has already found everything:

22:13:34 SILL PuppetPuppeteerFirer checkRoomJoin() inviteeList: louis, inviter: Melody

So I don't know what else checkRoomSystem is trying to find...

Caught one, after just logging in on second attempt (was trying to give up as I haven't caught anything for a day, but decided to give it another try). Anyway:

22:12:51 INFO Messenger: Contact<me> login
22:13:03 SILL Wechaty memoryCheck() free: 701 MB, require: 4 MB
22:13:03 VERB Wechaty start() throttleQueue.subscribe() new item: [object Object]
22:13:03 VERB MemoryCard <puppeteer> set(PUPPET_PUPPETEER, [object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object])
22:13:03 VERB MemoryCard <puppeteer> save() file: /path/to/default.memory-card.json
22:13:03 VERB MemoryCard <> save() file: /path/to/default.memory-card.json
22:13:18 SILL Wechaty memoryCheck() free: 701 MB, require: 4 MB
22:13:33 SILL Wechaty memoryCheck() free: 701 MB, require: 4 MB
22:13:34 VERB PuppetPuppeteerFirer parseRoomJoin("Melody"邀请"louis"加入了群聊)
22:13:34 SILL PuppetPuppeteerFirer checkRoomJoin() inviteeList: louis, inviter: Melody
22:13:34 SILL PuppetPuppeteerFirer fireRoomJoin() retry() ttl 59
22:13:34 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, "louis")
22:13:34 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, {"roomAlias":"louis"})
22:13:34 VERB PuppetPuppeteer roommemberList(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2)
22:13:34 VERB PuppetPuppeteer roomRawPayload(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2)
22:13:34 SILL PuppetPuppeteerBridge getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry attempt 1
22:13:34 SILL PuppetPuppeteerBridge proxyWechaty(getContact, @@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2)
22:13:34 VERB PuppetPuppeteerFirer parseRoomJoin("louis"与群里其他人都不是微信朋友关系，请注意隐私安全)
22:13:34 SILL PuppetPuppeteerFirer checkRoomJoin() ""louis"与群里其他人都不是微信朋友关系，请注意隐私安全" is not a join message
22:13:34 VERB PuppetPuppeteerFirer fireRoomLeave("louis"与群里其他人都不是微信朋友关系，请注意隐私安全)
22:13:34 SILL PuppetPuppeteerFirer fireRoomLeave() no match
/home/u/wechaty1.log:22:13:34 WARN PuppetPuppeteerEvent checkRoomSystem message: <"louis"与群里其他人都不是微信朋友关系，请注意隐私安全> not found

I noticed that checkRoomJoin() has already found everything:

22:13:34 SILL PuppetPuppeteerFirer checkRoomJoin() inviteeList: louis, inviter: Melody

So I don't know what else checkRoomSystem is trying to find...

Found some fireRoomJoin() resolve payload fail cases. Folded up to protect PII data from the innocent eyes.

Debug Logs ### Full output logs here with `WECHATY_LOG=silly` set ```shell ## Case #1 23:07:05 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd9 1fc969547cc9db5154e44d26fc2, {"contactAlias":"Mary Guan 伦敦理财"}) 23:07:05 VERB PuppetPuppeteer roommemberList(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 23:07:05 VERB PuppetPuppeteer roomRawPayload(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 23:07:05 SILL PuppetPuppeteerBridge getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry attempt 1 23:07:05 SILL PuppetPuppeteerBridge proxyWechaty(getContact, @@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 23:07:05 SILL PuppetPuppeteer roomPayload() this.bridge.getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) MemberList.length:(prev:0, curr:319) at ttl:6 23:07:05 SILL PuppetPuppeteer roomPayload() puppet.getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry at ttl:6 23:07:06 SILL PuppetPuppeteerBridge getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry attempt 1 23:07:06 SILL PuppetPuppeteerBridge proxyWechaty(getContact, @@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 23:07:06 SILL PuppetPuppeteer roomPayload() this.bridge.getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) MemberList.length:(prev:319, curr:319) at ttl:5 23:07:06 SILL PuppetPuppeteer roomPayload() puppet.getContact(@857611455f2c8f a958b13eb97882a3013d8bb382c86e3e875261d5eebd98fee0) done at ttl:5 with length:319 23:07:06 VERB Puppet contactSearch(query={"alias":"Mary Guan 伦敦理财"}, idList.length = 319) 23:07:06 SILL Puppet contactSearch() searchIdList.length = 319 23:07:06 VERB Puppet contactQueryFilterFactory({"alias":"Mary Guan 伦敦理财"}) 23:07:06 SILL Puppet contactSearch() searchContactPayloadList.length = 0 23:07:06 VERB Puppet roomMemberPayload(roomId=@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, contactId=@9f673b0e2eed298af1b7b6b69059b0d6ac80ad359d66526e8dff1964de55bda6) ... skipping loads of the roomMemberPayloads (IMHO, this should not be in the debug output unless expressively requested) ... 23:07:06 VERB Puppet roomMemberPayload(roomId=@@013f4fab7068f77094a867dd174b2 7f42acd91fc969547cc9db5154e44d26fc2, contactId=@e10e30536e2dd036b7bf694dd7f68 731ff44800141e171e4926ba62697e513e0) 23:07:06 VERB Puppet roomMemberPayload(roomId=@@013f4fab7068f77094a867dd174b2 7f42acd91fc969547cc9db5154e44d26fc2, contactId=@c459c1baad08142db863023eece61 68256995631a894782e326f41a0034d3301) 23:07:06 WARN PuppetPuppeteerFier fireRoomJoin() resolve payload fail. 23:07:06 VERB PuppetPuppeteerFirer fireRoomLeave("NK 刘旭伟法律留学4163589377"邀请"Mary Guan 伦敦理财"加入了群聊) 23:07:06 SILL PuppetPuppeteerFirer fireRoomLeave() no match 23:07:06 WARN PuppetPuppeteerEvent checkRoomSystem message: <"NK 刘旭伟法律留学4163589377"邀请"Mary Guan 伦敦理财"加入了群聊> not found 23:07:06 VERB Message static create(4904610157633791086) 23:07:06 SILL Accessory #2984 constructor() 23:07:06 VERB Message constructor(4904610157633791086) for class Message 23:07:06 VERB Message ready() ## Case #2 01:15:15 VERB Puppet roomMemberPayload(roomId=@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, contactId=@81beed378a44396dd79acf5b99290dfdf11aa3e0c9777113df56421712d5ca17) 01:15:15 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, {"contactAlias":"快乐燕子"}) 01:15:15 VERB PuppetPuppeteer roommemberList(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 01:15:15 VERB PuppetPuppeteer roomRawPayload(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 01:15:15 SILL PuppetPuppeteerBridge getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry attempt 1 01:15:15 SILL PuppetPuppeteerBridge proxyWechaty(getContact, @@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 01:15:16 SILL PuppetPuppeteer roomPayload() this.bridge.getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) MemberList.length:(prev:0, curr:325) at ttl:6 01:15:16 SILL PuppetPuppeteer roomPayload() puppet.getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry at ttl:6 01:15:17 SILL PuppetPuppeteerBridge getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) retry attempt 1 01:15:17 SILL PuppetPuppeteerBridge proxyWechaty(getContact, @@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) 01:15:17 SILL PuppetPuppeteer roomPayload() this.bridge.getContact(@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2) MemberList.length:(prev:325, curr:325) at ttl:5 01:15:17 SILL PuppetPuppeteer roomPayload() puppet.getContact(@857611455f2c8fa958b13eb97882a3013d8bb382c86e3e875261d5eebd98fee0) done at ttl:5 with length:325 01:15:17 VERB Puppet contactSearch(query={"alias":"快乐燕子"}, idList.length = 325) 01:15:17 SILL Puppet contactSearch() searchIdList.length = 325 01:15:17 VERB Puppet contactQueryFilterFactory({"alias":"快乐燕子"}) 01:15:17 SILL Puppet contactSearch() searchContactPayloadList.length = 0 01:15:17 VERB Puppet roomMemberPayload(roomId=@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, contactId=@9f673b0e2eed298af1b7b6b69059b0d6ac80ad359d66526e8dff1964de55bda6) ... skipping loads of the roomMemberPayloads (IMHO, this should not be in the debug output unless expressively requested) ... 01:15:17 VERB Puppet roomMemberPayload(roomId=@@013f4fab7068f77094a867dd174b27f42acd91fc969547cc9db5154e44d26fc2, contactId=@81beed378a44396dd79acf5b99290dfdf11aa3e0c9777113df56421712d5ca17) 01:15:17 WARN PuppetPuppeteerFier fireRoomJoin() resolve payload fail. 01:15:17 VERB PuppetPuppeteerFirer fireRoomLeave("快乐燕子"邀请"李菊"加入了群聊) 01:15:17 SILL PuppetPuppeteerFirer fireRoomLeave() no match 01:15:17 WARN PuppetPuppeteerEvent checkRoomSystem message: <"快乐燕子"邀请" 李菊"加入了群聊> not found 01:15:17 VERB Message static create(6271236879510998245) 01:15:17 SILL Accessory #3169 constructor() 01:15:17 VERB Message constructor(6271236879510998245) for class Message 01:15:17 VERB Message ready() . . . ```
huan commented 6 years ago

@suntong Let's tracking this issue at puppeteer's own repo