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.

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

Possible Bug: Spam Motd to console, but not chat. #3

Closed
NeoSword opened this issue Jul 29, 2014 · 10 comments
Closed

Possible Bug: Spam Motd to console, but not chat. #3

NeoSword opened this issue Jul 29, 2014 · 10 comments
Assignees

Comments

@NeoSword
Copy link

Using SEserverExtender Ver 0.2.6.1
Using Motd 0.2.1.2

This was only showing in console, in game the only symptom was some double posts back to back of Motd. Console was constant spam of the message causing SEserverextender to run very slow almost unresponsive. Only stopped when unloaded. Immediately restarted upon reload of plugin. Switching to Enabled:false did not stop spam of messages.

Auto Garbage Cleanup/Reboot 1am PST. 600 true

2014-07-29 12:33:49.366 - Thread: 18 -> Log Started
2014-07-29 12:33:49.366 - Thread: 18 -> Timezone (local - UTC): -7h
2014-07-29 12:33:49.366 - Thread: 18 -> App Version: 0.1.8.0
2014-07-29 12:33:49.366 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:37:18.486 - Thread: 6 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:40:20.761 - Thread: 6 -> Chat - Server: Your position:
2014-07-29 12:47:10.455 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:47:18.570 - Thread: 6 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:54:21.817 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:55:41.516 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:57:18.651 - Thread: 6 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:57:59.219 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 12:58:20.715 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:01:47.577 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:04:30.824 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:04:35.890 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:05:27.210 - Thread: 4 -> Chat - Client
2014-07-29 13:05:37.194 - Thread: 4 -> Chat - Client
2014-07-29 13:06:12.594 - Thread: 4 -> Chat - Client
2014-07-29 13:06:13.594 - Thread: 4 -> Chat - Client
2014-07-29 13:06:31.610 - Thread: 4 -> Chat - Client
2014-07-29 13:06:35.060 - Thread: 4 -> Chat - Client
2014-07-29 13:06:42.677 - Thread: 4 -> Chat - Client
2014-07-29 13:06:47.660 - Thread: 4 -> Chat - Client
2014-07-29 13:06:55.826 - Thread: 4 -> Chat - Client
2014-07-29 13:07:18.787 - Thread: 6 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:10:54.994 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:12:16.755 - Thread: 4 -> Chat - Client
2014-07-29 13:15:02.008 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:15:03.421 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:15:07.884 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:15:41.961 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:17:02.063 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:17:18.895 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:17:44.243 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:18:32.307 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:19:37.219 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:24:14.917 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:26:16.209 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:27:19.078 - Thread: 9 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:28:56.413 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:34:51.126 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:14.248 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:14.448 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:14.648 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:14.861 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:15.060 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:15.261 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:15.461 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:15.674 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:15.874 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:16.082 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:16.275 - Thread: 22 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:16.474 - Thread: 23 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:16.687 - Thread: 24 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:16.887 - Thread: 25 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:17.087 - Thread: 24 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:17.287 - Thread: 23 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:17.501 - Thread: 22 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:17.700 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:17.900 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:18.099 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:18.301 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:18.514 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:18.713 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:18.913 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:19.112 - Thread: 22 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:19.313 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:19.526 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:19.726 - Thread: 23 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:19.926 - Thread: 24 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:20.125 - Thread: 23 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:20.340 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:20.539 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:20.739 - Thread: 22 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:20.939 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:21.139 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:21.353 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:21.552 - Thread: 24 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:21.752 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:21.952 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:22.152 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:22.372 - Thread: 22 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:22.565 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:22.765 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:22.965 - Thread: 23 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:23.178 - Thread: 24 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:23.378 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:23.578 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:23.778 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:23.978 - Thread: 25 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:24.191 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:24.391 - Thread: 20 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:24.591 - Thread: 21 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:24.792 - Thread: 24 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:25.004 - Thread: 23 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:25.204 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-29 13:35:25.403 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.

@DraygoKorvan
Copy link
Owner

Its on todo to fix, I am currently using the update() method in the function to produce the MoTD message on a schedule, unfortunately there is a bug in SEServerExtender that can cause multiple update threads to exist at the same time, which is causing the double post and other weirdness. I'll aim to get this fixed up in the next version to decouple the mod from the update() method

@DraygoKorvan
Copy link
Owner

Ok latest version moves the sendMotd method outside the update method, it now has its own thread for the process.

This should fix the double message issue, which was caused by SEServerExtender spamming the update function, and a race condition that would let it through the time check.

@NeoSword
Copy link
Author

Thanks for the update. It is working much better, though there is still something off in the timing.

Interval is set to 600. I also tried to correlate it to player joins but could not. There were only 2 onplayerconnect events during this period, at ~ 2014-07-30 00:15:28

Once the last player logged off at 2014-07-30 01:38:02.141 It seems to work as expected. Last 3 events are exactly 10 min apart. -->>Might be due to entering/exiting cockpit.

Let me know if there is anything else I can provide to help track down.

2014-07-30 00:50:44.835 - Thread: 15 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 00:54:32.783 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 00:55:03.615 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:04:00.848 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:05:03.661 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:08:07.240 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:09:11.131 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:15:03.700 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:15:28.141 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:16:04.234 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:19:22.577 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:22:06.841 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:22:50.440 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:25:03.743 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:25:22.548 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:28:22.032 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:31:16.843 - Thread: 10 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:33:59.099 - Thread: 19 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:35:03.814 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:45:03.867 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.
2014-07-30 01:55:03.927 - Thread: 18 -> Chat - Server: Auto Garbage Cleanup/Reboot 1am PST.

@ghost
Copy link

ghost commented Jul 30, 2014

I am still seeing this as well with latest version. Also chats motd when players get in and out of cockpits.

@DraygoKorvan
Copy link
Owner

Yes, onplayerjoined is triggered when people get in and out of cockpits. Its an unfortunate 'feature' of SEServerExtender - so I have to wait till SEServerExtender gets better handling for OnJoin and PlayerLeft etc. Or work on a workaround.

The serverwide broadcast in your log is 10 mins apart Neo, as it only does that broadcast on Thread 18 in your log, the thread 10 and thread 19 messages etc are PM's. Probably due to the above issue.

I am going to close this though as the original issue is solved I want to keep the issue tracking separate so if its something that bugs you open a new issue thread about the onplayerjoined cockpit issue.

@ghost
Copy link

ghost commented Jul 30, 2014

Even though the spams are PMs to server, it will eventually crash it if not unloaded. The chat log gets huge and cause the server to run out of memory. Don't think you should close this one just yet.

@DraygoKorvan
Copy link
Owner

This issue was to address the "Console was constant spam of the message causing SEserverextender to run very slow almost unresponsive. Only stopped when unloaded. Immediately restarted upon reload of plugin. " part of the issue, in order to keep issue tracking easy for me, I opened a new issue about the PM spam associated with the onPlayerJoined method to a new issue, you can post to that one.

@ghost
Copy link

ghost commented Jul 30, 2014

oh sorry getting confused with what is what, however I just experienced "Console was constant spam of the message causing SEserverextender to run very slow almost unresponsive. Only stopped when unloaded. Immediately restarted upon reload of plugin. " today with the latest version.

@DraygoKorvan
Copy link
Owner

Upload the log to the other issue thread

@DraygoKorvan
Copy link
Owner

#5 is where I am continuing this issue.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

2 participants