Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

安卓平台（Android 7+）长时间运行mirai会输出“Bot is closed manually”，然后就没有任何输出了 #1509

Closed
zhaodice opened this issue Aug 30, 2021 · 10 comments
Closed

安卓平台（Android 7+）长时间运行mirai会输出“Bot is closed manually”，然后就没有任何输出了 #1509

zhaodice opened this issue Aug 30, 2021 · 10 comments
Labels
M 优先级: 主要 t:problem 类型: 不容易归类为特性或 bug 的综合问题 x:question 标签: 需要更多信息
Milestone
2.7.1

Comments

@zhaodice
Copy link
Contributor

zhaodice commented Aug 30, 2021 •
edited

在安卓平台长时间登陆mirai，一段时间之后（大约几个小时），控制台会输出”Bot is closed manually“，从此再无任何响应，再任何事件（哪怕是掉线事件）。

复现

在安卓平台长时间登陆mirai达到几个小时即有可能出现，暂未发现准确的复现规律

版本及相关选项选择

  • mirai-core: 2.7.0

网络日志

�[0m2021-08-29 21:18:03 V/Net 1240819017: Loading server list from cache.�[0m
�[92m2021-08-29 21:18:03 I/Net 1240819017: Server list: 111.30.181.202:8080, 111.30.181.215:80, 120.232.18.82:443, msfwifi.3g.qq.com:8080, 36.155.229.140:80, 111.30.178.182:80, 36.155.229.173:14000, 111.30.181.201:80, 120.232.22.118:8080, 36.155.229.228:8080.�[0m
�[92m2021-08-29 21:18:03 I/Net 1240819017: ECDH key is valid.�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Send: wtlogin.exchange_emp�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Recv: wtlogin.exchange_emp�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Send: StatSvc.register(online)�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Recv: StatSvc.register�[0m
�[92m2021-08-29 21:18:03 I/Net 1240819017: Syncing friend message history...�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Loading BdhSession from cache file�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Send: StatSvc.GetDevLoginInfo(StatSvc.GetDevLoginInfo)�[0m
�[92m2021-08-29 21:18:03 I/Net 1240819017: Start loading stranger list...�[0m
�[92m2021-08-29 21:18:03 I/Net 1240819017: Start loading friend list...�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Send: OidbSvc.0x5d2_0(OidbSvc.0x5d2_0)�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Send: OidbSvc.0x496(OidbSvc.0x496)�[0m
�[0m2021-08-29 21:18:03 V/Net 1240819017: Send: friendlist.getFriendGroupList(friendlist.getFriendGroupList)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: OidbSvc.0x496�[0m
�[92m2021-08-29 21:18:04 I/Net 1240819017: Start loading group list...�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: OidbSvc.0x5d2_0�[0m
�[92m2021-08-29 21:18:04 I/Net 1240819017: Successfully loaded stranger list: 0 in total�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopListReqV2(friendlist.GetTroopListReqV2)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: StatSvc.GetDevLoginInfo�[0m
�[92m2021-08-29 21:18:04 I/Net 1240819017: Online OtherClients: vivo(MOBILE)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.getFriendGroupList�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Loading friend list: 57/57�[0m
�[92m2021-08-29 21:18:04 I/Net 1240819017: Successfully loaded friend list: 57 in total�[0m
�[92m2021-08-29 21:18:04 I/Net 1240819017: Saved 57 friends to local cache.�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopListReqV2�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:04 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[92m2021-08-29 21:18:05 I/Net 1240819017: Syncing friend message history: Success.�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:05 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:06 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:06 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:06 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:06 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:06 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:07 V/Net 1240819017: Recv: ConfigPushSvc.PushReq�[0m
�[0m2021-08-29 21:18:07 V/Net 1240819017: Saving bdh session to cache�[0m
�[0m2021-08-29 21:18:07 V/Net 1240819017: Send: ConfigPushSvc.PushResp(ConfigPushSvc.PushResp)�[0m
�[0m2021-08-29 21:18:07 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[0m2021-08-29 21:18:07 V/Net 1240819017: Send: friendlist.GetTroopMemberListReq(friendlist.GetTroopMemberListReq)�[0m
�[0m2021-08-29 21:18:07 V/Net 1240819017: Recv: friendlist.GetTroopMemberListReq�[0m
�[92m2021-08-29 21:18:07 I/Net 1240819017: Successfully loaded group list: 13 in total.�[0m
�[92m2021-08-29 21:18:07 I/Net 1240819017: Saved 13 groups to local cache.�[0m
�[92m2021-08-29 21:18:07 I/Net 1240819017: Scheduled key refresh in 23h 55min 0.0s.�[0m
�[92m2021-08-29 21:18:07 I/Net 1240819017: Scheduled refresh login session in 19d 23h 55min 0.0s.�[0m
�[0m2021-08-29 21:22:57 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:22:57 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:25:48 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:25:49 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:35:51 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:35:51 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:36:03 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:36:03 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:42:49 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:42:50 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:44:10 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:44:11 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:45:15 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:45:15 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:54:10 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:54:10 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 21:56:32 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 21:56:32 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:15:57 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:15:57 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:16:44 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:16:45 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:16:49 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:16:50 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:21:58 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:21:58 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:22:34 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:22:34 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:25:23 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:25:24 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:27:15 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:27:15 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:28:40 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:28:40 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:30:10 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:30:11 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:30:23 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:30:23 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:32:21 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:32:21 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:32:33 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:32:33 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:32:39 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:32:39 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:32:56 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[0m2021-08-29 22:34:24 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 22:34:25 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 22:35:34 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[0m2021-08-29 22:42:44 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[0m2021-08-29 23:01:37 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:01:38 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:08:03 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:08:04 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:08:25 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:08:25 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:12:40 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:12:40 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:23:05 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:23:06 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:23:13 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:23:13 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:23:56 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-29 23:23:56 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-29 23:27:26 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[0m2021-08-29 23:31:33 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[0m2021-08-29 23:38:13 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[96m2021-08-29 23:38:55 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModLongNick=ModLongNick#215455885 {
                    uin=0x000000006BE983AA(1810465706)
                    value=E6 88 91 E4 B8 8D E4 BC 9A E6 BB 91 E6 BB 91 EF BC 88
            }
            opType=0x0000003C(60)
    }�[0m
�[96m2021-08-29 23:38:55 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModRichLongNick=ModLongNick#215455885 {
                    uin=0x000000006BE983AA(1810465706)
                    value=00 00 00 00 61 2B AA 0F 91 04 00 00 00 00 92 04 00 00 00 00 03 12 E6 88 91 E4 B8 8D E4 BC 9A E6 BB 91 E6 BB 91 EF BC 88 95 00 90 1C 31 38 31 30 34 36 35 37 30 36 30 66 61 61 32 62 36 31 65 33 61 39 30 35 30 30 35 61
            }
            opType=0x0000003D(61)
    }�[0m
�[96m2021-08-29 23:38:55 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModRichLongNickEx=ModLongNick#215455885 {
                    uin=0x000000006BE983AA(1810465706)
                    value=00 00 00 00 61 2B AA 0F 91 04 00 00 00 00 92 04 00 00 00 00 03 12 E6 88 91 E4 B8 8D E4 BC 9A E6 BB 91 E6 BB 91 EF BC 88 95 00 90 1C 31 38 31 30 34 36 35 37 30 36 30 66 61 61 32 62 36 31 65 33 61 39 30 35 30 30 35 61 A2 04 00 00 00 00 A3 04 00 00 00 00
            }
            opType=0x000000D3(211)
    }�[0m
�[96m2021-08-29 23:38:55 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModLongNick=ModLongNick#215455885 {
                    uin=0x000000006BE983AA(1810465706)
                    value=E6 88 91 E4 B8 8D E4 BC 9A E6 BB 91 E6 BB 91 EF BC 88
            }
            opType=0x0000003C(60)
    }�[0m
�[96m2021-08-29 23:38:55 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModRichLongNick=ModLongNick#215455885 {
                    uin=0x000000006BE983AA(1810465706)
                    value=00 00 00 00 61 2B AA 0F 91 04 00 00 00 00 92 04 00 00 00 00 03 12 E6 88 91 E4 B8 8D E4 BC 9A E6 BB 91 E6 BB 91 EF BC 88 95 00 90 1C 31 38 31 30 34 36 35 37 30 36 30 66 61 61 32 62 36 31 65 33 61 39 30 35 30 30 35 61
            }
            opType=0x0000003D(61)
    }�[0m
�[96m2021-08-29 23:38:55 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModRichLongNickEx=ModLongNick#215455885 {
                    uin=0x000000006BE983AA(1810465706)
                    value=00 00 00 00 61 2B AA 0F 91 04 00 00 00 00 92 04 00 00 00 00 03 12 E6 88 91 E4 B8 8D E4 BC 9A E6 BB 91 E6 BB 91 EF BC 88 95 00 90 1C 31 38 31 30 34 36 35 37 30 36 30 66 61 61 32 62 36 31 65 33 61 39 30 35 30 30 35 61 A2 04 00 00 00 00 A3 04 00 00 00 00
            }
            opType=0x000000D3(211)
    }�[0m
�[0m2021-08-29 23:44:36 V/Net 1240819017: Recv: OnlinePush.PbPushTransMsg�[0m
�[0m2021-08-30 00:20:06 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-30 00:20:06 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[96m2021-08-30 00:20:06 D/Net 1240819017: Unknown Transformers732 0x10 optEnumType
optEnumType=24
content=NotifyMsgBody#67009843 {
            optEnumType=0x00000018(24)
            optMsgGroupInfoChange=GroupInfoChange#248782441 {
            }
            optUint64GroupCode=0x0000000028EC3C78(686570616)
            optUint64MsgExpires=0x00000000FFFFFFFF(4294967295)
            optUint64MsgTime=0x00000000612BB3B6(1630254006)
            optUint64SenderUin=0x00000000B1FA5B0C(2985974540)
            serviceType=0x00000017(23)
    }�[0m
�[0m2021-08-30 00:20:35 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-30 00:20:36 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-30 00:26:01 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-30 00:26:01 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-30 00:47:40 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-30 00:47:41 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[0m2021-08-30 00:47:53 V/Net 1240819017: Send: MessageSvc.PbSendMsg(MessageSvc.PbSendMsg)�[0m
�[0m2021-08-30 00:47:53 V/Net 1240819017: Recv: MessageSvc.PbSendMsg�[0m
�[96m2021-08-30 00:57:54 D/Net 1240819017: Unknown Transformers732 0x10 optEnumType
optEnumType=6
content=NotifyMsgBody#67009843 {
            optEnumType=0x00000006(6)
            optMsgGroupNotify=GroupNotifyInfo#205147047 {
                    optBytesFeedsId=32 38 37 64 38 61 30 63 30 30 30 30 30 30 30 30 39 32 62 63 32 62 36 31 31 31 39 31 30 35 30 30
                    optUint32AutoPullFlag=0x00000001(1)
            }
            optUint64GroupCode=0x000000000C8A7D28(210402600)
            optUint64MsgExpires=0x0000000000000000(0)
            optUint64MsgTime=0x0000000000000000(0)
            optUint64SenderUin=0x0000000000000000(0)
    }�[0m
�[96m2021-08-30 01:05:42 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModFriendRings=ModSnsGeneralInfo#90751617 {
                    msgSnsGeneralInfos=[SnsUpateBuffer#161763110 {
                            code=0x0000000000000000(0)
                            msgSnsUpdateItem=[SnsUpdateItem#142047079 {
                                    updateSnsType=0x00003507(13575)
                                    value=00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 49 B3
                            }]
                            uin=0x00000000CC5C5B22(3428604706)
                            uint32Idlist=[]
                    }]
            }
            opType=0x00000007(7)
    }�[0m
�[96m2021-08-30 01:05:42 D/Net 1240819017: Transformers528 0x27L: new data: ForwardBody#17002636 {
            msgModFriendRings=ModSnsGeneralInfo#90751617 {
                    msgSnsGeneralInfos=[SnsUpateBuffer#161763110 {
                            code=0x0000000000000000(0)
                            msgSnsUpdateItem=[SnsUpdateItem#142047079 {
                                    updateSnsType=0x00003507(13575)
                                    value=00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 49 B3
                            }]
                            uin=0x00000000CC5C5B22(3428604706)
                            uint32Idlist=[]
                    }]
            }
            opType=0x00000007(7)
    }�[0m
�[0m2021-08-30 02:25:30 V/Net 1240819017: Send: MultiMsg.ApplyDown(MultiMsg.ApplyDown)�[0m
�[0m2021-08-30 02:25:30 V/Net 1240819017: Recv: MultiMsg.ApplyDown�[0m
�[0m2021-08-30 02:25:30 V/Net 1240819017: [Http] Downloading forward message from https://ssl.htdata.qq.com:443�[0m
�[0m2021-08-30 02:25:30 V/Net 1240819017: [Http] Downloading forward message: succeed�[0m
�[96m2021-08-30 02:27:39 D/Net 1240819017: Unknown Transformers732 0x10 optEnumType
optEnumType=24
content=NotifyMsgBody#67009843 {
            optEnumType=0x00000018(24)
            optMsgGroupInfoChange=GroupInfoChange#248782441 {
            }
            optUint64GroupCode=0x000000002EA8CCE6(782814438)
            optUint64MsgExpires=0x00000000FFFFFFFF(4294967295)
            optUint64MsgTime=0x00000000612BD19A(1630261658)
            optUint64SenderUin=0x000000000E080F5F(235409247)
            serviceType=0x00000017(23)
    }�[0m
�[96m2021-08-30 03:14:50 D/Net 1240819017: Transformers528 0x27L: new data: []�[0m
�[96m2021-08-30 03:14:50 D/Net 1240819017: Transformers528 0x27L: new data: []�[0m
PS:然后到七点半就罢工了（输出了Bot is closed manually，宣告进入假死）

补充信息

【所有的假死】都是从输出一句”Bot is closed manually“开始的。
@zhaodice zhaodice added the x:question 标签: 需要更多信息 label Aug 30, 2021
@zhaodice zhaodice changed the title 安卓平台（Android 7+）长时间运行mirai会假死，从此再无任何响应，再任何事件（哪怕是掉线事件）。 安卓平台（Android 7+）长时间运行mirai会假死，从此再无任何响应，无任何事件（哪怕是掉线事件）。 Aug 30, 2021
@zhaodice
Copy link
Contributor Author

比较想知道，如果不是主动调用bot.close()，还有哪些情况会导致“Bot is closed manually”？

@zhaodice zhaodice changed the title 安卓平台（Android 7+）长时间运行mirai会假死，从此再无任何响应，无任何事件（哪怕是掉线事件）。 安卓平台（Android 7+）长时间运行mirai会输出“Bot is closed manually”，然后就没有任何输出了 Aug 30, 2021
@zhaodice
Copy link
Contributor Author

可能和这个问题一样？ #1466

@Karlatemp
Copy link
Member

Provide
mirai.network.handle.selector.logging=true mirai.network.state.observer.logging=true

@Him188 Him188 added M 优先级: 主要 t:problem 类型: 不容易归类为特性或 bug 的综合问题 labels Aug 30, 2021
@Him188 Him188 added this to the 2.7.1 milestone Aug 30, 2021
@zhaodice
Copy link
Contributor Author

日志有点难复现，研究中

@zhaodice
Copy link
Contributor Author 

�[96m2021-08-31 11:39:25 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:39:25 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:39:25 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:39:32 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:39:32 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:39:32 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:44:41 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:44:41 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:44:41 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:44:50 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:44:50 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:44:50 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:45:06 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:45:06 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:45:06 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:45:15 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:45:15 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:45:15 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:46:15 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:46:15 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:46:15 D/Net 646811626: Attempt #0: RETURN�[0m
�[0m2021-08-31 11:46:15 V/Net 646811626: Send: MultiMsg.ApplyDown(MultiMsg.ApplyDown)�[0m
�[0m2021-08-31 11:46:15 V/Net 646811626: Recv: MultiMsg.ApplyDown�[0m
�[0m2021-08-31 11:46:15 V/Net 646811626: [Http] Downloading forward message from https://ssl.htdata.qq.com:443�[0m
�[0m2021-08-31 11:46:15 V/Net 646811626: [Http] Downloading forward message: succeed�[0m
�[96m2021-08-31 11:47:26 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:47:26 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:47:26 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:47:34 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:47:34 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:47:34 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:54:11 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:54:11 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:54:11 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:54:20 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:54:20 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:54:20 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:56:55 D/Net 646811626: Transformers528 0x27L: new data: []�[0m
�[96m2021-08-31 11:56:55 D/Net 646811626: Transformers528 0x27L: new data: []�[0m
�[96m2021-08-31 11:56:55 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:56:55 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:56:55 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 11:57:04 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 11:57:04 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 11:57:04 D/Net 646811626: Attempt #0: RETURN�[0m
�[91m2021-08-31 12:00:33 W/Net 646811626: Failed to find member 2185090044 in group 978563358�[0m
�[91m2021-08-31 12:00:48 W/Net 646811626: Failed to find member 2185090044 in group 978563358�[0m
�[0m2021-08-31 12:01:53 V/Net 646811626: Recv: OnlinePush.PbPushTransMsg�[0m
�[96m2021-08-31 12:01:53 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:01:53 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:01:53 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 12:07:35 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:07:35 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:07:35 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 12:07:43 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:07:43 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:07:43 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 12:09:47 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:09:47 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:09:47 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 12:09:55 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:09:55 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:09:55 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 12:10:15 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:10:15 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:10:15 D/Net 646811626: Attempt #0: RETURN�[0m
�[96m2021-08-31 12:10:23 D/Net 646811626: Attempt #0: current.state = OK�[0m
�[96m2021-08-31 12:10:23 D/Net 646811626: Attempt #0: Try resumeConnection�[0m
�[96m2021-08-31 12:10:24 D/Net 646811626: Attempt #0: RETURN�[0m

@Karlatemp 已复现，此时输出了“Bot is closed manually”后不再有下文，日志比较长只截取了后半部分的日志，如果需要全部日志可以提一下。

@zhaodice
Copy link
Contributor Author

个人感觉这【不太像】网络离线，因为“Bot is closed manually”之后，框架就完全没有反应了，也并没有触发bot离线等事件，没有任何报错信息。

@zhaodice
Copy link
Contributor Author

zhaodice commented Sep 1, 2021 •
edited

我不确定是不是因为这段代码造成的：

globalEventChannel().subscribeAlways(BotOnlineEvent::class, EmptyCoroutineContext) {
            val botId = bot.id
            //当有重复BOT上线时，关闭之前的BOT，留住新的
            botsList[botId].apply {
                if(this!=bot) {
                    this?.close()
                    botsList[botId] = bot//用新bot 取代之
                }
            }
//.....
}

我已经改成了

//当有重复BOT上线时，关闭之前的BOT，留住新的
            botsList[botId].apply {
                if(this!=bot) {
                    this?.close(Exception("bot $botId already existed"))
                    botsList[botId] = bot//用新bot 取代之
                }
            }

看看到底是不是它的问题，我咋那么高度怀疑呢。。。

好，不是我这边的问题，依然是“bot is closed manually”

@mzdluo123
Copy link
Member

一个简单的修复方案

mirai/mirai-core/src/commonMain/kotlin/QQAndroidBot.kt

Line 125 in 238ec52

when {

修改为

            StateChangedObserver("BotOfflineEventBroadcaster", State.OK, State.CLOSED) { new ->
                // logging performed by BotOfflineEventMonitor
                val cause = new.getCause()
                when {
                    cause is ForceOfflineException -> {
                        eventDispatcher.broadcastAsync(BotOfflineEvent.Force(bot, cause.title, cause.message))
                    }
                    cause is StatSvc.ReqMSFOffline.MsfOfflineToken -> {
                        eventDispatcher.broadcastAsync(BotOfflineEvent.MsfOffline(bot, cause))
                    }
                    cause is NetworkException && cause.recoverable -> {
                        eventDispatcher.broadcastAsync(BotOfflineEvent.Dropped(bot, cause))
                    }
                    cause == null ->{
                        eventDispatcher.broadcastAsync(BotOfflineEvent.Dropped(bot, null))
                    }
                    else -> {
                        // any other unexpected exceptions considered as an error
                        eventDispatcher.broadcastAsync(BotOfflineEvent.Active(bot, cause))
                    }
                }
            }

原因是某些情况下的异常连接关闭会被netty认为是正常关闭，不抛出任何异常

mirai/mirai-core/src/commonMain/kotlin/network/impl/netty/NettyNetworkHandler.kt

Line 160 in 8271fa9

future.channel().closeFuture().addListener {

可以观察到it.cause()为null

    future.channel().closeFuture().addListener {
            if (_state.correspondingState == State.CLOSED) return@addListener
            close(it.cause())
        }

mzdluo123 added a commit that referenced this issue Sep 5, 2021
@mzdluo123
#1509 try to fix bot is closed manually
b822c04
mzdluo123 added a commit that referenced this issue Sep 5, 2021
@mzdluo123
#1509 try to fix bot is closed manually
93fad9e
Karlatemp added a commit that referenced this issue Sep 5, 2021
@Karlatemp
#1509 try to fix bot is closed manually
6cd7fa9
@Lin-XCC
Copy link

Lin-XCC commented Sep 6, 2021

我也出现过这个问题，不过我的环境比较特殊。
是闲来无事的时候使用安卓平台7.0通过Linux Deploy安装了一个UBuntu的bionic版本。然后安装了环境跑了jar包形式的mirai，可以正常跑起来。
但是如果手机息屏后，一段时间后安卓的cpu会进入休眠状态导致linux中的机器人出现该问题。如果不息屏貌似就不会出现。
因为环境过于特殊，就没有开提问。看到有人问了提一提，可能会有帮助？

Karlatemp pushed a commit that referenced this issue Sep 9, 2021
@mzdluo123 @Karlatemp
#1509 try to fix bot is closed manually
853b6c6
Karlatemp added a commit that referenced this issue Sep 9, 2021
@Karlatemp
#1509 try to fix bot is closed manually
72dc1e1
@zhaodice
Copy link
Contributor Author

经过一段时间测试，似乎2.7.1-DEV-1没什么问题了

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
M 优先级: 主要 t:problem 类型: 不容易归类为特性或 bug 的综合问题 x:question 标签: 需要更多信息
Projects
None yet
Milestone
2.7.1
Development

No branches or pull requests
5 participants
@Him188 @mzdluo123 @Karlatemp @Lin-XCC @zhaodice