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

Published 20 hours ago • verified publisher icon wechaty

PuppetPuppeteerFier fireRoomJoin() resolve payload fail

Open huan opened this issue 7 years ago • 1 comments

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

## Case #1

23:07:05 VERB Puppet roomMemberSearch(@@013f4fab7068f77094a867dd174b27f42acd9
1fc969547cc9db5154e44d26fc2, {"contactAlias":"Mary Guan <span class=\"emoji emoji2122\"></span> 伦敦理财"})
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 <span class=\"emoji emoji2122\"></span> 伦敦理财"}, idList.length = 319)
23:07:06 SILL Puppet contactSearch() searchIdList.length = 319
23:07:06 VERB Puppet contactQueryFilterFactory({"alias":"Mary Guan <span class=\"emoji emoji2122\"></span> 伦敦理财"})
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 <span class="emoji emoji2122"></span> 伦敦理财"加入了群聊)
23:07:06 SILL PuppetPuppeteerFirer fireRoomLeave() no match
23:07:06 WARN PuppetPuppeteerEvent checkRoomSystem message: <"NK 刘旭伟法律留学4163589377"邀请"Mary Guan <span class="emoji emoji2122"></span> 伦敦理财"加入了群聊> not found
23:07:06 VERB Message static create(4904610157633791086)
23:07:06 SILL Accessory #2984<Message> 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<Message> constructor()
01:15:17 VERB Message constructor(6271236879510998245) for class Message
01:15:17 VERB Message ready()
. . .

huan avatar Aug 19 '18 04:08 huan

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

huan avatar Aug 19 '18 04:08 huan