Possible memory leak in the Beta channel releases

2peer commented 1 month ago

I suspect there might be a newly introduced memory leak in the latest two beta channel releases (28,29). My memory limit was 2560MB (-Xmx2560m in java.vmoptions), yet now twice in a row I came to a computer where BiglyBT was running for a few days (maybe 1-3) and 100% of CPU was consumed by Bigly's garbage collection threads, it was not communicating on the network (observed network interface upload speed was around 0), and I couldn't raise the GUI to be rendered (it was minimized in tray).

Is there something I can do to help diagnose the problem? Logs that I can look at that will indicate what is possibly eating so much memory? Or could this be a JDK issue (how could I check that)?

There used to be a warning about possible out of memory situation, nudging you to increase the limit, but no such thing happened these two times (it just silently died, trying to free memory).

I increased the memory limit to 3072MB in case it's just a legitimate increase of memory requirement due to amount of data handled & I will see if this happens again, but even if it's so some info about memory use that would lead you to a way how to possibly lower it would be nice.

System info:

Java 21.0.3 (64 bit)
  Red Hat, Inc.
/usr/lib/jvm/java-21-openjdk-21.0.3.0.9-1.fc40.x86_64

SWT v4956r12, gtk/3.24.42, zoom=100, dpi=96
Linux v6.9.7-200.fc40.x86_64, amd64 (64 bit)
B3.6.0.1_B29/4 az2 en

2peer commented 1 month ago

Regarding the JDK: I checked the package update log:

there was no recent openjdk update (definitely nothing in the last 6 weeks)
the last glibc update (which seem to be it's only external dependency) was 2024-07-09 (but my current uptime is only 5 days so the previous instances might have lived of some older version)

Is there a recommended JDK version to run BiglyBT?

parg commented 1 month ago

Only recent changes would not appear to be able to cause such a leak

https://www.tumblr.com/blog/biglybt

You sure you haven't just added more downloads or have more active downloads?

Watch View->Log Views->JVM Info for memory usage stats

2peer commented 1 month ago

You sure you haven't just added more downloads or have more active downloads?

not dramatically

I'm starting tu suspect the JDK a little bit. What about the warning? How does that work / why wasn't it raised? Is it normal for the GC threads to consistently consume all the CPU in OOM situations? Is it possible that the issue is not the heap size but some other thing preventing the GC from working? Maybe stack size?

I discovered jconsole & noticed that there is also Non-Heap Memory usage chart. Will keep it running in the background to get more info next time.

Watch View->Log Views->JVM Info for memory usage stats

I have this one backed-up from the previous run (thread_1.log):

[08:20:31] Thread state: elapsed=10000,cpu=4277,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=146012
[08:20:41] Thread state: elapsed=10000,cpu=4130,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=80824
[08:20:51] Thread state: elapsed=10000,cpu=5117,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=105639
[08:21:01] Thread state: elapsed=10000,cpu=3657,max=ReadController:ReadProcessor(870/8%),mem:max=2621440,tot=2621440,free=164700
[08:21:11] Thread state: elapsed=10001,cpu=3346,max=ReadController:ReadProcessor(700/7%),mem:max=2621440,tot=2621440,free=76616
[08:21:21] Thread state: elapsed=10000,cpu=8736,max=Global Status Checker(3580/35%),mem:max=2621440,tot=2621440,free=196355
[08:21:21]     java.base@21.0.3/jdk.internal.misc.Unsafe.park(Native Method), java.base@21.0.3/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269), java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:756), java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1126), java.base@21.0.3/java.util.concurrent.Semaphore.tryAcquire(Semaphore.java:415), app//com.biglybt.core.util.AESemaphore.reserve(AESemaphore.java:83), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:667), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[08:21:31] Thread state: elapsed=10001,cpu=3377,max=PeerControlScheduler(900/9%),mem:max=2621440,tot=2621440,free=135482
[08:21:41] Thread state: elapsed=10000,cpu=4441,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=180267
[08:21:51] Thread state: elapsed=10000,cpu=5118,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=94995
[08:22:01] Thread state: elapsed=10000,cpu=4206,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=143526
[08:22:11] Thread state: elapsed=10000,cpu=3897,max=PeerControlScheduler(1000/10%),mem:max=2621440,tot=2621440,free=182164
[08:22:21] Thread state: elapsed=10000,cpu=3477,max=ReadController:ReadProcessor(920/9%),mem:max=2621440,tot=2621440,free=83054
[08:22:31] Thread state: elapsed=10000,cpu=4273,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=115748
[08:22:41] Thread state: elapsed=10000,cpu=4090,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=172859
[08:22:51] Thread state: elapsed=10001,cpu=5180,max=PeerControlScheduler(1000/10%),mem:max=2621440,tot=2621440,free=88085
[08:23:01] Thread state: elapsed=10011,cpu=3407,max=ReadController:ReadProcessor(820/8%),mem:max=2621440,tot=2621440,free=138237
[08:23:11] Thread state: elapsed=10000,cpu=2906,max=PeerControlScheduler(740/7%),mem:max=2621440,tot=2621440,free=197966
[08:23:21] Thread state: elapsed=10000,cpu=4009,max=ReadController:ReadProcessor(1030/10%),mem:max=2621440,tot=2621440,free=121294
[08:23:31] Thread state: elapsed=10000,cpu=3557,max=ReadController:ReadProcessor(850/8%),mem:max=2621440,tot=2621440,free=159289
[08:23:41] Thread state: elapsed=10000,cpu=3927,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=206544
[08:23:51] Thread state: elapsed=10000,cpu=4301,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=128004
[08:24:01] Thread state: elapsed=10000,cpu=4347,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=168535
[08:24:11] Thread state: elapsed=10000,cpu=4116,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=94848
[08:24:21] Thread state: elapsed=10000,cpu=3158,max=ReadController:ReadProcessor(800/8%),mem:max=2621440,tot=2621440,free=127777
[08:24:31] Thread state: elapsed=10000,cpu=4236,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=160583
[08:24:41] Thread state: elapsed=10000,cpu=4508,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=95147
[08:24:51] Thread state: elapsed=10000,cpu=5179,max=tag:constraints(1070/10%),mem:max=2621440,tot=2621440,free=138658
[08:25:02] Thread state: elapsed=10000,cpu=4447,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=182835
[08:25:12] Thread state: elapsed=10000,cpu=2857,max=PeerControlScheduler(680/6%),mem:max=2621440,tot=2621440,free=188428
[08:25:22] Thread state: elapsed=10000,cpu=4529,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=113606
[08:25:32] Thread state: elapsed=10000,cpu=4259,max=ReadController:ReadProcessor(1030/10%),mem:max=2621440,tot=2621440,free=166347
[08:25:42] Thread state: elapsed=10000,cpu=4178,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=201845
[08:25:52] Thread state: elapsed=10000,cpu=4367,max=tag:constraints(940/9%),mem:max=2621440,tot=2621440,free=123181
[08:26:02] Thread state: elapsed=10000,cpu=4577,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=164989
[08:26:12] Thread state: elapsed=10000,cpu=4140,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=200600
[08:26:22] Thread state: elapsed=12202,cpu=5128,max=ReadController:ReadProcessor(980/9%),mem:max=2621440,tot=2621440,free=153277
[08:26:34] Thread state: elapsed=10000,cpu=7277,max=Global Status Checker(3290/32%),mem:max=2621440,tot=2621440,free=120646
[08:26:34]     java.base@21.0.3/jdk.internal.misc.Unsafe.park(Native Method), java.base@21.0.3/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269), java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:756), java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1126), java.base@21.0.3/java.util.concurrent.Semaphore.tryAcquire(Semaphore.java:415), app//com.biglybt.core.util.AESemaphore.reserve(AESemaphore.java:83), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:667), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[08:26:44] Thread state: elapsed=10000,cpu=4078,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=152439
[08:26:54] Thread state: elapsed=10000,cpu=5288,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=188133
[08:27:04] Thread state: elapsed=10000,cpu=4219,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=124399
[08:27:14] Thread state: elapsed=10000,cpu=2868,max=PeerControlScheduler(750/7%),mem:max=2621440,tot=2621440,free=148718
[08:27:24] Thread state: elapsed=10000,cpu=4457,max=ReadController:ReadProcessor(1000/10%),mem:max=2621440,tot=2621440,free=171760
[08:27:34] Thread state: elapsed=10000,cpu=4292,max=ReadController:ReadProcessor(980/9%),mem:max=2621440,tot=2621440,free=89402
[08:27:44] Thread state: elapsed=10000,cpu=4502,max=PeerControlScheduler(1230/12%),mem:max=2621440,tot=2621440,free=101792
[08:27:54] Thread state: elapsed=10000,cpu=5190,max=ReadController:ReadProcessor(1080/10%),mem:max=2621440,tot=2621440,free=148856
[08:28:04] Thread state: elapsed=10000,cpu=4188,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=180316
[08:28:14] Thread state: elapsed=10000,cpu=3156,max=ReadController:ReadProcessor(850/8%),mem:max=2621440,tot=2621440,free=74073
[08:28:24] Thread state: elapsed=10001,cpu=5230,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=114074
[08:28:34] Thread state: elapsed=10001,cpu=4477,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=157965
[08:28:44] Thread state: elapsed=10000,cpu=4259,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=95443
[08:28:54] Thread state: elapsed=10000,cpu=3428,max=ReadController:ReadProcessor(880/8%),mem:max=2621440,tot=2621440,free=117961
[08:29:04] Thread state: elapsed=10000,cpu=3916,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=60965
[08:29:14] Thread state: elapsed=10001,cpu=3061,max=PeerControlScheduler(870/8%),mem:max=2621440,tot=2621440,free=162568
[08:29:24] Thread state: elapsed=10000,cpu=5239,max=PeerControlScheduler(1140/11%),mem:max=2621440,tot=2621440,free=197219
[08:29:34] Thread state: elapsed=10000,cpu=3169,max=ReadController:ReadProcessor(810/8%),mem:max=2621440,tot=2621440,free=133505
[08:29:44] Thread state: elapsed=10000,cpu=4116,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=183799
[08:29:54] Thread state: elapsed=10000,cpu=5189,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=92935
[08:30:04] Thread state: elapsed=10000,cpu=3957,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=147353
[08:30:14] Thread state: elapsed=10000,cpu=4065,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=78211
[08:30:24] Thread state: elapsed=10001,cpu=3138,max=ReadController:ReadProcessor(840/8%),mem:max=2621440,tot=2621440,free=105076
[08:30:34] Thread state: elapsed=10000,cpu=4157,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=151942
[08:30:44] Thread state: elapsed=10000,cpu=3818,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=92176
[08:30:54] Thread state: elapsed=10000,cpu=5306,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=136212
[08:31:04] Thread state: elapsed=10000,cpu=4046,max=PeerControlScheduler(1090/10%),mem:max=2621440,tot=2621440,free=75597
[08:31:14] Thread state: elapsed=10000,cpu=3266,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=134053
[08:31:24] Thread state: elapsed=10000,cpu=5118,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=148554
[08:31:34] Thread state: elapsed=10000,cpu=5037,max=Global Status Checker(1350/13%),mem:max=2621440,tot=2621440,free=172479
[08:31:44] Thread state: elapsed=10000,cpu=5686,max=Global Status Checker(2730/27%),mem:max=2621440,tot=2621440,free=168652
[08:31:44]     java.base@21.0.3/jdk.internal.misc.Unsafe.park(Native Method), java.base@21.0.3/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269), java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:756), java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1126), java.base@21.0.3/java.util.concurrent.Semaphore.tryAcquire(Semaphore.java:415), app//com.biglybt.core.util.AESemaphore.reserve(AESemaphore.java:83), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:667), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[08:31:54] Thread state: elapsed=10000,cpu=3326,max=ReadController:ReadProcessor(950/9%),mem:max=2621440,tot=2621440,free=94930
[08:32:04] Thread state: elapsed=10000,cpu=3987,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=141433
[08:32:14] Thread state: elapsed=10001,cpu=4038,max=PeerControlScheduler(1230/12%),mem:max=2621440,tot=2621440,free=179017
[08:32:24] Thread state: elapsed=10000,cpu=5318,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=83445
[08:32:34] Thread state: elapsed=10000,cpu=3198,max=ReadController:ReadProcessor(790/7%),mem:max=2621440,tot=2621440,free=170787
[08:32:44] Thread state: elapsed=10000,cpu=3686,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=104566
[08:32:54] Thread state: elapsed=10000,cpu=5474,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=139885
[08:33:04] Thread state: elapsed=10000,cpu=3675,max=PeerControlScheduler(1010/10%),mem:max=2621440,tot=2621440,free=39527
[08:33:14] Thread state: elapsed=10001,cpu=2016,max=PeerControlScheduler(600/6%),mem:max=2621440,tot=2621440,free=155038
[08:33:24] Thread state: elapsed=10000,cpu=5158,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=73067
[08:33:34] Thread state: elapsed=10000,cpu=3376,max=ReadController:ReadProcessor(820/8%),mem:max=2621440,tot=2621440,free=116790
[08:33:44] Thread state: elapsed=10000,cpu=4066,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=171232
[08:33:54] Thread state: elapsed=10001,cpu=5335,max=PeerControlScheduler(1200/12%),mem:max=2621440,tot=2621440,free=97149
[08:34:04] Thread state: elapsed=10000,cpu=4405,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=152141
[08:34:14] Thread state: elapsed=10000,cpu=4175,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=72141
[08:34:24] Thread state: elapsed=10000,cpu=4067,max=tag:constraints(980/9%),mem:max=2621440,tot=2621440,free=114490
[08:34:34] Thread state: elapsed=10000,cpu=4095,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=167173
[08:34:45] Thread state: elapsed=10000,cpu=4136,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=88981
[08:34:55] Thread state: elapsed=10000,cpu=3950,max=ReadController:ReadProcessor(980/9%),mem:max=2621440,tot=2621440,free=142085
[08:35:05] Thread state: elapsed=10000,cpu=4360,max=PeerControlScheduler(1090/10%),mem:max=2621440,tot=2621440,free=84983
[08:35:15] Thread state: elapsed=10000,cpu=3260,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=156968
[08:35:25] Thread state: elapsed=10004,cpu=5169,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=197227
[08:35:35] Thread state: elapsed=10001,cpu=3196,max=ReadController:ReadProcessor(890/8%),mem:max=2621440,tot=2621440,free=143419
[08:35:45] Thread state: elapsed=10001,cpu=4297,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=188908
[08:35:55] Thread state: elapsed=10000,cpu=4226,max=PeerControlScheduler(1000/10%),mem:max=2621440,tot=2621440,free=111376
[08:36:05] Thread state: elapsed=10000,cpu=4195,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=175888
[08:36:15] Thread state: elapsed=10000,cpu=3835,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=96190
[08:36:25] Thread state: elapsed=10000,cpu=4381,max=tag:constraints(1000/10%),mem:max=2621440,tot=2621440,free=132074
[08:36:35] Thread state: elapsed=10000,cpu=3796,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=185132
[08:36:45] Thread state: elapsed=10000,cpu=4336,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=103067
[08:36:53] Thread state: elapsed=10199,cpu=6265,max=Global Status Checker(3480/34%),mem:max=2621440,tot=2621440,free=219964
[08:36:53]     java.base@21.0.3/java.io.FileDescriptor.sync0(Native Method), java.base@21.0.3/java.io.FileDescriptor.sync(FileDescriptor.java:212), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:314), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.setResumeData(DownloadManagerStateImpl.java:1194), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:1032), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:987), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:973), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveResumeData(DiskManagerImpl.java:2654), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveResumeData(DownloadManagerImpl.java:2303), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:488), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[08:37:05] Thread state: elapsed=10000,cpu=4596,max=ReadController:ReadProcessor(1030/10%),mem:max=2621440,tot=2621440,free=192407
[08:37:15] Thread state: elapsed=10000,cpu=2785,max=PeerControlScheduler(810/8%),mem:max=2621440,tot=2621440,free=171981
[08:37:25] Thread state: elapsed=10000,cpu=5105,max=ReadController:ReadProcessor(1020/10%),mem:max=2621440,tot=2621440,free=192929
[08:37:35] Thread state: elapsed=10000,cpu=3355,max=ReadController:ReadProcessor(890/8%),mem:max=2621440,tot=2621440,free=129968
[08:37:45] Thread state: elapsed=10000,cpu=3947,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=179249
[08:37:55] Thread state: elapsed=10000,cpu=5131,max=PeerControlScheduler(1010/10%),mem:max=2621440,tot=2621440,free=93234
[08:38:05] Thread state: elapsed=10000,cpu=4187,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=133954
[08:38:15] Thread state: elapsed=10001,cpu=3564,max=PeerControlScheduler(1010/10%),mem:max=2621440,tot=2621440,free=169199
[08:38:25] Thread state: elapsed=10000,cpu=4347,max=tag:constraints(980/9%),mem:max=2621440,tot=2621440,free=191485
[08:38:35] Thread state: elapsed=10001,cpu=4544,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=110487
[08:38:45] Thread state: elapsed=10000,cpu=4213,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=138291
[08:38:55] Thread state: elapsed=10000,cpu=5066,max=ReadController:ReadProcessor(1000/10%),mem:max=2621440,tot=2621440,free=145692
[08:39:05] Thread state: elapsed=10000,cpu=3255,max=ReadController:ReadProcessor(890/8%),mem:max=2621440,tot=2621440,free=89997
[08:39:15] Thread state: elapsed=10000,cpu=3356,max=PeerControlScheduler(870/8%),mem:max=2621440,tot=2621440,free=170783
[08:39:25] Thread state: elapsed=10001,cpu=5242,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=190367
[08:39:35] Thread state: elapsed=10000,cpu=3116,max=ReadController:ReadProcessor(850/8%),mem:max=2621440,tot=2621440,free=130991
[08:39:45] Thread state: elapsed=10000,cpu=4224,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=183099
[08:39:55] Thread state: elapsed=10000,cpu=4014,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=109898
[08:40:05] Thread state: elapsed=10000,cpu=4264,max=PeerControlScheduler(1090/10%),mem:max=2621440,tot=2621440,free=165609
[08:40:15] Thread state: elapsed=10000,cpu=3905,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=103484
[08:40:25] Thread state: elapsed=10000,cpu=4683,max=ReadController:ReadProcessor(950/9%),mem:max=2621440,tot=2621440,free=140746
[08:40:35] Thread state: elapsed=10001,cpu=3735,max=ReadController:ReadProcessor(960/9%),mem:max=2621440,tot=2621440,free=187548
[08:40:45] Thread state: elapsed=10000,cpu=4396,max=PeerControlScheduler(1230/12%),mem:max=2621440,tot=2621440,free=123668
[08:40:55] Thread state: elapsed=10000,cpu=5272,max=PeerControlScheduler(1010/10%),mem:max=2621440,tot=2621440,free=165757
[08:41:05] Thread state: elapsed=10000,cpu=4402,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=91538
[08:41:15] Thread state: elapsed=10000,cpu=2892,max=PeerControlScheduler(800/8%),mem:max=2621440,tot=2621440,free=167295
[08:41:25] Thread state: elapsed=10000,cpu=5376,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=81940
[08:41:35] Thread state: elapsed=10000,cpu=3125,max=ReadController:ReadProcessor(840/8%),mem:max=2621440,tot=2621440,free=134153
[08:41:45] Thread state: elapsed=10000,cpu=3925,max=PeerControlScheduler(1010/10%),mem:max=2621440,tot=2621440,free=191917
[08:41:55] Thread state: elapsed=10000,cpu=5014,max=tag:constraints(1190/11%),mem:max=2621440,tot=2621440,free=111872
[08:42:04] Thread state: elapsed=10659,cpu=6555,max=Global Status Checker(3370/33%),mem:max=2621440,tot=2621440,free=191581
[08:42:04]     java.base@21.0.3/java.util.concurrent.locks.AbstractQueuedSynchronizer.release(AbstractQueuedSynchronizer.java:1060), java.base@21.0.3/java.util.concurrent.locks.ReentrantLock.unlock(ReentrantLock.java:494), java.base@21.0.3/jdk.internal.misc.InternalLock.unlock(InternalLock.java:78), java.base@21.0.3/java.io.BufferedInputStream.read(BufferedInputStream.java:314), app//com.biglybt.core.util.BDecoder.decodeInputStream(BDecoder.java:397), app//com.biglybt.core.util.BDecoder.decodeInputStream(BDecoder.java:617), app//com.biglybt.core.util.BDecoder.decodeInputStream(BDecoder.java:534), app//com.biglybt.core.util.BDecoder.decodeStream(BDecoder.java:222), app//com.biglybt.core.util.BDecoder.decodeStream(BDecoder.java:210), app//com.biglybt.core.util.FileUtil.writeResilientFile(FileUtil.java:698), app//com.biglybt.core.util.FileUtil.writeResilientFile(FileUtil.java:660), app//com.biglybt.core.util.FileUtil.writeResilientFile(FileUtil.java:639), app//com.biglybt.core.util.FileUtil.writeResilientConfigFile(FileUtil.java:603), app//com.biglybt.core.global.impl.GlobalManagerImpl.saveDownloads(GlobalManagerImpl.java:3179), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:429), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[08:42:16] Thread state: elapsed=10000,cpu=3855,max=PeerControlScheduler(800/8%),mem:max=2621440,tot=2621440,free=116460
[08:42:26] Thread state: elapsed=10000,cpu=3596,max=ReadController:ReadProcessor(940/9%),mem:max=2621440,tot=2621440,free=149294
[08:42:36] Thread state: elapsed=10001,cpu=5166,max=ReadController:ReadProcessor(1060/10%),mem:max=2621440,tot=2621440,free=179580
[08:42:46] Thread state: elapsed=10000,cpu=4058,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=104094
[08:42:56] Thread state: elapsed=10000,cpu=4296,max=PeerControlScheduler(1010/10%),mem:max=2621440,tot=2621440,free=164357
[08:43:06] Thread state: elapsed=10000,cpu=4764,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=94824
[08:43:16] Thread state: elapsed=10000,cpu=3164,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=163879
[08:43:26] Thread state: elapsed=10000,cpu=4552,max=PeerControlScheduler(1210/12%),mem:max=2621440,tot=2621440,free=94873
[08:43:36] Thread state: elapsed=10000,cpu=5407,max=PeerControlScheduler(1240/12%),mem:max=2621440,tot=2621440,free=135889
[08:43:46] Thread state: elapsed=10000,cpu=3833,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=176375
[08:43:56] Thread state: elapsed=10000,cpu=3575,max=ReadController:ReadProcessor(900/9%),mem:max=2621440,tot=2621440,free=96576
[08:44:06] Thread state: elapsed=10000,cpu=5561,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=97278
[08:44:16] Thread state: elapsed=10000,cpu=3993,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=140537
[08:44:26] Thread state: elapsed=10000,cpu=4330,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=183363
[08:44:36] Thread state: elapsed=10000,cpu=3435,max=ReadController:ReadProcessor(910/9%),mem:max=2621440,tot=2621440,free=108811
[08:44:46] Thread state: elapsed=10000,cpu=4175,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=149330
[08:44:56] Thread state: elapsed=10000,cpu=4385,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=83661
[08:45:06] Thread state: elapsed=10000,cpu=4993,max=ReadController:ReadProcessor(980/9%),mem:max=2621440,tot=2621440,free=133502
[08:45:16] Thread state: elapsed=10000,cpu=3201,max=PeerControlScheduler(840/8%),mem:max=2621440,tot=2621440,free=150124
[08:45:26] Thread state: elapsed=10000,cpu=4217,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=91956
[08:45:36] Thread state: elapsed=10000,cpu=4016,max=tag:constraints(970/9%),mem:max=2621440,tot=2621440,free=138794
[08:45:46] Thread state: elapsed=10000,cpu=4414,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=76084
[08:45:56] Thread state: elapsed=10000,cpu=4636,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=65415
[08:46:06] Thread state: elapsed=10000,cpu=5153,max=ReadController:ReadProcessor(1080/10%),mem:max=2621440,tot=2621440,free=104895
[08:46:16] Thread state: elapsed=10000,cpu=3576,max=ReadController:ReadProcessor(890/8%),mem:max=2621440,tot=2621440,free=148585
[08:46:26] Thread state: elapsed=10000,cpu=3915,max=ReadController:ReadProcessor(910/9%),mem:max=2621440,tot=2621440,free=69398
[08:46:36] Thread state: elapsed=10000,cpu=5271,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=110584
[08:46:46] Thread state: elapsed=10000,cpu=4385,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=112517
[08:46:56] Thread state: elapsed=10000,cpu=3705,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=120431
[08:47:06] Thread state: elapsed=10000,cpu=4305,max=tag:constraints(950/9%),mem:max=2621440,tot=2621440,free=132536
[08:47:16] Thread state: elapsed=10000,cpu=4952,max=Global Status Checker(2340/23%),mem:max=2621440,tot=2621440,free=182657
[08:47:26] Thread state: elapsed=10000,cpu=3706,max=Global Status Checker(1780/17%),mem:max=2621440,tot=2621440,free=80122
[08:47:36] Thread state: elapsed=10000,cpu=4335,max=ReadController:ReadProcessor(960/9%),mem:max=2621440,tot=2621440,free=128225
[08:47:46] Thread state: elapsed=10000,cpu=3777,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=173893
[08:47:56] Thread state: elapsed=10000,cpu=4185,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=103585
[08:48:06] Thread state: elapsed=10000,cpu=4999,max=ReadController:ReadProcessor(1040/10%),mem:max=2621440,tot=2621440,free=138953
[08:48:16] Thread state: elapsed=10000,cpu=3766,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=73581
[08:48:26] Thread state: elapsed=10000,cpu=3357,max=ReadController:ReadProcessor(920/9%),mem:max=2621440,tot=2621440,free=122524
[08:48:36] Thread state: elapsed=10000,cpu=4776,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=132643
[08:48:46] Thread state: elapsed=10000,cpu=3931,max=PeerControlScheduler(1220/12%),mem:max=2621440,tot=2621440,free=79352
[08:48:56] Thread state: elapsed=10000,cpu=3617,max=ReadController:ReadProcessor(910/9%),mem:max=2621440,tot=2621440,free=51231
[08:49:06] Thread state: elapsed=10001,cpu=3056,max=PeerControlScheduler(790/7%),mem:max=2621440,tot=2621440,free=90085
[08:49:16] Thread state: elapsed=10001,cpu=3198,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=121044
[08:49:26] Thread state: elapsed=10000,cpu=4358,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=180626
[08:49:36] Thread state: elapsed=10000,cpu=4406,max=tag:constraints(980/9%),mem:max=2621440,tot=2621440,free=86266
[08:49:46] Thread state: elapsed=10000,cpu=3896,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=110228
[08:49:56] Thread state: elapsed=10000,cpu=4097,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=59320
[08:50:06] Thread state: elapsed=10000,cpu=4300,max=ReadController:ReadProcessor(990/9%),mem:max=2621440,tot=2621440,free=81669
[08:50:16] Thread state: elapsed=10000,cpu=3586,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=129784
[08:50:26] Thread state: elapsed=10000,cpu=3698,max=ReadController:ReadProcessor(960/9%),mem:max=2621440,tot=2621440,free=79600
[08:50:36] Thread state: elapsed=10000,cpu=5389,max=PeerControlScheduler(1140/11%),mem:max=2621440,tot=2621440,free=96162
[08:50:46] Thread state: elapsed=10000,cpu=4391,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=137148
[08:50:56] Thread state: elapsed=10000,cpu=4286,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=83942
[08:51:06] Thread state: elapsed=10000,cpu=3368,max=PeerControlScheduler(850/8%),mem:max=2621440,tot=2621440,free=160907
[08:51:17] Thread state: elapsed=10000,cpu=3139,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=143905
[08:51:27] Thread state: elapsed=10000,cpu=4119,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=83458
[08:51:37] Thread state: elapsed=10000,cpu=4727,max=ReadController:ReadProcessor(970/9%),mem:max=2621440,tot=2621440,free=123665
[08:51:47] Thread state: elapsed=10000,cpu=3536,max=ReadController:ReadProcessor(870/8%),mem:max=2621440,tot=2621440,free=159041
[08:51:57] Thread state: elapsed=10000,cpu=4262,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=90280
[08:52:07] Thread state: elapsed=10000,cpu=4896,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=136842
[08:52:17] Thread state: elapsed=10000,cpu=4254,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=160492
[08:52:26] Thread state: elapsed=11960,cpu=3425,max=ReadController:ReadProcessor(820/8%),mem:max=2621440,tot=2621440,free=143313
[08:52:39] Thread state: elapsed=10000,cpu=7676,max=Global Status Checker(3480/34%),mem:max=2621440,tot=2621440,free=71501
[08:52:39]     java.base@21.0.3/java.io.FileDescriptor.sync0(Native Method), java.base@21.0.3/java.io.FileDescriptor.sync(FileDescriptor.java:212), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:314), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.setResumeData(DownloadManagerStateImpl.java:1194), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:1032), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:987), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:973), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveResumeData(DiskManagerImpl.java:2654), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveResumeData(DownloadManagerImpl.java:2303), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:488), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[08:52:49] Thread state: elapsed=10000,cpu=2846,max=ReadController:ReadProcessor(670/6%),mem:max=2621440,tot=2621440,free=178014
[08:52:59] Thread state: elapsed=10000,cpu=3686,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=122370
[08:53:08] Thread state: elapsed=11624,cpu=4769,max=tag:constraints(950/9%),mem:max=2621440,tot=2621440,free=197817
[08:53:20] Thread state: elapsed=10000,cpu=3926,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=107638
[08:53:30] Thread state: elapsed=10000,cpu=4073,max=ReadController:ReadProcessor(950/9%),mem:max=2621440,tot=2621440,free=173525
[08:53:40] Thread state: elapsed=10000,cpu=4916,max=tag:constraints(1020/10%),mem:max=2621440,tot=2621440,free=88254
[08:53:50] Thread state: elapsed=10000,cpu=4376,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=124214
[08:54:00] Thread state: elapsed=10000,cpu=4456,max=PeerControlScheduler(1090/10%),mem:max=2621440,tot=2621440,free=155157
[08:54:10] Thread state: elapsed=10000,cpu=4025,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=77956
[08:54:20] Thread state: elapsed=10000,cpu=3546,max=PeerControlScheduler(830/8%),mem:max=2621440,tot=2621440,free=111591
[08:54:30] Thread state: elapsed=10000,cpu=3896,max=PeerControlScheduler(950/9%),mem:max=2621440,tot=2621440,free=58807
[08:54:40] Thread state: elapsed=10000,cpu=5362,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=92895
[08:54:50] Thread state: elapsed=10001,cpu=4297,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=131482
[08:55:00] Thread state: elapsed=10001,cpu=4454,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=68479
[08:55:08] Thread state: elapsed=10045,cpu=3386,max=tag:constraints(940/9%),mem:max=2621440,tot=2621440,free=193776
[08:55:20] Thread state: elapsed=10000,cpu=4055,max=PeerControlScheduler(1090/10%),mem:max=2621440,tot=2621440,free=120685
[08:55:30] Thread state: elapsed=10000,cpu=4397,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=174547
[08:55:40] Thread state: elapsed=10000,cpu=5198,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=95845
[08:55:50] Thread state: elapsed=10000,cpu=3454,max=ReadController:ReadProcessor(880/8%),mem:max=2621440,tot=2621440,free=151991
[08:56:01] Thread state: elapsed=10000,cpu=3885,max=ReadController:ReadProcessor(930/9%),mem:max=2621440,tot=2621440,free=79572
[08:56:10] Thread state: elapsed=10000,cpu=4715,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=127506
[08:56:21] Thread state: elapsed=10000,cpu=4135,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=59224
[08:56:31] Thread state: elapsed=10000,cpu=3867,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=89304
[08:56:41] Thread state: elapsed=10000,cpu=4267,max=tag:constraints(1010/10%),mem:max=2621440,tot=2621440,free=110247
[08:56:49] Thread state: elapsed=10634,cpu=2795,max=PeerControlScheduler(670/6%),mem:max=2621440,tot=2621440,free=181691
[08:57:01] Thread state: elapsed=10000,cpu=3956,max=ReadController:ReadProcessor(940/9%),mem:max=2621440,tot=2621440,free=64458
[08:57:09] Thread state: elapsed=10216,cpu=2946,max=tag:constraints(950/9%),mem:max=2621440,tot=2621440,free=155763
[08:57:21] Thread state: elapsed=10001,cpu=3439,max=ReadController:ReadProcessor(900/9%),mem:max=2621440,tot=2621440,free=48605
[08:57:30] Thread state: elapsed=11232,cpu=2517,max=PeerControlScheduler(730/7%),mem:max=2621440,tot=2621440,free=146318
[08:57:43] Thread state: elapsed=10000,cpu=4110,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=55568
[08:57:47] Thread state: elapsed=11381,cpu=1593,max=ReadController:ReadProcessor(330/3%),mem:max=2621440,tot=2621440,free=132096
[08:57:59] Thread state: elapsed=11869,cpu=458,max=Global Status Checker(280/2%),mem:max=2621440,tot=2621440,free=125379
[08:58:00] Thread state: elapsed=11966,cpu=938,max=ReadController:ReadProcessor(250/2%),mem:max=2621440,tot=2621440,free=130550
[08:58:23] Thread state: elapsed=11705,cpu=1089,max=Global Status Checker(240/2%),mem:max=2621440,tot=2621440,free=138929
[08:58:40] Thread state: elapsed=10004,cpu=1171,max=ReadController:ReadProcessor(310/3%),mem:max=2621440,tot=2621440,free=127579
[08:58:40] Thread state: elapsed=11833,cpu=880,max=Global Status Checker(240/2%),mem:max=2621440,tot=2621440,free=114294
[08:59:01] Thread state: elapsed=11867,cpu=1122,max=ReadController:ReadProcessor(270/2%),mem:max=2621440,tot=2621440,free=128649
[08:59:02] Thread state: elapsed=12157,cpu=1344,max=tag:constraints(300/3%),mem:max=2621440,tot=2621440,free=89061
[08:59:18] Thread state: elapsed=12029,cpu=1312,max=Global Status Checker(240/2%),mem:max=2621440,tot=2621440,free=94032
[08:59:35] Thread state: elapsed=12134,cpu=1602,max=tag:constraints(340/3%),mem:max=2621440,tot=2621440,free=132426
[08:59:54] Thread state: elapsed=12162,cpu=1552,max=Global Status Checker(300/3%),mem:max=2621440,tot=2621440,free=127411
[08:59:55] Thread state: elapsed=12033,cpu=1272,max=ReadController:ReadSelector(280/2%),mem:max=2621440,tot=2621440,free=109800
[09:00:14] Thread state: elapsed=10004,cpu=1561,max=tag:constraints(310/3%),mem:max=2621440,tot=2621440,free=140175
[09:00:31] Thread state: elapsed=10001,cpu=4823,max=tag:constraints(1300/13%),mem:max=2621440,tot=2621440,free=90470
[09:00:41] Thread state: elapsed=10000,cpu=2846,max=PeerControlScheduler(710/7%),mem:max=2621440,tot=2621440,free=150278
[09:00:51] Thread state: elapsed=10001,cpu=4169,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=70893
[09:01:01] Thread state: elapsed=10001,cpu=4029,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=112551
[09:01:11] Thread state: elapsed=10000,cpu=3659,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=55612
[09:01:21] Thread state: elapsed=10000,cpu=2458,max=ReadController:ReadProcessor(700/7%),mem:max=2621440,tot=2621440,free=79534
[09:01:31] Thread state: elapsed=10001,cpu=3257,max=tag:constraints(830/8%),mem:max=2621440,tot=2621440,free=113194
[09:01:39] Thread state: elapsed=10000,cpu=3349,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=148476
[09:01:51] Thread state: elapsed=10000,cpu=4377,max=PeerControlScheduler(1240/12%),mem:max=2621440,tot=2621440,free=57616
[09:02:01] Thread state: elapsed=10000,cpu=3045,max=PeerControlScheduler(820/8%),mem:max=2621440,tot=2621440,free=65305
[09:02:11] Thread state: elapsed=10000,cpu=3181,max=PeerControlScheduler(920/9%),mem:max=2621440,tot=2621440,free=99800
[09:02:21] Thread state: elapsed=10000,cpu=3372,max=ReadController:ReadProcessor(820/8%),mem:max=2621440,tot=2621440,free=55522
[09:02:31] Thread state: elapsed=10000,cpu=4148,max=tag:constraints(960/9%),mem:max=2621440,tot=2621440,free=75476
[09:02:41] Thread state: elapsed=10000,cpu=3089,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=133854
[09:02:51] Thread state: elapsed=10001,cpu=4281,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=51559
[09:03:01] Thread state: elapsed=10000,cpu=4018,max=tag:constraints(950/9%),mem:max=2621440,tot=2621440,free=104259
[09:03:11] Thread state: elapsed=10000,cpu=4016,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=33924
[09:03:21] Thread state: elapsed=10000,cpu=2098,max=PeerControlScheduler(620/6%),mem:max=2621440,tot=2621440,free=153534
[09:03:31] Thread state: elapsed=10000,cpu=3978,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=73015
[09:03:41] Thread state: elapsed=10001,cpu=3287,max=ReadController:ReadProcessor(780/7%),mem:max=2621440,tot=2621440,free=143128
[09:03:51] Thread state: elapsed=10000,cpu=4330,max=PeerControlScheduler(1200/12%),mem:max=2621440,tot=2621440,free=70447
[09:04:01] Thread state: elapsed=10000,cpu=3495,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=115101
[09:04:11] Thread state: elapsed=10000,cpu=4736,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=45144
[09:04:21] Thread state: elapsed=10000,cpu=2806,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=131143
[09:04:31] Thread state: elapsed=10000,cpu=4600,max=PeerControlScheduler(1210/12%),mem:max=2621440,tot=2621440,free=48832
[09:04:41] Thread state: elapsed=10001,cpu=3319,max=PeerControlScheduler(970/9%),mem:max=2621440,tot=2621440,free=84382
[09:04:51] Thread state: elapsed=10000,cpu=3491,max=ReadController:ReadProcessor(860/8%),mem:max=2621440,tot=2621440,free=150499
[09:05:01] Thread state: elapsed=10001,cpu=4220,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=69949
[09:05:11] Thread state: elapsed=10000,cpu=4296,max=tag:constraints(960/9%),mem:max=2621440,tot=2621440,free=120418
[09:05:21] Thread state: elapsed=10000,cpu=3188,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=153186
[09:05:31] Thread state: elapsed=10000,cpu=4219,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=74286
[09:05:41] Thread state: elapsed=10000,cpu=4651,max=Global Status Checker(1050/10%),mem:max=2621440,tot=2621440,free=54249
[09:05:51] Thread state: elapsed=11679,cpu=4867,max=Global Status Checker(2630/26%),mem:max=2621440,tot=2621440,free=176771
[09:05:51]     java.base@21.0.3/java.io.FileDescriptor.sync0(Native Method), java.base@21.0.3/java.io.FileDescriptor.sync(FileDescriptor.java:212), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:314), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.setResumeData(DownloadManagerStateImpl.java:1194), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:1032), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:987), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:973), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveResumeData(DiskManagerImpl.java:2654), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveResumeData(DownloadManagerImpl.java:2303), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:488), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[09:06:03] Thread state: elapsed=10000,cpu=3197,max=ReadController:ReadProcessor(760/7%),mem:max=2621440,tot=2621440,free=70058
[09:06:13] Thread state: elapsed=10000,cpu=4886,max=ReadController:ReadProcessor(1030/10%),mem:max=2621440,tot=2621440,free=121311
[09:06:23] Thread state: elapsed=10000,cpu=4047,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=54408
[09:06:33] Thread state: elapsed=10000,cpu=4116,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=103573
[09:06:43] Thread state: elapsed=10000,cpu=5288,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=115658
[09:06:53] Thread state: elapsed=10000,cpu=4177,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=54142
[09:07:03] Thread state: elapsed=10000,cpu=3388,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=133784
[09:07:13] Thread state: elapsed=10000,cpu=5409,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=154840
[09:07:23] Thread state: elapsed=10000,cpu=3094,max=PeerControlScheduler(950/9%),mem:max=2621440,tot=2621440,free=144820
[09:07:33] Thread state: elapsed=10000,cpu=4299,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=85416
[09:07:43] Thread state: elapsed=10001,cpu=4605,max=tag:constraints(990/9%),mem:max=2621440,tot=2621440,free=107361
[09:07:53] Thread state: elapsed=10000,cpu=3626,max=ReadController:ReadProcessor(900/9%),mem:max=2621440,tot=2621440,free=46700
[09:08:03] Thread state: elapsed=10000,cpu=3387,max=PeerControlScheduler(850/8%),mem:max=2621440,tot=2621440,free=120317
[09:08:13] Thread state: elapsed=10000,cpu=3891,max=PeerControlScheduler(1020/10%),mem:max=2621440,tot=2621440,free=148646
[09:08:23] Thread state: elapsed=10000,cpu=3366,max=ReadController:ReadProcessor(830/8%),mem:max=2621440,tot=2621440,free=86448
[09:08:31] Thread state: elapsed=10000,cpu=3380,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=159769
[09:08:43] Thread state: elapsed=10001,cpu=5188,max=PeerControlScheduler(1140/11%),mem:max=2621440,tot=2621440,free=52026
[09:08:53] Thread state: elapsed=10000,cpu=3329,max=PeerControlScheduler(900/9%),mem:max=2621440,tot=2621440,free=90578
[09:09:03] Thread state: elapsed=10000,cpu=4569,max=PeerControlScheduler(1260/12%),mem:max=2621440,tot=2621440,free=146240
[09:09:13] Thread state: elapsed=10000,cpu=5579,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=60690
[09:09:23] Thread state: elapsed=10000,cpu=2406,max=PeerControlScheduler(750/7%),mem:max=2621440,tot=2621440,free=142835
[09:09:33] Thread state: elapsed=10001,cpu=4347,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=69377
[09:09:43] Thread state: elapsed=10001,cpu=3036,max=PeerControlScheduler(920/9%),mem:max=2621440,tot=2621440,free=130359
[09:09:53] Thread state: elapsed=10000,cpu=4029,max=PeerControlScheduler(1050/10%),mem:max=2621440,tot=2621440,free=141713
[09:10:03] Thread state: elapsed=10000,cpu=3256,max=ReadController:ReadProcessor(890/8%),mem:max=2621440,tot=2621440,free=63405
[09:10:11] Thread state: elapsed=10005,cpu=3249,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=167303
[09:10:23] Thread state: elapsed=10000,cpu=3126,max=ReadController:ReadProcessor(900/9%),mem:max=2621440,tot=2621440,free=80911
[09:10:32] Thread state: elapsed=11272,cpu=3727,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=164556
[09:10:45] Thread state: elapsed=10000,cpu=3497,max=ReadController:ReadProcessor(1000/10%),mem:max=2621440,tot=2621440,free=61632
[09:10:55] Thread state: elapsed=10001,cpu=3008,max=PeerControlScheduler(820/8%),mem:max=2621440,tot=2621440,free=143143
[09:11:05] Thread state: elapsed=10000,cpu=4450,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=72438
[09:11:14] Thread state: elapsed=11843,cpu=2674,max=Global Status Checker(1120/11%),mem:max=2621440,tot=2621440,free=122247
[09:11:27] Thread state: elapsed=10004,cpu=2486,max=tag:constraints(590/5%),mem:max=2621440,tot=2621440,free=60187
[09:11:37] Thread state: elapsed=10009,cpu=2256,max=Global Status Checker(770/7%),mem:max=2621440,tot=2621440,free=36539
[09:11:37] Thread state: elapsed=11524,cpu=957,max=Global Status Checker(270/2%),mem:max=2621440,tot=2621440,free=144418
[09:11:51] Thread state: elapsed=11762,cpu=1216,max=Global Status Checker(300/3%),mem:max=2621440,tot=2621440,free=136201
[09:11:52] Thread state: elapsed=11548,cpu=1038,max=ReadController:ReadProcessor(300/3%),mem:max=2621440,tot=2621440,free=135252
[09:12:21] Thread state: elapsed=10000,cpu=1807,max=ReadController:ReadProcessor(510/5%),mem:max=2621440,tot=2621440,free=123913
[09:12:29] Thread state: elapsed=10000,cpu=2858,max=Global Status Checker(900/9%),mem:max=2621440,tot=2621440,free=138609
[09:12:42] Thread state: elapsed=10000,cpu=4729,max=PeerControlScheduler(1340/13%),mem:max=2621440,tot=2621440,free=65458
[09:12:52] Thread state: elapsed=10000,cpu=3089,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=126126
[09:13:02] Thread state: elapsed=10000,cpu=5132,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=42121
[09:13:12] Thread state: elapsed=10000,cpu=2967,max=ReadController:ReadProcessor(770/7%),mem:max=2621440,tot=2621440,free=137740
[09:13:22] Thread state: elapsed=10000,cpu=4356,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=67744
[09:13:29] Thread state: elapsed=10000,cpu=2870,max=ReadController:ReadProcessor(770/7%),mem:max=2621440,tot=2621440,free=128505
[09:13:42] Thread state: elapsed=10000,cpu=4576,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=62057
[09:13:52] Thread state: elapsed=10000,cpu=3427,max=ReadController:ReadProcessor(880/8%),mem:max=2621440,tot=2621440,free=113222
[09:14:02] Thread state: elapsed=12083,cpu=3845,max=ReadController:ReadProcessor(910/9%),mem:max=2621440,tot=2621440,free=162741
[09:14:14] Thread state: elapsed=10001,cpu=3986,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=89528
[09:14:24] Thread state: elapsed=10000,cpu=3317,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=152659
[09:14:34] Thread state: elapsed=10001,cpu=4329,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=68449
[09:14:44] Thread state: elapsed=10000,cpu=3218,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=136741
[09:14:54] Thread state: elapsed=10000,cpu=4239,max=PeerControlScheduler(1220/12%),mem:max=2621440,tot=2621440,free=53356
[09:15:04] Thread state: elapsed=10000,cpu=3120,max=PeerControlScheduler(850/8%),mem:max=2621440,tot=2621440,free=109166
[09:15:14] Thread state: elapsed=10001,cpu=4259,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=34962
[09:15:24] Thread state: elapsed=10000,cpu=3308,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=97172
[09:15:34] Thread state: elapsed=10000,cpu=3117,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=114621
[09:15:44] Thread state: elapsed=10000,cpu=4679,max=PeerControlScheduler(1250/12%),mem:max=2621440,tot=2621440,free=39953
[09:15:54] Thread state: elapsed=10000,cpu=3587,max=PeerControlScheduler(920/9%),mem:max=2621440,tot=2621440,free=82197
[09:16:02] Thread state: elapsed=10800,cpu=3677,max=PeerControlScheduler(900/9%),mem:max=2621440,tot=2621440,free=158802
[09:16:15] Thread state: elapsed=10000,cpu=5406,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=50787
[09:16:25] Thread state: elapsed=10000,cpu=3260,max=PeerControlScheduler(960/9%),mem:max=2621440,tot=2621440,free=109547
[09:16:35] Thread state: elapsed=10001,cpu=4206,max=ReadController:ReadProcessor(1020/10%),mem:max=2621440,tot=2621440,free=35486
[09:16:45] Thread state: elapsed=10000,cpu=3607,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=90101
[09:16:55] Thread state: elapsed=10000,cpu=3267,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=142320
[09:17:05] Thread state: elapsed=10000,cpu=4303,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=83750
[09:17:15] Thread state: elapsed=10000,cpu=4116,max=PeerControlScheduler(1020/10%),mem:max=2621440,tot=2621440,free=110895
[09:17:25] Thread state: elapsed=10000,cpu=3983,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=48048
[09:17:35] Thread state: elapsed=10000,cpu=2172,max=PeerControlScheduler(630/6%),mem:max=2621440,tot=2621440,free=121342
[09:17:40] Thread state: elapsed=10003,cpu=1320,max=Global Status Checker(520/5%),mem:max=2621440,tot=2621440,free=133227
[09:17:48] Thread state: elapsed=10068,cpu=1946,max=tag:constraints(540/5%),mem:max=2621440,tot=2621440,free=145021
[09:17:49] Thread state: elapsed=11918,cpu=1516,max=tag:constraints(380/3%),mem:max=2621440,tot=2621440,free=145400
[09:18:10] Thread state: elapsed=11944,cpu=1255,max=Global Status Checker(270/2%),mem:max=2621440,tot=2621440,free=150166
[09:18:28] Thread state: elapsed=11971,cpu=1598,max=tag:constraints(330/3%),mem:max=2621440,tot=2621440,free=120569
[09:18:29] Thread state: elapsed=11694,cpu=1228,max=ReadController:ReadProcessor(310/3%),mem:max=2621440,tot=2621440,free=112588
[09:18:45] Thread state: elapsed=11882,cpu=979,max=Global Status Checker(290/2%),mem:max=2621440,tot=2621440,free=108016
[09:18:46] Thread state: elapsed=11837,cpu=1010,max=Global Status Checker(290/2%),mem:max=2621440,tot=2621440,free=141237
[09:19:07] Thread state: elapsed=12125,cpu=1308,max=Global Status Checker(290/2%),mem:max=2621440,tot=2621440,free=111402
[09:19:26] Thread state: elapsed=11613,cpu=990,max=Global Status Checker(260/2%),mem:max=2621440,tot=2621440,free=96691
[09:19:38] Thread state: elapsed=10008,cpu=2200,max=tag:constraints(630/6%),mem:max=2621440,tot=2621440,free=119678
[09:19:50] Thread state: elapsed=11718,cpu=3340,max=ReadController:ReadProcessor(870/8%),mem:max=2621440,tot=2621440,free=155955
[09:20:02] Thread state: elapsed=10000,cpu=2848,max=Global Status Checker(650/6%),mem:max=2621440,tot=2621440,free=50329
[09:20:12] Thread state: elapsed=10001,cpu=3107,max=PeerControlScheduler(780/7%),mem:max=2621440,tot=2621440,free=89541
[09:20:20] Thread state: elapsed=10628,cpu=3489,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=160203
[09:20:33] Thread state: elapsed=10000,cpu=3986,max=PeerControlScheduler(1240/12%),mem:max=2621440,tot=2621440,free=64266
[09:20:43] Thread state: elapsed=10000,cpu=3358,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=107585
[09:20:53] Thread state: elapsed=10000,cpu=4138,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=45490
[09:21:03] Thread state: elapsed=10000,cpu=3034,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=96325
[09:21:10] Thread state: elapsed=10000,cpu=3006,max=PeerControlScheduler(760/7%),mem:max=2621440,tot=2621440,free=150906
[09:21:23] Thread state: elapsed=10000,cpu=4086,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=83016
[09:21:33] Thread state: elapsed=10000,cpu=2891,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=128531
[09:21:43] Thread state: elapsed=10000,cpu=3016,max=PeerControlScheduler(770/7%),mem:max=2621440,tot=2621440,free=115729
[09:21:53] Thread state: elapsed=10000,cpu=4217,max=PeerControlScheduler(1200/12%),mem:max=2621440,tot=2621440,free=57973
[09:22:03] Thread state: elapsed=10000,cpu=3226,max=PeerControlScheduler(1020/10%),mem:max=2621440,tot=2621440,free=95137
[09:22:12] Thread state: elapsed=11667,cpu=3750,max=ReadController:ReadProcessor(800/8%),mem:max=2621440,tot=2621440,free=135597
[09:22:24] Thread state: elapsed=10000,cpu=3727,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=101891
[09:22:33] Thread state: elapsed=10878,cpu=3859,max=PeerControlScheduler(1030/10%),mem:max=2621440,tot=2621440,free=129048
[09:22:44] Thread state: elapsed=11565,cpu=3907,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=129911
[09:22:57] Thread state: elapsed=10000,cpu=3488,max=PeerControlScheduler(900/9%),mem:max=2621440,tot=2621440,free=123962
[09:23:06] Thread state: elapsed=11388,cpu=3350,max=ReadController:ReadProcessor(770/7%),mem:max=2621440,tot=2621440,free=142235
[09:23:18] Thread state: elapsed=10000,cpu=4076,max=PeerControlScheduler(1100/11%),mem:max=2621440,tot=2621440,free=46906
[09:23:28] Thread state: elapsed=10000,cpu=2885,max=PeerControlScheduler(900/9%),mem:max=2621440,tot=2621440,free=80576
[09:23:38] Thread state: elapsed=11904,cpu=3271,max=PeerControlScheduler(850/8%),mem:max=2621440,tot=2621440,free=139124
[09:23:50] Thread state: elapsed=10000,cpu=3848,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=61813
[09:24:00] Thread state: elapsed=10000,cpu=2981,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=117073
[09:24:10] Thread state: elapsed=10000,cpu=4387,max=PeerControlScheduler(1200/12%),mem:max=2621440,tot=2621440,free=53689
[09:24:20] Thread state: elapsed=10000,cpu=2920,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=93467
[09:24:30] Thread state: elapsed=10000,cpu=3138,max=PeerControlScheduler(840/8%),mem:max=2621440,tot=2621440,free=137575
[09:24:40] Thread state: elapsed=10000,cpu=3837,max=PeerControlScheduler(1240/12%),mem:max=2621440,tot=2621440,free=75055
[09:24:50] Thread state: elapsed=10000,cpu=3895,max=tag:constraints(970/9%),mem:max=2621440,tot=2621440,free=98180
[09:25:00] Thread state: elapsed=11616,cpu=3499,max=PeerControlScheduler(1150/11%),mem:max=2621440,tot=2621440,free=144824
[09:25:03] Thread state: elapsed=10526,cpu=1197,max=Global Status Checker(440/4%),mem:max=2621440,tot=2621440,free=141624
[09:25:13] Thread state: elapsed=12204,cpu=867,max=Global Status Checker(230/2%),mem:max=2621440,tot=2621440,free=123011
[09:25:13] Thread state: elapsed=12069,cpu=1036,max=ReadController:ReadProcessor(260/2%),mem:max=2621440,tot=2621440,free=123015
[09:25:40] Thread state: elapsed=10002,cpu=866,max=ReadController:ReadProcessor(210/2%),mem:max=2621440,tot=2621440,free=67500
[09:25:59] Thread state: elapsed=11985,cpu=1028,max=Global Status Checker(250/2%),mem:max=2621440,tot=2621440,free=123778
[09:26:00] Thread state: elapsed=12115,cpu=1110,max=Global Status Checker(260/2%),mem:max=2621440,tot=2621440,free=133053
[09:26:16] Thread state: elapsed=12072,cpu=1201,max=tag:constraints(320/3%),mem:max=2621440,tot=2621440,free=105883
[09:26:33] Thread state: elapsed=11941,cpu=1361,max=tag:constraints(290/2%),mem:max=2621440,tot=2621440,free=127696
[09:26:34] Thread state: elapsed=12072,cpu=1293,max=tag:constraints(310/3%),mem:max=2621440,tot=2621440,free=83358
[09:26:52] Thread state: elapsed=11916,cpu=1120,max=Global Status Checker(280/2%),mem:max=2621440,tot=2621440,free=44434
[09:27:11] Thread state: elapsed=12159,cpu=1353,max=tag:constraints(270/2%),mem:max=2621440,tot=2621440,free=126776
[09:27:12] Thread state: elapsed=10000,cpu=1613,max=ReadController:ReadProcessor(340/3%),mem:max=2621440,tot=2621440,free=44204
[09:27:27] Thread state: elapsed=12069,cpu=853,max=Global Status Checker(220/2%),mem:max=2621440,tot=2621440,free=92877
[09:27:43] Thread state: elapsed=10006,cpu=1651,max=Global Status Checker(400/4%),mem:max=2621440,tot=2621440,free=118971
[09:27:53] Thread state: elapsed=12013,cpu=4230,max=tag:constraints(1040/10%),mem:max=2621440,tot=2621440,free=160359
[09:28:07] Thread state: elapsed=11897,cpu=2509,max=ReadController:ReadProcessor(520/5%),mem:max=2621440,tot=2621440,free=166722
[09:28:20] Thread state: elapsed=10000,cpu=4378,max=PeerControlScheduler(1370/13%),mem:max=2621440,tot=2621440,free=92971
[09:28:30] Thread state: elapsed=10000,cpu=3900,max=PeerControlScheduler(960/9%),mem:max=2621440,tot=2621440,free=130178
[09:28:40] Thread state: elapsed=10000,cpu=3791,max=PeerControlScheduler(1260/12%),mem:max=2621440,tot=2621440,free=61580
[09:28:50] Thread state: elapsed=10000,cpu=3050,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=134097
[09:29:00] Thread state: elapsed=10000,cpu=3808,max=tag:constraints(950/9%),mem:max=2621440,tot=2621440,free=147450
[09:29:10] Thread state: elapsed=10000,cpu=3603,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=55710
[09:29:20] Thread state: elapsed=10000,cpu=2617,max=PeerControlScheduler(870/8%),mem:max=2621440,tot=2621440,free=124237
[09:29:30] Thread state: elapsed=10000,cpu=4038,max=PeerControlScheduler(1020/10%),mem:max=2621440,tot=2621440,free=51636
[09:29:40] Thread state: elapsed=10000,cpu=2731,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=123718
[09:29:50] Thread state: elapsed=10000,cpu=2818,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=142910
[09:30:00] Thread state: elapsed=10001,cpu=4179,max=tag:constraints(1010/10%),mem:max=2621440,tot=2621440,free=49855
[09:30:10] Thread state: elapsed=10000,cpu=2606,max=PeerControlScheduler(830/8%),mem:max=2621440,tot=2621440,free=127845
[09:30:20] Thread state: elapsed=10000,cpu=3654,max=PeerControlScheduler(1140/11%),mem:max=2621440,tot=2621440,free=64738
[09:30:30] Thread state: elapsed=10001,cpu=3547,max=tag:constraints(970/9%),mem:max=2621440,tot=2621440,free=123967
[09:30:40] Thread state: elapsed=11952,cpu=3045,max=PeerControlScheduler(1000/10%),mem:max=2621440,tot=2621440,free=178079
[09:30:52] Thread state: elapsed=10000,cpu=3796,max=PeerControlScheduler(1230/12%),mem:max=2621440,tot=2621440,free=88651
[09:31:02] Thread state: elapsed=10000,cpu=3941,max=tag:constraints(980/9%),mem:max=2621440,tot=2621440,free=50989
[09:31:12] Thread state: elapsed=10000,cpu=2657,max=PeerControlScheduler(640/6%),mem:max=2621440,tot=2621440,free=125750
[09:31:22] Thread state: elapsed=10000,cpu=4048,max=PeerControlScheduler(1340/13%),mem:max=2621440,tot=2621440,free=52307
[09:31:30] Thread state: elapsed=10421,cpu=3246,max=tag:constraints(950/9%),mem:max=2621440,tot=2621440,free=175235
[09:31:42] Thread state: elapsed=10000,cpu=3556,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=87209
[09:31:52] Thread state: elapsed=10001,cpu=2956,max=PeerControlScheduler(920/9%),mem:max=2621440,tot=2621440,free=121015
[09:32:02] Thread state: elapsed=12238,cpu=4549,max=PeerControlScheduler(1070/10%),mem:max=2621440,tot=2621440,free=167481
[09:32:15] Thread state: elapsed=10000,cpu=3518,max=PeerControlScheduler(1120/11%),mem:max=2621440,tot=2621440,free=94212
[09:32:23] Thread state: elapsed=10764,cpu=3496,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=169542
[09:32:35] Thread state: elapsed=10000,cpu=4326,max=PeerControlScheduler(1240/12%),mem:max=2621440,tot=2621440,free=97236
[09:32:44] Thread state: elapsed=10946,cpu=2778,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=166961
[09:32:56] Thread state: elapsed=10000,cpu=3577,max=PeerControlScheduler(1220/12%),mem:max=2621440,tot=2621440,free=78399
[09:33:06] Thread state: elapsed=10000,cpu=2872,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=151102
[09:33:16] Thread state: elapsed=11414,cpu=2351,max=Global Status Checker(950/9%),mem:max=2621440,tot=2621440,free=129494
[09:33:26] Thread state: elapsed=10092,cpu=4017,max=Global Status Checker(2130/21%),mem:max=2621440,tot=2621440,free=152486
[09:33:38] Thread state: elapsed=10000,cpu=2016,max=Global Status Checker(910/9%),mem:max=2621440,tot=2621440,free=77931
[09:33:48] Thread state: elapsed=10001,cpu=2706,max=tag:constraints(770/7%),mem:max=2621440,tot=2621440,free=153169
[09:33:58] Thread state: elapsed=10000,cpu=3799,max=PeerControlScheduler(1170/11%),mem:max=2621440,tot=2621440,free=85177
[09:34:08] Thread state: elapsed=12135,cpu=3119,max=PeerControlScheduler(1160/11%),mem:max=2621440,tot=2621440,free=170935
[09:34:20] Thread state: elapsed=10000,cpu=4577,max=PeerControlScheduler(1180/11%),mem:max=2621440,tot=2621440,free=74853
[09:34:28] Thread state: elapsed=10235,cpu=2519,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=170185
[09:34:40] Thread state: elapsed=10001,cpu=4147,max=PeerControlScheduler(1200/12%),mem:max=2621440,tot=2621440,free=86288
[09:34:49] Thread state: elapsed=10597,cpu=2807,max=PeerControlScheduler(960/9%),mem:max=2621440,tot=2621440,free=169561
[09:35:01] Thread state: elapsed=10001,cpu=3247,max=PeerControlScheduler(1140/11%),mem:max=2621440,tot=2621440,free=90409
[09:35:11] Thread state: elapsed=10000,cpu=2600,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=152852
[09:35:21] Thread state: elapsed=10000,cpu=4057,max=PeerControlScheduler(1230/12%),mem:max=2621440,tot=2621440,free=88876
[09:35:31] Thread state: elapsed=10000,cpu=2724,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=151797
[09:35:41] Thread state: elapsed=10000,cpu=3065,max=PeerControlScheduler(1190/11%),mem:max=2621440,tot=2621440,free=82519
[09:35:51] Thread state: elapsed=10000,cpu=2607,max=PeerControlScheduler(850/8%),mem:max=2621440,tot=2621440,free=136405
[09:36:01] Thread state: elapsed=10000,cpu=3699,max=PeerControlScheduler(1220/12%),mem:max=2621440,tot=2621440,free=69885
[09:36:11] Thread state: elapsed=10000,cpu=2655,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=152324
[09:36:21] Thread state: elapsed=10000,cpu=3927,max=PeerControlScheduler(1200/12%),mem:max=2621440,tot=2621440,free=86497
[09:36:30] Thread state: elapsed=10729,cpu=2828,max=PeerControlScheduler(960/9%),mem:max=2621440,tot=2621440,free=169219
[09:36:42] Thread state: elapsed=10000,cpu=3056,max=PeerControlScheduler(1220/12%),mem:max=2621440,tot=2621440,free=104120
[09:36:50] Thread state: elapsed=10164,cpu=2719,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=167836
[09:37:02] Thread state: elapsed=10000,cpu=3487,max=PeerControlScheduler(1270/12%),mem:max=2621440,tot=2621440,free=83924
[09:37:12] Thread state: elapsed=10000,cpu=2600,max=PeerControlScheduler(720/7%),mem:max=2621440,tot=2621440,free=71442
[09:37:22] Thread state: elapsed=10000,cpu=4386,max=PeerControlScheduler(1040/10%),mem:max=2621440,tot=2621440,free=42221
[09:37:32] Thread state: elapsed=10000,cpu=2387,max=PeerControlScheduler(710/7%),mem:max=2621440,tot=2621440,free=104872
[09:37:42] Thread state: elapsed=12351,cpu=2956,max=PeerControlScheduler(730/7%),mem:max=2621440,tot=2621440,free=143914
[09:37:52] Thread state: elapsed=10181,cpu=2986,max=tag:constraints(990/9%),mem:max=2621440,tot=2621440,free=129676
[09:38:05] Thread state: elapsed=10000,cpu=2517,max=PeerControlScheduler(790/7%),mem:max=2621440,tot=2621440,free=54497
[09:38:15] Thread state: elapsed=10000,cpu=2488,max=PeerControlScheduler(750/7%),mem:max=2621440,tot=2621440,free=53098
[09:38:24] Thread state: elapsed=11202,cpu=3724,max=tag:constraints(970/9%),mem:max=2621440,tot=2621440,free=110042
[09:38:34] Thread state: elapsed=10736,cpu=3308,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=107576
[09:38:45] Thread state: elapsed=10476,cpu=3609,max=PeerControlScheduler(990/9%),mem:max=2621440,tot=2621440,free=111153
[09:38:48] Thread state: elapsed=10836,cpu=908,max=Global Status Checker(310/3%),mem:max=2621440,tot=2621440,free=49384
[09:38:48] Thread state: elapsed=10106,cpu=669,max=ReadController:ReadProcessor(140/1%),mem:max=2621440,tot=2621440,free=88151
[09:39:11] Thread state: elapsed=12336,cpu=699,max=Global Status Checker(160/1%),mem:max=2621440,tot=2621440,free=103201
[09:39:12] Thread state: elapsed=10106,cpu=903,max=tag:constraints(190/1%),mem:max=2621440,tot=2621440,free=92183
[09:39:34] Thread state: elapsed=12345,cpu=711,max=Global Status Checker(170/1%),mem:max=2621440,tot=2621440,free=62377
[09:39:34] Thread state: elapsed=12339,cpu=1152,max=tag:constraints(280/2%),mem:max=2621440,tot=2621440,free=74643
[09:39:58] Thread state: elapsed=11794,cpu=512,max=Global Status Checker(140/1%),mem:max=2621440,tot=2621440,free=53476
[09:39:59] Thread state: elapsed=12173,cpu=1085,max=ReadController:ReadProcessor(190/1%),mem:max=2621440,tot=2621440,free=61619
[09:40:27] Thread state: elapsed=11928,cpu=813,max=Global Status Checker(130/1%),mem:max=2621440,tot=2621440,free=89071
[09:40:28] Thread state: elapsed=12196,cpu=666,max=Global Status Checker(170/1%),mem:max=2621440,tot=2621440,free=29772
[09:40:54] Thread state: elapsed=12093,cpu=653,max=Global Status Checker(140/1%),mem:max=2621440,tot=2621440,free=33382
[09:40:54] Thread state: elapsed=11888,cpu=684,max=Global Status Checker(150/1%),mem:max=2621440,tot=2621440,free=80196
[09:41:25] Thread state: elapsed=12037,cpu=592,max=Global Status Checker(140/1%),mem:max=2621440,tot=2621440,free=61891
[09:41:25] Thread state: elapsed=11990,cpu=574,max=Global Status Checker(140/1%),mem:max=2621440,tot=2621440,free=56765
[09:41:51] Thread state: elapsed=12072,cpu=794,max=Global Status Checker(130/1%),mem:max=2621440,tot=2621440,free=57695
[09:41:52] Thread state: elapsed=11976,cpu=843,max=Global Status Checker(160/1%),mem:max=2621440,tot=2621440,free=81434
[09:42:16] Thread state: elapsed=12057,cpu=1183,max=tag:constraints(230/2%),mem:max=2621440,tot=2621440,free=91529
[09:42:16] Thread state: elapsed=11865,cpu=1015,max=Global Status Checker(190/1%),mem:max=2621440,tot=2621440,free=84608
[09:42:40] Thread state: elapsed=12257,cpu=1202,max=tag:constraints(290/2%),mem:max=2621440,tot=2621440,free=60945
[09:42:40] Thread state: elapsed=11890,cpu=794,max=tag:constraints(160/1%),mem:max=2621440,tot=2621440,free=72523
[09:43:04] Thread state: elapsed=12316,cpu=1205,max=tag:constraints(190/1%),mem:max=2621440,tot=2621440,free=34099
[09:43:30] Thread state: elapsed=12163,cpu=1025,max=Global Status Checker(170/1%),mem:max=2621440,tot=2621440,free=40023
[09:43:31] Thread state: elapsed=12122,cpu=1065,max=tag:constraints(180/1%),mem:max=2621440,tot=2621440,free=41219
[09:43:53] Thread state: elapsed=10569,cpu=1804,max=ReadController:ReadSelector(430/4%),mem:max=2621440,tot=2621440,free=90910
[09:44:07] Thread state: elapsed=11513,cpu=3325,max=tag:constraints(980/9%),mem:max=2621440,tot=2621440,free=119490
[09:44:18] Thread state: elapsed=10997,cpu=2160,max=ReadController:ReadSelector(580/5%),mem:max=2621440,tot=2621440,free=122632
[09:44:30] Thread state: elapsed=11814,cpu=1942,max=Global Status Checker(470/4%),mem:max=2621440,tot=2621440,free=139169
[09:44:42] Thread state: elapsed=10000,cpu=4679,max=PeerControlScheduler(910/9%),mem:max=2621440,tot=2621440,free=93473
[09:44:50] Thread state: elapsed=10000,cpu=2970,max=PeerControlScheduler(730/7%),mem:max=2621440,tot=2621440,free=112475
[09:45:02] Thread state: elapsed=10002,cpu=3617,max=tag:constraints(1020/10%),mem:max=2621440,tot=2621440,free=49998
[09:45:10] Thread state: elapsed=10147,cpu=2057,max=ReadController:ReadProcessor(470/4%),mem:max=2621440,tot=2621440,free=124791
[09:45:21] Thread state: elapsed=11236,cpu=2809,max=PeerControlScheduler(660/6%),mem:max=2621440,tot=2621440,free=109334
[09:45:33] Thread state: elapsed=11335,cpu=2839,max=PeerControlScheduler(720/7%),mem:max=2621440,tot=2621440,free=98754
[09:45:45] Thread state: elapsed=10000,cpu=3190,max=tag:constraints(1010/10%),mem:max=2621440,tot=2621440,free=58598
[09:45:53] Thread state: elapsed=10696,cpu=2476,max=PeerControlScheduler(680/6%),mem:max=2621440,tot=2621440,free=96345
[09:46:06] Thread state: elapsed=10001,cpu=3100,max=PeerControlScheduler(870/8%),mem:max=2621440,tot=2621440,free=37919
[09:46:16] Thread state: elapsed=10001,cpu=2434,max=PeerControlScheduler(580/5%),mem:max=2621440,tot=2621440,free=63189
[09:46:25] Thread state: elapsed=12026,cpu=3138,max=PeerControlScheduler(870/8%),mem:max=2621440,tot=2621440,free=94473
[09:46:37] Thread state: elapsed=11893,cpu=2959,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=94797
[09:46:50] Thread state: elapsed=10000,cpu=3189,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=59896
[09:47:00] Thread state: elapsed=10000,cpu=3035,max=PeerControlScheduler(950/9%),mem:max=2621440,tot=2621440,free=55307
[09:47:10] Thread state: elapsed=10000,cpu=3230,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=57298
[09:47:19] Thread state: elapsed=10979,cpu=2635,max=PeerControlScheduler(680/6%),mem:max=2621440,tot=2621440,free=105036
[09:47:31] Thread state: elapsed=10093,cpu=2945,max=PeerControlScheduler(950/9%),mem:max=2621440,tot=2621440,free=104353
[09:47:40] Thread state: elapsed=11669,cpu=4017,max=PeerControlScheduler(1110/11%),mem:max=2621440,tot=2621440,free=100741
[09:47:53] Thread state: elapsed=10000,cpu=3078,max=PeerControlScheduler(950/9%),mem:max=2621440,tot=2621440,free=54880
[09:48:03] Thread state: elapsed=10001,cpu=3275,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=59561
[09:48:13] Thread state: elapsed=10000,cpu=3238,max=PeerControlScheduler(870/8%),mem:max=2621440,tot=2621440,free=63098
[09:48:23] Thread state: elapsed=10001,cpu=2107,max=PeerControlScheduler(620/6%),mem:max=2621440,tot=2621440,free=62189
[09:48:33] Thread state: elapsed=10000,cpu=2886,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=77871
[09:48:40] Thread state: elapsed=10000,cpu=3215,max=PeerControlScheduler(930/9%),mem:max=2621440,tot=2621440,free=101574
[09:48:53] Thread state: elapsed=10000,cpu=3686,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=87859
[09:49:03] Thread state: elapsed=10000,cpu=3326,max=PeerControlScheduler(950/9%),mem:max=2621440,tot=2621440,free=86849
[09:49:13] Thread state: elapsed=10000,cpu=3035,max=PeerControlScheduler(970/9%),mem:max=2621440,tot=2621440,free=91972
[09:49:23] Thread state: elapsed=12235,cpu=2645,max=PeerControlScheduler(800/8%),mem:max=2621440,tot=2621440,free=82907
[09:49:35] Thread state: elapsed=10000,cpu=3258,max=tag:constraints(940/9%),mem:max=2621440,tot=2621440,free=44050
[09:49:43] Thread state: elapsed=10000,cpu=2016,max=ReadController:ReadProcessor(420/4%),mem:max=2621440,tot=2621440,free=66986
[09:49:48] Thread state: elapsed=10535,cpu=838,max=Global Status Checker(230/2%),mem:max=2621440,tot=2621440,free=51579
[09:49:48] Thread state: elapsed=12340,cpu=309,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=63030
[09:49:49] Thread state: elapsed=10355,cpu=428,max=ReadController:ReadProcessor(100/1%),mem:max=2621440,tot=2621440,free=60545
[09:50:21] Thread state: elapsed=10454,cpu=669,max=Fetcher-XXXClub 1080p(150/1%),mem:max=2621440,tot=2621440,free=68095
[09:50:22] Thread state: elapsed=10184,cpu=421,max=Fetcher-XXXClub 1080p(70/0%),mem:max=2621440,tot=2621440,free=78443
[09:50:49] Thread state: elapsed=10219,cpu=512,max=<dead>(110/1%),mem:max=2621440,tot=2621440,free=27130
[09:50:50] Thread state: elapsed=10152,cpu=361,max=Global Status Checker(70/0%),mem:max=2621440,tot=2621440,free=23798
[09:50:50] Thread state: elapsed=10426,cpu=575,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=76588
[09:51:18] Thread state: elapsed=10562,cpu=804,max=tag:constraints(150/1%),mem:max=2621440,tot=2621440,free=39407
[09:51:19] Thread state: elapsed=10359,cpu=735,max=tag:constraints(150/1%),mem:max=2621440,tot=2621440,free=57652
[09:51:19] Thread state: elapsed=10330,cpu=766,max=tag:constraints(150/1%),mem:max=2621440,tot=2621440,free=58936
[09:51:50] Thread state: elapsed=10590,cpu=715,max=ReadController:ReadProcessor(180/1%),mem:max=2621440,tot=2621440,free=30314
[09:51:50] Thread state: elapsed=10312,cpu=562,max=ReadController:ReadProcessor(90/0%),mem:max=2621440,tot=2621440,free=61084
[09:52:15] Thread state: elapsed=10055,cpu=540,max=ReadController:ReadProcessor(110/1%),mem:max=2621440,tot=2621440,free=59808
[09:52:15] Thread state: elapsed=10135,cpu=622,max=tag:constraints(120/1%),mem:max=2621440,tot=2621440,free=59406
[09:52:16] Thread state: elapsed=10165,cpu=755,max=Global Status Checker(100/1%),mem:max=2621440,tot=2621440,free=43903
[09:52:48] Thread state: elapsed=10612,cpu=444,max=Global Status Checker(70/0%),mem:max=2621440,tot=2621440,free=68044
[09:52:49] Thread state: elapsed=10315,cpu=555,max=tag:constraints(100/1%),mem:max=2621440,tot=2621440,free=64864
[09:52:49] Thread state: elapsed=10599,cpu=579,max=tag:constraints(130/1%),mem:max=2621440,tot=2621440,free=67628
[09:53:20] Thread state: elapsed=10376,cpu=842,max=ReadController:ReadSelector(160/1%),mem:max=2621440,tot=2621440,free=37685
[09:53:20] Thread state: elapsed=10115,cpu=253,max=ReadController:ReadProcessor(80/0%),mem:max=2621440,tot=2621440,free=39512
[09:53:48] Thread state: elapsed=10176,cpu=683,max=ReadController:ReadProcessor(130/1%),mem:max=2621440,tot=2621440,free=43764
[09:53:48] Thread state: elapsed=11083,cpu=783,max=Global Status Checker(120/1%),mem:max=2621440,tot=2621440,free=50085
[09:53:49] Thread state: elapsed=10595,cpu=434,max=Global Status Checker(70/0%),mem:max=2621440,tot=2621440,free=40903
[09:54:30] Thread state: elapsed=10907,cpu=453,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=30332
[09:54:30] Thread state: elapsed=11081,cpu=766,max=ReadController:ReadProcessor(140/1%),mem:max=2621440,tot=2621440,free=36868
[09:55:03] Thread state: elapsed=10960,cpu=275,max=ReadController:ReadSelector(130/1%),mem:max=2621440,tot=2621440,free=63742
[09:55:03] Thread state: elapsed=11848,cpu=541,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=26778
[09:55:42] Thread state: elapsed=11286,cpu=491,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=25792
[09:55:43] Thread state: elapsed=10796,cpu=413,max=Global Status Checker(70/0%),mem:max=2621440,tot=2621440,free=42084
[09:55:43] Thread state: elapsed=10999,cpu=626,max=tag:constraints(110/1%),mem:max=2621440,tot=2621440,free=48555
[09:56:27] Thread state: elapsed=11979,cpu=553,max=ReadController:ReadProcessor(80/0%),mem:max=2621440,tot=2621440,free=35966
[09:56:27] Thread state: elapsed=11434,cpu=443,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=41853
[09:56:28] Thread state: elapsed=11224,cpu=860,max=tag:constraints(170/1%),mem:max=2621440,tot=2621440,free=59677
[09:56:58] Thread state: elapsed=10403,cpu=448,max=ReadController:ReadProcessor(70/0%),mem:max=2621440,tot=2621440,free=47463
[09:56:58] Thread state: elapsed=10527,cpu=369,max=Global Status Checker(60/0%),mem:max=2621440,tot=2621440,free=56456
[09:56:58] Thread state: elapsed=11367,cpu=652,max=tag:constraints(130/1%),mem:max=2621440,tot=2621440,free=35693
[09:57:33] Thread state: elapsed=10859,cpu=553,max=ReadController:ReadSelector(120/1%),mem:max=2621440,tot=2621440,free=57144
[09:57:33] Thread state: elapsed=10818,cpu=333,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=43459
[09:57:34] Thread state: elapsed=10330,cpu=625,max=tag:constraints(100/1%),mem:max=2621440,tot=2621440,free=46744
[09:58:05] Thread state: elapsed=10454,cpu=692,max=tag:constraints(140/1%),mem:max=2621440,tot=2621440,free=29007
[09:58:05] Thread state: elapsed=10371,cpu=454,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=58093
[09:58:43] Thread state: elapsed=10365,cpu=574,max=tag:constraints(150/1%),mem:max=2621440,tot=2621440,free=54160
[09:58:44] Thread state: elapsed=10619,cpu=1592,max=tag:constraints(380/3%),mem:max=2621440,tot=2621440,free=45435
[09:59:01] Thread state: elapsed=12381,cpu=3501,max=tag:constraints(1080/10%),mem:max=2621440,tot=2621440,free=95053
[09:59:16] Thread state: elapsed=12319,cpu=2884,max=ReadController:ReadSelector(1050/10%),mem:max=2621440,tot=2621440,free=102004
[09:59:26] Thread state: elapsed=12264,cpu=3412,max=ReadController:ReadSelector(800/8%),mem:max=2621440,tot=2621440,free=106755
[09:59:41] Thread state: elapsed=10000,cpu=4044,max=ReadController:ReadProcessor(990/9%),mem:max=2621440,tot=2621440,free=62799
[09:59:49] Thread state: elapsed=10502,cpu=2504,max=ReadController:ReadProcessor(510/5%),mem:max=2621440,tot=2621440,free=100642
[10:00:01] Thread state: elapsed=12122,cpu=2924,max=ReadController:ReadProcessor(620/6%),mem:max=2621440,tot=2621440,free=110184
[10:00:14] Thread state: elapsed=10000,cpu=3991,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=51533
[10:00:22] Thread state: elapsed=11067,cpu=2523,max=PeerControlScheduler(690/6%),mem:max=2621440,tot=2621440,free=112479
[10:00:35] Thread state: elapsed=10000,cpu=2629,max=PeerControlScheduler(830/8%),mem:max=2621440,tot=2621440,free=84163
[10:00:45] Thread state: elapsed=10000,cpu=2859,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=77461
[10:00:55] Thread state: elapsed=10000,cpu=2880,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=86839
[10:01:02] Thread state: elapsed=10000,cpu=3780,max=PeerControlScheduler(840/8%),mem:max=2621440,tot=2621440,free=104540
[10:01:15] Thread state: elapsed=10000,cpu=2657,max=PeerControlScheduler(760/7%),mem:max=2621440,tot=2621440,free=69292
[10:01:25] Thread state: elapsed=10001,cpu=2556,max=PeerControlScheduler(710/7%),mem:max=2621440,tot=2621440,free=111153
[10:01:34] Thread state: elapsed=11542,cpu=3054,max=PeerControlScheduler(1080/10%),mem:max=2621440,tot=2621440,free=120526
[10:01:46] Thread state: elapsed=10000,cpu=2828,max=PeerControlScheduler(980/9%),mem:max=2621440,tot=2621440,free=55276
[10:01:56] Thread state: elapsed=10001,cpu=2212,max=PeerControlScheduler(770/7%),mem:max=2621440,tot=2621440,free=96651
[10:02:03] Thread state: elapsed=10000,cpu=3672,max=tag:constraints(1050/10%),mem:max=2621440,tot=2621440,free=122101
[10:02:14] Thread state: elapsed=10454,cpu=2501,max=PeerControlScheduler(890/8%),mem:max=2621440,tot=2621440,free=136787
[10:02:27] Thread state: elapsed=10000,cpu=3307,max=PeerControlScheduler(1130/11%),mem:max=2621440,tot=2621440,free=59490
[10:02:37] Thread state: elapsed=10000,cpu=2472,max=PeerControlScheduler(820/8%),mem:max=2621440,tot=2621440,free=74977
[10:02:47] Thread state: elapsed=10000,cpu=2896,max=PeerControlScheduler(800/8%),mem:max=2621440,tot=2621440,free=88434
[10:02:55] Thread state: elapsed=10770,cpu=2887,max=PeerControlScheduler(690/6%),mem:max=2621440,tot=2621440,free=118578
[10:03:08] Thread state: elapsed=10000,cpu=3368,max=tag:constraints(970/9%),mem:max=2621440,tot=2621440,free=50380
[10:03:15] Thread state: elapsed=10000,cpu=1718,max=ReadController:ReadProcessor(440/4%),mem:max=2621440,tot=2621440,free=84724
[10:03:25] Thread state: elapsed=10261,cpu=2253,max=PeerControlScheduler(730/7%),mem:max=2621440,tot=2621440,free=93877
[10:03:37] Thread state: elapsed=12074,cpu=3510,max=tag:constraints(900/9%),mem:max=2621440,tot=2621440,free=91098
[10:03:47] Thread state: elapsed=10000,cpu=2921,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=85419
[10:03:58] Thread state: elapsed=10813,cpu=2726,max=PeerControlScheduler(1000/10%),mem:max=2621440,tot=2621440,free=94044
[10:04:11] Thread state: elapsed=10000,cpu=2689,max=PeerControlScheduler(800/8%),mem:max=2621440,tot=2621440,free=74963
[10:04:21] Thread state: elapsed=10000,cpu=2489,max=PeerControlScheduler(770/7%),mem:max=2621440,tot=2621440,free=55477
[10:04:31] Thread state: elapsed=10000,cpu=1622,max=PeerControlScheduler(440/4%),mem:max=2621440,tot=2621440,free=52932
[10:04:41] Thread state: elapsed=12194,cpu=2788,max=tag:constraints(990/9%),mem:max=2621440,tot=2621440,free=66655
[10:04:47] Thread state: elapsed=11594,cpu=1190,max=ReadController:ReadProcessor(230/2%),mem:max=2621440,tot=2621440,free=60375
[10:05:01] Thread state: elapsed=10983,cpu=739,max=Tracker Announce Private Timer 87863(160/1%),mem:max=2621440,tot=2621440,free=35277
[10:05:01] Thread state: elapsed=10400,cpu=343,max=Global Status Checker(60/0%),mem:max=2621440,tot=2621440,free=44164
[10:05:01] Thread state: elapsed=12342,cpu=441,max=ReadController:ReadSelector(110/1%),mem:max=2621440,tot=2621440,free=46517
[10:05:49] Thread state: elapsed=12419,cpu=391,max=ReadController:ReadProcessor(90/0%),mem:max=2621440,tot=2621440,free=26598
[10:05:49] Thread state: elapsed=10104,cpu=341,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=30963
[10:05:49] Thread state: elapsed=10071,cpu=325,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=48651
[10:05:49] Thread state: elapsed=10230,cpu=305,max=ReadController:ReadProcessor(70/0%),mem:max=2621440,tot=2621440,free=51909
[10:06:35] Thread state: elapsed=12297,cpu=452,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=28653
[10:06:35] Thread state: elapsed=12185,cpu=545,max=ReadController:ReadProcessor(80/0%),mem:max=2621440,tot=2621440,free=44934
[10:07:16] Thread state: elapsed=12138,cpu=530,max=ReadController:ReadProcessor(110/1%),mem:max=2621440,tot=2621440,free=32664
[10:07:16] Thread state: elapsed=12148,cpu=681,max=ReadController:ReadProcessor(80/0%),mem:max=2621440,tot=2621440,free=31037
[10:07:16] Thread state: elapsed=12014,cpu=353,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=39550
[10:07:59] Thread state: elapsed=12089,cpu=472,max=ReadController:ReadSelector(100/1%),mem:max=2621440,tot=2621440,free=32409
[10:08:00] Thread state: elapsed=12155,cpu=282,max=ReadController:ReadProcessor(50/0%),mem:max=2621440,tot=2621440,free=37830
[10:08:00] Thread state: elapsed=12384,cpu=595,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=54025
[10:08:43] Thread state: elapsed=11963,cpu=370,max=Global Status Checker(60/0%),mem:max=2621440,tot=2621440,free=26224
[10:08:44] Thread state: elapsed=12409,cpu=413,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=54883
[10:08:44] Thread state: elapsed=12232,cpu=593,max=tag:constraints(90/0%),mem:max=2621440,tot=2621440,free=42279
[10:09:18] Thread state: elapsed=10002,cpu=463,max=tag:constraints(60/0%),mem:max=2621440,tot=2621440,free=36478
[10:09:18] Thread state: elapsed=10096,cpu=664,max=tag:constraints(110/1%),mem:max=2621440,tot=2621440,free=38317
[10:09:58] Thread state: elapsed=10144,cpu=874,max=ReadController:ReadProcessor(130/1%),mem:max=2621440,tot=2621440,free=30267
[10:09:58] Thread state: elapsed=10022,cpu=384,max=Global Status Checker(60/0%),mem:max=2621440,tot=2621440,free=33052
[10:09:58] Thread state: elapsed=10002,cpu=525,max=tag:constraints(110/1%),mem:max=2621440,tot=2621440,free=28206
[10:10:50] Thread state: elapsed=12266,cpu=490,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=29794
[10:10:50] Thread state: elapsed=10138,cpu=482,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=38487
[10:10:51] Thread state: elapsed=10166,cpu=264,max=Global Status Checker(40/0%),mem:max=2621440,tot=2621440,free=26028
[10:10:51] Thread state: elapsed=10001,cpu=334,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=51879
[10:11:41] Thread state: elapsed=10217,cpu=343,max=ReadController:ReadProcessor(50/0%),mem:max=2621440,tot=2621440,free=45854
[10:11:41] Thread state: elapsed=10178,cpu=293,max=Global Status Checker(40/0%),mem:max=2621440,tot=2621440,free=45513
[10:11:41] Thread state: elapsed=10497,cpu=294,max=Global Status Checker(70/0%),mem:max=2621440,tot=2621440,free=37359
[10:11:41] Thread state: elapsed=10220,cpu=285,max=tag:constraints(60/0%),mem:max=2621440,tot=2621440,free=36486
[10:12:37] Thread state: elapsed=10370,cpu=251,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=38481
[10:12:37] Thread state: elapsed=10226,cpu=524,max=tag:constraints(90/0%),mem:max=2621440,tot=2621440,free=32508
[10:12:38] Thread state: elapsed=10317,cpu=442,max=ReadController:ReadProcessor(70/0%),mem:max=2621440,tot=2621440,free=34862
[10:13:23] Thread state: elapsed=10398,cpu=404,max=tag:constraints(70/0%),mem:max=2621440,tot=2621440,free=30763
[10:13:24] Thread state: elapsed=10514,cpu=203,max=Global Status Checker(40/0%),mem:max=2621440,tot=2621440,free=42760
[10:13:24] Thread state: elapsed=10301,cpu=645,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=33826
[10:14:17] Thread state: elapsed=10218,cpu=312,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=46099
[10:14:18] Thread state: elapsed=10379,cpu=554,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=30522
[10:15:16] Thread state: elapsed=10398,cpu=486,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=44026
[10:15:17] Thread state: elapsed=10453,cpu=486,max=ReadController:ReadSelector(70/0%),mem:max=2621440,tot=2621440,free=32460
[10:15:17] Thread state: elapsed=10713,cpu=625,max=tag:constraints(110/1%),mem:max=2621440,tot=2621440,free=42709
[10:15:54] Thread state: elapsed=10328,cpu=416,max=tag:constraints(90/0%),mem:max=2621440,tot=2621440,free=34418
[10:15:54] Thread state: elapsed=10140,cpu=594,max=Global Status Checker(100/1%),mem:max=2621440,tot=2621440,free=43676
[10:16:42] Thread state: elapsed=10190,cpu=562,max=ReadController:ReadSelector(70/0%),mem:max=2621440,tot=2621440,free=30089
[10:16:42] Thread state: elapsed=10022,cpu=334,max=Global Status Checker(70/0%),mem:max=2621440,tot=2621440,free=57495
[10:16:42] Thread state: elapsed=10504,cpu=466,max=tag:constraints(80/0%),mem:max=2621440,tot=2621440,free=59486
[10:17:25] Thread state: elapsed=10013,cpu=432,max=tag:constraints(130/1%),mem:max=2621440,tot=2621440,free=58191
[10:17:25] Thread state: elapsed=12162,cpu=631,max=ReadController:ReadProcessor(90/0%),mem:max=2621440,tot=2621440,free=48690
[10:17:26] Thread state: elapsed=12053,cpu=660,max=Global Status Checker(110/1%),mem:max=2621440,tot=2621440,free=51890
[10:18:07] Thread state: elapsed=12387,cpu=511,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=44287
[10:18:07] Thread state: elapsed=12360,cpu=534,max=Global Status Checker(80/0%),mem:max=2621440,tot=2621440,free=31164
[10:18:07] Thread state: elapsed=12108,cpu=443,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=23849
[10:18:49] Thread state: elapsed=12376,cpu=525,max=tag:constraints(160/1%),mem:max=2621440,tot=2621440,free=28130
[10:18:49] Thread state: elapsed=10320,cpu=1520,max=tag:constraints(490/4%),mem:max=2621440,tot=2621440,free=74871
[10:19:09] Thread state: elapsed=10002,cpu=2534,max=tag:constraints(800/8%),mem:max=2621440,tot=2621440,free=105360
[10:19:21] Thread state: elapsed=12205,cpu=3904,max=ReadController:ReadSelector(970/9%),mem:max=2621440,tot=2621440,free=95285
[10:19:34] Thread state: elapsed=12327,cpu=4121,max=tag:constraints(1110/11%),mem:max=2621440,tot=2621440,free=102907
[10:19:43] Thread state: elapsed=10001,cpu=3954,max=AEThread2:parked[13](1170/11%),mem:max=2621440,tot=2621440,free=80730
[10:19:51] Thread state: elapsed=10486,cpu=2112,max=ReadController:ReadProcessor(630/6%),mem:max=2621440,tot=2621440,free=83627
[10:20:04] Thread state: elapsed=10000,cpu=3353,max=tag:constraints(1160/11%),mem:max=2621440,tot=2621440,free=65696
[10:20:14] Thread state: elapsed=12103,cpu=1863,max=ReadController:ReadProcessor(320/3%),mem:max=2621440,tot=2621440,free=69221
[10:20:26] Thread state: elapsed=10094,cpu=1773,max=ReadController:ReadProcessor(330/3%),mem:max=2621440,tot=2621440,free=57329
[10:20:37] Thread state: elapsed=10686,cpu=2354,max=PeerControlScheduler(550/5%),mem:max=2621440,tot=2621440,free=56485
[10:20:49] Thread state: elapsed=12077,cpu=3185,max=tag:constraints(930/9%),mem:max=2621440,tot=2621440,free=54956
[10:21:00] Thread state: elapsed=10662,cpu=1902,max=PeerControlScheduler(570/5%),mem:max=2621440,tot=2621440,free=58514
[10:21:10] Thread state: elapsed=10118,cpu=2299,max=PeerControlScheduler(620/6%),mem:max=2621440,tot=2621440,free=58435
[10:21:17] Thread state: elapsed=11622,cpu=826,max=ReadController:ReadProcessor(190/1%),mem:max=2621440,tot=2621440,free=54638
[10:21:34] Thread state: elapsed=12127,cpu=1426,max=ReadController:ReadProcessor(450/4%),mem:max=2621440,tot=2621440,free=53711
[10:21:46] Thread state: elapsed=12204,cpu=1400,max=ReadController:ReadProcessor(320/3%),mem:max=2621440,tot=2621440,free=69854
[10:21:58] Thread state: elapsed=12328,cpu=3067,max=PeerControlScheduler(1060/10%),mem:max=2621440,tot=2621440,free=77213
[10:22:09] Thread state: elapsed=10629,cpu=1261,max=PeerControlScheduler(280/2%),mem:max=2621440,tot=2621440,free=74964
[10:22:21] Thread state: elapsed=12408,cpu=4230,max=tag:constraints(1000/10%),mem:max=2621440,tot=2621440,free=97254
[10:22:31] Thread state: elapsed=10000,cpu=2728,max=PeerControlScheduler(960/9%),mem:max=2621440,tot=2621440,free=97108
[10:22:41] Thread state: elapsed=10000,cpu=2654,max=PeerControlScheduler(820/8%),mem:max=2621440,tot=2621440,free=99402
[10:22:54] Thread state: elapsed=10000,cpu=3766,max=PeerControlScheduler(820/8%),mem:max=2621440,tot=2621440,free=85360
[10:23:04] Thread state: elapsed=10000,cpu=2826,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=92978
[10:23:12] Thread state: elapsed=10703,cpu=2610,max=PeerControlScheduler(880/8%),mem:max=2621440,tot=2621440,free=102040
[10:23:25] Thread state: elapsed=10000,cpu=2836,max=PeerControlScheduler(960/9%),mem:max=2621440,tot=2621440,free=86374
[10:23:32] Thread state: elapsed=10196,cpu=2660,max=PeerControlScheduler(860/8%),mem:max=2621440,tot=2621440,free=100895
[10:23:43] Thread state: elapsed=10763,cpu=2846,max=PeerControlScheduler(940/9%),mem:max=2621440,tot=2621440,free=99682
[10:23:53] Thread state: elapsed=10001,cpu=2435,max=PeerControlScheduler(720/7%),mem:max=2621440,tot=2621440,free=92030
[10:24:06] Thread state: elapsed=10000,cpu=3166,max=PeerControlScheduler(1090/10%),mem:max=2621440,tot=2621440,free=43109
[10:24:16] Thread state: elapsed=10000,cpu=1525,max=PeerControlScheduler(500/5%),mem:max=2621440,tot=2621440,free=60063
[10:24:26] Thread state: elapsed=10000,cpu=3552,max=tag:constraints(970/9%),mem:max=2621440,tot=2621440,free=50004
[10:24:36] Thread state: elapsed=10000,cpu=2091,max=PeerControlScheduler(750/7%),mem:max=2621440,tot=2621440,free=58954
[10:24:46] Thread state: elapsed=10000,cpu=2097,max=PeerControlScheduler(710/7%),mem:max=2621440,tot=2621440,free=68923
[10:24:53] Thread state: elapsed=10000,cpu=2568,max=PeerControlScheduler(850/8%),mem:max=2621440,tot=2621440,free=88154
[10:24:57] Thread state: elapsed=11468,cpu=804,max=Global Status Checker(200/2%),mem:max=2621440,tot=2621440,free=70566
[10:24:57] Thread state: elapsed=10600,cpu=537,max=tag:constraints(130/1%),mem:max=2621440,tot=2621440,free=84401
[10:25:23] Thread state: elapsed=10571,cpu=587,max=tag:constraints(150/1%),mem:max=2621440,tot=2621440,free=72509
[10:25:24] Thread state: elapsed=10752,cpu=567,max=ReadController:ReadProcessor(100/1%),mem:max=2621440,tot=2621440,free=93217
[10:25:24] Thread state: elapsed=10531,cpu=647,max=ReadController:ReadSelector(160/1%),mem:max=2621440,tot=2621440,free=82950
[10:25:53] Thread state: elapsed=10670,cpu=566,max=tag:constraints(140/1%),mem:max=2621440,tot=2621440,free=69768
[10:25:53] Thread state: elapsed=10630,cpu=438,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=88701
[10:25:53] Thread state: elapsed=10714,cpu=657,max=tag:constraints(140/1%),mem:max=2621440,tot=2621440,free=37159
[10:26:25] Thread state: elapsed=10708,cpu=518,max=Global Status Checker(100/1%),mem:max=2621440,tot=2621440,free=69950
[10:26:25] Thread state: elapsed=10698,cpu=671,max=Global Status Checker(140/1%),mem:max=2621440,tot=2621440,free=64149
[10:26:48] Thread state: elapsed=10006,cpu=346,max=ReadController:ReadSelector(110/1%),mem:max=2621440,tot=2621440,free=79838
[10:26:49] Thread state: elapsed=10001,cpu=517,max=Global Status Checker(120/1%),mem:max=2621440,tot=2621440,free=36848
[10:26:49] Thread state: elapsed=12290,cpu=587,max=Global Status Checker(120/1%),mem:max=2621440,tot=2621440,free=26301
[10:27:23] Thread state: elapsed=12378,cpu=628,max=ReadController:ReadSelector(170/1%),mem:max=2621440,tot=2621440,free=33936
[10:27:24] Thread state: elapsed=12445,cpu=619,max=Global Status Checker(130/1%),mem:max=2621440,tot=2621440,free=66175
[10:27:24] Thread state: elapsed=12324,cpu=609,max=ReadController:ReadSelector(150/1%),mem:max=2621440,tot=2621440,free=61526
[10:27:53] Thread state: elapsed=11994,cpu=457,max=Global Status Checker(110/1%),mem:max=2621440,tot=2621440,free=50895
[10:27:54] Thread state: elapsed=12244,cpu=549,max=tag:constraints(140/1%),mem:max=2621440,tot=2621440,free=64214
[10:28:27] Thread state: elapsed=10109,cpu=771,max=ReadController:ReadProcessor(180/1%),mem:max=2621440,tot=2621440,free=34002
[10:28:28] Thread state: elapsed=10261,cpu=520,max=tag:constraints(130/1%),mem:max=2621440,tot=2621440,free=60070
[10:28:50] Thread state: elapsed=10004,cpu=769,max=tag:constraints(190/1%),mem:max=2621440,tot=2621440,free=28016
[10:28:51] Thread state: elapsed=12441,cpu=839,max=ReadController:ReadProcessor(140/1%),mem:max=2621440,tot=2621440,free=45326
[10:29:15] Thread state: elapsed=12232,cpu=710,max=tag:constraints(150/1%),mem:max=2621440,tot=2621440,free=54335
[10:29:15] Thread state: elapsed=11875,cpu=480,max=Global Status Checker(90/0%),mem:max=2621440,tot=2621440,free=66001
[10:29:16] Thread state: elapsed=10023,cpu=481,max=Global Status Checker(60/0%),mem:max=2621440,tot=2621440,free=27943
[10:29:49] Thread state: elapsed=10001,cpu=650,max=ReadController:ReadSelector(120/1%),mem:max=2621440,tot=2621440,free=28984
[10:29:50] Thread state: elapsed=12265,cpu=718,max=ReadController:ReadSelector(100/1%),mem:max=2621440,tot=2621440,free=57000
[10:29:50] Thread state: elapsed=12131,cpu=614,max=tag:constraints(140/1%),mem:max=2621440,tot=2621440,free=40646
[10:30:29] Thread state: elapsed=11938,cpu=473,max=ReadController:ReadSelector(80/0%),mem:max=2621440,tot=2621440,free=56980
[10:30:29] Thread state: elapsed=12398,cpu=793,max=tag:constraints(170/1%),mem:max=2621440,tot=2621440,free=36084
[10:31:07] Thread state: elapsed=12071,cpu=595,max=Global Status Checker(100/1%),mem:max=2621440,tot=2621440,free=26057
[10:31:08] Thread state: elapsed=10000,cpu=454,max=tag:constraints(100/1%),mem:max=2621440,tot=2621440,free=57351
[10:31:35] Thread state: elapsed=10573,cpu=1724,max=tag:constraints(390/3%),mem:max=2621440,tot=2621440,free=58679
[10:31:49] Thread state: elapsed=10892,cpu=2075,max=tag:constraints(520/5%),mem:max=2621440,tot=2621440,free=89990
[10:31:57] Thread state: elapsed=10893,cpu=1952,max=tag:constraints(480/4%),mem:max=2621440,tot=2621440,free=75657
[10:32:08] Thread state: elapsed=10821,cpu=1932,max=ReadController:ReadSelector(620/6%),mem:max=2621440,tot=2621440,free=85817
[10:32:23] Thread state: elapsed=12052,cpu=1910,max=ReadController:ReadProcessor(550/5%),mem:max=2621440,tot=2621440,free=67591
[10:32:35] Thread state: elapsed=10000,cpu=3224,max=tag:constraints(1070/10%),mem:max=2621440,tot=2621440,free=41861
[10:32:45] Thread state: elapsed=12496,cpu=1376,max=ReadController:ReadProcessor(390/3%),mem:max=2621440,tot=2621440,free=58864
[10:32:55] Thread state: elapsed=10001,cpu=1539,max=ReadController:ReadProcessor(430/4%),mem:max=2621440,tot=2621440,free=41850
[10:33:07] Thread state: elapsed=11595,cpu=2633,max=tag:constraints(890/8%),mem:max=2621440,tot=2621440,free=49620
[10:33:08] Thread state: elapsed=10723,cpu=1150,max=ReadController:ReadSelector(210/2%),mem:max=2621440,tot=2621440,free=39276
[10:33:20] Thread state: elapsed=10007,cpu=399,max=ReadController:ReadSelector(110/1%),mem:max=2621440,tot=2621440,free=40788
[10:33:36] Thread state: elapsed=10851,cpu=849,max=ReadController:ReadProcessor(260/2%),mem:max=2621440,tot=2621440,free=35584
[10:33:47] Thread state: elapsed=11034,cpu=843,max=ReadController:ReadProcessor(190/1%),mem:max=2621440,tot=2621440,free=34057
[10:33:47] Thread state: elapsed=10009,cpu=405,max=ReadController:ReadSelector(100/1%),mem:max=2621440,tot=2621440,free=29994
[10:34:07] Thread state: elapsed=10182,cpu=762,max=ReadController:ReadProcessor(230/2%),mem:max=2621440,tot=2621440,free=33404
[10:34:08] Thread state: elapsed=10283,cpu=332,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=28983
[10:34:33] Thread state: elapsed=10297,cpu=723,max=ReadController:ReadProcessor(230/2%),mem:max=2621440,tot=2621440,free=32626
[10:34:34] Thread state: elapsed=10001,cpu=774,max=tag:constraints(190/1%),mem:max=2621440,tot=2621440,free=30929
[10:34:34] Thread state: elapsed=10134,cpu=1094,max=tag:constraints(370/3%),mem:max=2621440,tot=2621440,free=30307
[10:35:01] Thread state: elapsed=10030,cpu=634,max=tag:constraints(160/1%),mem:max=2621440,tot=2621440,free=23989
[10:35:01] Thread state: elapsed=12405,cpu=754,max=tag:constraints(190/1%),mem:max=2621440,tot=2621440,free=25198
[10:35:31] Thread state: elapsed=10257,cpu=995,max=tag:constraints(280/2%),mem:max=2621440,tot=2621440,free=30313
[10:35:31] Thread state: elapsed=10102,cpu=346,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=26219
[10:35:32] Thread state: elapsed=10129,cpu=544,max=ReadController:ReadSelector(240/2%),mem:max=2621440,tot=2621440,free=29642
[10:35:59] Thread state: elapsed=10212,cpu=544,max=ReadController:ReadSelector(180/1%),mem:max=2621440,tot=2621440,free=27044
[10:35:59] Thread state: elapsed=10172,cpu=604,max=ReadController:ReadSelector(200/2%),mem:max=2621440,tot=2621440,free=26875
[10:36:34] Thread state: elapsed=12322,cpu=565,max=ReadController:ReadProcessor(160/1%),mem:max=2621440,tot=2621440,free=28067
[10:36:34] Thread state: elapsed=12019,cpu=174,max=PeerControlScheduler(40/0%),mem:max=2621440,tot=2621440,free=22329
[10:36:34] Thread state: elapsed=12295,cpu=397,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=22879
[10:36:34] Thread state: elapsed=11771,cpu=134,max=ReadController:ReadSelector(30/0%),mem:max=2621440,tot=2621440,free=24968
[10:36:35] Thread state: elapsed=12294,cpu=303,max=ReadController:ReadSelector(110/1%),mem:max=2621440,tot=2621440,free=24134
[10:37:49] Thread state: elapsed=10001,cpu=254,max=ReadController:ReadSelector(80/0%),mem:max=2621440,tot=2621440,free=23995
[10:37:49] Thread state: elapsed=12297,cpu=303,max=ReadController:ReadSelector(100/1%),mem:max=2621440,tot=2621440,free=26587
[10:37:50] Thread state: elapsed=10183,cpu=232,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=30554
[10:37:50] Thread state: elapsed=10052,cpu=164,max=ReadController:ReadProcessor(30/0%),mem:max=2621440,tot=2621440,free=28002
[10:37:50] Thread state: elapsed=10001,cpu=223,max=ReadController:ReadSelector(70/0%),mem:max=2621440,tot=2621440,free=32623
[10:39:02] Thread state: elapsed=12432,cpu=344,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=34987
[10:39:02] Thread state: elapsed=12150,cpu=524,max=PeerControlScheduler(100/1%),mem:max=2621440,tot=2621440,free=29916
[10:39:02] Thread state: elapsed=12393,cpu=314,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=23310
[10:39:02] Thread state: elapsed=10042,cpu=193,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=27192
[10:39:03] Thread state: elapsed=12213,cpu=154,max=ReadController:ReadSelector(30/0%),mem:max=2621440,tot=2621440,free=26859
[10:40:23] Thread state: elapsed=12044,cpu=193,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=32572
[10:40:23] Thread state: elapsed=12015,cpu=294,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=39709
[10:40:23] Thread state: elapsed=12191,cpu=204,max=ReadController:ReadProcessor(30/0%),mem:max=2621440,tot=2621440,free=38088
[10:40:24] Thread state: elapsed=12220,cpu=153,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=32702
[10:40:24] Thread state: elapsed=12266,cpu=195,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=27085
[10:40:24] Thread state: elapsed=12125,cpu=194,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=36519
[10:41:58] Thread state: elapsed=12185,cpu=204,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=27168
[10:41:58] Thread state: elapsed=12258,cpu=295,max=PeerControlScheduler(60/0%),mem:max=2621440,tot=2621440,free=34918
[10:41:58] Thread state: elapsed=11999,cpu=174,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=37479
[10:41:59] Thread state: elapsed=12159,cpu=235,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=29212
[10:41:59] Thread state: elapsed=10011,cpu=284,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=25887
[10:43:28] Thread state: elapsed=12331,cpu=255,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=37164
[10:43:28] Thread state: elapsed=12350,cpu=274,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=35577
[10:43:29] Thread state: elapsed=12374,cpu=334,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=31335
[10:43:29] Thread state: elapsed=12171,cpu=293,max=Global Status Checker(40/0%),mem:max=2621440,tot=2621440,free=34655
[10:44:57] Thread state: elapsed=10060,cpu=364,max=Global Status Checker(50/0%),mem:max=2621440,tot=2621440,free=28498
[10:44:57] Thread state: elapsed=12126,cpu=323,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=32012
[10:44:57] Thread state: elapsed=12472,cpu=284,max=ReadController:ReadProcessor(50/0%),mem:max=2621440,tot=2621440,free=24501
[10:44:58] Thread state: elapsed=12190,cpu=244,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=24796
[10:46:30] Thread state: elapsed=10040,cpu=263,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=32034
[10:46:31] Thread state: elapsed=12217,cpu=204,max=Global Status Checker(40/0%),mem:max=2621440,tot=2621440,free=30558
[10:46:31] Thread state: elapsed=11640,cpu=233,max=ReadController:ReadSelector(30/0%),mem:max=2621440,tot=2621440,free=32327
[10:48:10] Thread state: elapsed=10039,cpu=244,max=Global Status Checker(30/0%),mem:max=2621440,tot=2621440,free=29551
[10:48:11] Thread state: elapsed=12328,cpu=243,max=ReadController:ReadProcessor(30/0%),mem:max=2621440,tot=2621440,free=24913
[10:48:11] Thread state: elapsed=12456,cpu=204,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=32548
[10:48:11] Thread state: elapsed=12199,cpu=223,max=ReadController:ReadProcessor(40/0%),mem:max=2621440,tot=2621440,free=30019
[10:49:54] Thread state: elapsed=10193,cpu=163,max=Simple Timer 4137468{ProtocolDecoder:timeouts}(30/0%),mem:max=2621440,tot=2621440,free=28821
[10:49:54] Thread state: elapsed=10035,cpu=243,max=ReadController:ReadProcessor(70/0%),mem:max=2621440,tot=2621440,free=25757
[10:49:54] Thread state: elapsed=10080,cpu=203,max=Fetcher-EZTV(50/0%),mem:max=2621440,tot=2621440,free=30932
[10:49:55] Thread state: elapsed=12415,cpu=283,max=ReadController:ReadProcessor(50/0%),mem:max=2621440,tot=2621440,free=23684
[10:49:55] Thread state: elapsed=10071,cpu=213,max=Fetcher-EZTV(50/0%),mem:max=2621440,tot=2621440,free=30274
[10:49:55] Thread state: elapsed=10046,cpu=193,max=Fetcher-EZTV(30/0%),mem:max=2621440,tot=2621440,free=25350
[10:51:42] Thread state: elapsed=12174,cpu=193,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=25331
[10:51:42] Thread state: elapsed=10180,cpu=174,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=27103
[10:51:42] Thread state: elapsed=12361,cpu=213,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=25566
[10:51:42] Thread state: elapsed=10101,cpu=193,max=ReadController:ReadSelector(30/0%),mem:max=2621440,tot=2621440,free=27339
[10:51:42] Thread state: elapsed=12196,cpu=164,max=PeerControlScheduler(30/0%),mem:max=2621440,tot=2621440,free=28708
[10:51:42] Thread state: elapsed=12192,cpu=263,max=Global Status Checker(30/0%),mem:max=2621440,tot=2621440,free=22548
[10:51:42] Thread state: elapsed=12139,cpu=71,max=tag:constraints(20/0%),mem:max=2621440,tot=2621440,free=23179
[10:53:29] Thread state: elapsed=12321,cpu=120,max=SWT Thread(10/0%),mem:max=2621440,tot=2621440,free=25568
[10:53:29] Thread state: elapsed=10162,cpu=180,max=PeerControlScheduler(20/0%),mem:max=2621440,tot=2621440,free=28622
[10:53:30] Thread state: elapsed=12067,cpu=412,max=tag:constraints(80/0%),mem:max=2621440,tot=2621440,free=22819
[10:53:30] Thread state: elapsed=10546,cpu=151,max=Global Status Checker(30/0%),mem:max=2621440,tot=2621440,free=22153
[10:56:06] Thread state: elapsed=12460,cpu=183,max=ConnectDisconnectManager(20/0%),mem:max=2621440,tot=2621440,free=26172
[10:56:06] Thread state: elapsed=12289,cpu=143,max=ConnectDisconnectManager(20/0%),mem:max=2621440,tot=2621440,free=26152
[10:56:07] Thread state: elapsed=12068,cpu=163,max=PeerControlScheduler(30/0%),mem:max=2621440,tot=2621440,free=27358
[10:56:07] Thread state: elapsed=10120,cpu=224,max=tag:constraints(30/0%),mem:max=2621440,tot=2621440,free=24838
[10:57:52] Thread state: elapsed=11056,cpu=163,max=ReadController:ReadProcessor(20/0%),mem:max=2621440,tot=2621440,free=26301
[10:57:52] Thread state: elapsed=10719,cpu=203,max=ConnectDisconnectManager(30/0%),mem:max=2621440,tot=2621440,free=26582
[10:57:52] Thread state: elapsed=10479,cpu=163,max=ReadController:ReadSelector(30/0%),mem:max=2621440,tot=2621440,free=25474
[10:57:52] Thread state: elapsed=10289,cpu=133,max=AEThreadMonitor(13/0%),mem:max=2621440,tot=2621440,free=26275
[11:00:12] Thread state: elapsed=10247,cpu=154,max=ReadController:ReadSelector(30/0%),mem:max=2621440,tot=2621440,free=24730
[11:00:12] Thread state: elapsed=10362,cpu=283,max=PeerControlScheduler(50/0%),mem:max=2621440,tot=2621440,free=20504
[11:00:12] Thread state: elapsed=10188,cpu=244,max=ReadController:ReadSelector(50/0%),mem:max=2621440,tot=2621440,free=21468
[11:02:07] Thread state: elapsed=11817,cpu=193,max=Simple Timer 4137468{ProtocolDecoder:timeouts}(40/0%),mem:max=2621440,tot=2621440,free=22509
[11:02:07] Thread state: elapsed=11322,cpu=203,max=ConnectDisconnectManager(20/0%),mem:max=2621440,tot=2621440,free=21000
[11:02:08] Thread state: elapsed=10153,cpu=223,max=Simple Timer 4137468{ProtocolDecoder:timeouts}(70/0%),mem:max=2621440,tot=2621440,free=58492
[11:02:08] Thread state: elapsed=11669,cpu=403,max=AEThread2:parked[11](60/0%),mem:max=2621440,tot=2621440,free=48179
[11:04:09] Thread state: elapsed=10576,cpu=164,max=SWT Thread(20/0%),mem:max=2621440,tot=2621440,free=44405
[11:04:10] Thread state: elapsed=10987,cpu=213,max=SWT Thread(30/0%),mem:max=2621440,tot=2621440,free=47168
[11:04:10] Thread state: elapsed=10406,cpu=355,max=ReadController:ReadSelector(40/0%),mem:max=2621440,tot=2621440,free=49600
[11:04:10] Thread state: elapsed=10381,cpu=312,max=SWT Thread(50/0%),mem:max=2621440,tot=2621440,free=45216
[11:04:10] Thread state: elapsed=10173,cpu=164,max=Global Status Checker(30/0%),mem:max=2621440,tot=2621440,free=53853
[11:05:46] Thread state: elapsed=10012,cpu=463,max=ReadController:ReadSelector(60/0%),mem:max=2621440,tot=2621440,free=45894
[11:05:46] Thread state: elapsed=10079,cpu=463,max=PeerControlScheduler(70/0%),mem:max=2621440,tot=2621440,free=46607

2peer commented 1 month ago

It looks like normally the GC can bring down the heap size to just above 1GB, so 2.5GB should be plenty: Screenshot_GC-jconsole

2peer commented 1 month ago

Screenshot_memory_usage_climb

OK. So the memory usage has been steadily climbing over the course of the last 29 hours.

There was also an event that caused it to jump up by about 600MB in a single instance. Looks like it happened somewhere in this timeframe (suspecting the [11:24:03]):

EDIT: Looks like no sudden event there, just an aliasing effect based on sampling rate (gone now). But climbing at a steady rate.

[11:08:02] Thread state: elapsed=10012,cpu=4626,max=tag:constraints(1170/11%),mem:max=3145728,tot=3145728,free=350752
[11:08:12] Thread state: elapsed=10014,cpu=3496,max=ReadController:ReadProcessor(840/8%),mem:max=3145728,tot=3145728,free=1764941
[11:08:22] Thread state: elapsed=10115,cpu=4888,max=Global Status Checker(1350/13%),mem:max=3145728,tot=3145728,free=1756498
[11:08:32] Thread state: elapsed=10009,cpu=7128,max=Global Status Checker(2860/28%),mem:max=3145728,tot=3145728,free=1196252
[11:08:32]     java.base@21.0.3/java.io.FileOutputStream.writeBytes(Native Method), java.base@21.0.3/java.io.FileOutputStream.write(FileOutputStream.java:367), java.base@21.0.3/java.io.BufferedOutputStream.implWrite(BufferedOutputStream.java:217), java.base@21.0.3/java.io.BufferedOutputStream.write(BufferedOutputStream.java:200), java.base@21.0.3/java.io.FilterOutputStream.write(FilterOutputStream.java:110), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:310), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.setResumeData(DownloadManagerStateImpl.java:1194), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:1032), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:987), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:973), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveResumeData(DiskManagerImpl.java:2654), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveResumeData(DownloadManagerImpl.java:2303), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:488), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[11:08:42] Thread state: elapsed=10000,cpu=4008,max=ReadController:ReadProcessor(910/9%),mem:max=3145728,tot=3145728,free=888081
[11:08:52] Thread state: elapsed=10000,cpu=3386,max=ReadController:ReadProcessor(770/7%),mem:max=3145728,tot=3145728,free=759975
[11:09:02] Thread state: elapsed=10000,cpu=4487,max=tag:constraints(1060/10%),mem:max=3145728,tot=3145728,free=624147
[11:09:12] Thread state: elapsed=10000,cpu=3436,max=ReadController:ReadProcessor(810/8%),mem:max=3145728,tot=3145728,free=513403
[11:09:22] Thread state: elapsed=10000,cpu=4276,max=SWT Thread(1210/12%),mem:max=3145728,tot=3145728,free=327718
[11:09:32] Thread state: elapsed=10000,cpu=5357,max=tag:constraints(1210/12%),mem:max=3145728,tot=3145728,free=107887
[11:09:42] Thread state: elapsed=10000,cpu=3547,max=ReadController:ReadProcessor(880/8%),mem:max=3145728,tot=3145728,free=637253
[11:09:52] Thread state: elapsed=10002,cpu=3527,max=ReadController:ReadProcessor(870/8%),mem:max=3145728,tot=3145728,free=517522
[11:10:02] Thread state: elapsed=10003,cpu=4557,max=tag:constraints(1080/10%),mem:max=3145728,tot=3145728,free=376327
[11:10:12] Thread state: elapsed=10000,cpu=3446,max=ReadController:ReadProcessor(850/8%),mem:max=3145728,tot=3145728,free=227611
[11:10:22] Thread state: elapsed=10000,cpu=3347,max=ReadController:ReadProcessor(780/7%),mem:max=3145728,tot=3145728,free=587873
[11:10:32] Thread state: elapsed=10000,cpu=3746,max=tag:constraints(1120/11%),mem:max=3145728,tot=3145728,free=1762487
[11:10:42] Thread state: elapsed=10001,cpu=3296,max=ReadController:ReadProcessor(820/8%),mem:max=3145728,tot=3145728,free=1615018
[11:10:52] Thread state: elapsed=10004,cpu=3737,max=SWT Thread(930/9%),mem:max=3145728,tot=3145728,free=1489480
[11:11:02] Thread state: elapsed=10005,cpu=4587,max=tag:constraints(1070/10%),mem:max=3145728,tot=3145728,free=1351550
[11:11:12] Thread state: elapsed=10002,cpu=3318,max=ReadController:ReadProcessor(820/8%),mem:max=3145728,tot=3145728,free=1202666
[11:11:22] Thread state: elapsed=10001,cpu=3527,max=ReadController:ReadProcessor(810/8%),mem:max=3145728,tot=3145728,free=1101154
[11:11:32] Thread state: elapsed=10000,cpu=4607,max=tag:constraints(1100/11%),mem:max=3145728,tot=3145728,free=965500
[11:11:42] Thread state: elapsed=10000,cpu=3456,max=ReadController:ReadProcessor(820/8%),mem:max=3145728,tot=3145728,free=854221
[11:11:52] Thread state: elapsed=10000,cpu=3656,max=SWT Thread(870/8%),mem:max=3145728,tot=3145728,free=748294
[11:12:02] Thread state: elapsed=10002,cpu=4786,max=tag:constraints(1300/13%),mem:max=3145728,tot=3145728,free=605871
[11:12:12] Thread state: elapsed=10000,cpu=3267,max=ReadController:ReadProcessor(800/8%),mem:max=3145728,tot=3145728,free=487034
[11:12:22] Thread state: elapsed=10001,cpu=3347,max=ReadController:ReadProcessor(800/8%),mem:max=3145728,tot=3145728,free=392117
[11:12:32] Thread state: elapsed=10001,cpu=3666,max=tag:constraints(1130/11%),mem:max=3145728,tot=3145728,free=1772337
[11:12:42] Thread state: elapsed=10000,cpu=3927,max=SWT Thread(1220/12%),mem:max=3145728,tot=3145728,free=1645347
[11:12:52] Thread state: elapsed=10001,cpu=3636,max=SWT Thread(980/9%),mem:max=3145728,tot=3145728,free=1529614
[11:13:03] Thread state: elapsed=10000,cpu=4355,max=tag:constraints(1120/11%),mem:max=3145728,tot=3145728,free=1399334
[11:13:13] Thread state: elapsed=10000,cpu=3237,max=ReadController:ReadProcessor(790/7%),mem:max=3145728,tot=3145728,free=1280784
[11:13:23] Thread state: elapsed=10000,cpu=2986,max=ReadController:ReadProcessor(730/7%),mem:max=3145728,tot=3145728,free=1168143
[11:13:33] Thread state: elapsed=10001,cpu=4358,max=tag:constraints(1090/10%),mem:max=3145728,tot=3145728,free=995084
[11:13:43] Thread state: elapsed=10001,cpu=6957,max=Global Status Checker(3670/36%),mem:max=3145728,tot=3145728,free=1136600
[11:13:43]     java.base@21.0.3/java.io.FileDescriptor.sync0(Native Method), java.base@21.0.3/java.io.FileDescriptor.sync(FileDescriptor.java:212), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:314), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.setResumeData(DownloadManagerStateImpl.java:1194), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:1032), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:987), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:973), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveResumeData(DiskManagerImpl.java:2654), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveResumeData(DownloadManagerImpl.java:2303), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:488), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[11:13:53] Thread state: elapsed=10000,cpu=3598,max=ReadController:ReadProcessor(750/7%),mem:max=3145728,tot=3145728,free=816453
[11:14:03] Thread state: elapsed=10003,cpu=4397,max=tag:constraints(1150/11%),mem:max=3145728,tot=3145728,free=665421
[11:14:13] Thread state: elapsed=10000,cpu=3428,max=PeerControlScheduler(790/7%),mem:max=3145728,tot=3145728,free=560671
[11:14:23] Thread state: elapsed=10000,cpu=3458,max=ReadController:ReadProcessor(780/7%),mem:max=3145728,tot=3145728,free=452056
[11:14:33] Thread state: elapsed=10000,cpu=3865,max=tag:constraints(1110/11%),mem:max=3145728,tot=3145728,free=1758348
[11:14:43] Thread state: elapsed=10000,cpu=3437,max=ReadController:ReadProcessor(830/8%),mem:max=3145728,tot=3145728,free=1594072
[11:14:53] Thread state: elapsed=10000,cpu=3118,max=ReadController:ReadProcessor(700/7%),mem:max=3145728,tot=3145728,free=1439834
[11:15:03] Thread state: elapsed=10001,cpu=5307,max=tag:constraints(1160/11%),mem:max=3145728,tot=3145728,free=1631545
[11:15:13] Thread state: elapsed=10000,cpu=3448,max=ReadController:ReadProcessor(830/8%),mem:max=3145728,tot=3145728,free=1467827
[11:15:23] Thread state: elapsed=10000,cpu=3589,max=ReadController:ReadProcessor(910/9%),mem:max=3145728,tot=3145728,free=1298365
[11:15:33] Thread state: elapsed=10000,cpu=5338,max=tag:constraints(1120/11%),mem:max=3145728,tot=3145728,free=1133238
[11:15:43] Thread state: elapsed=10000,cpu=3859,max=ReadController:ReadProcessor(890/8%),mem:max=3145728,tot=3145728,free=922752
[11:15:53] Thread state: elapsed=10000,cpu=3869,max=ReadController:ReadProcessor(940/9%),mem:max=3145728,tot=3145728,free=810145
[11:16:03] Thread state: elapsed=10001,cpu=5038,max=tag:constraints(1240/12%),mem:max=3145728,tot=3145728,free=659806
[11:16:13] Thread state: elapsed=10000,cpu=3558,max=ReadController:ReadProcessor(880/8%),mem:max=3145728,tot=3145728,free=478434
[11:16:23] Thread state: elapsed=10000,cpu=3688,max=ReadController:ReadProcessor(840/8%),mem:max=3145728,tot=3145728,free=369207
[11:16:33] Thread state: elapsed=10000,cpu=3908,max=tag:constraints(1140/11%),mem:max=3145728,tot=3145728,free=1772860
[11:16:43] Thread state: elapsed=10000,cpu=3697,max=ReadController:ReadProcessor(880/8%),mem:max=3145728,tot=3145728,free=1632808
[11:16:53] Thread state: elapsed=10000,cpu=3007,max=ReadController:ReadProcessor(760/7%),mem:max=3145728,tot=3145728,free=1530379
[11:17:03] Thread state: elapsed=10001,cpu=4189,max=tag:constraints(1060/10%),mem:max=3145728,tot=3145728,free=1417153
[11:17:13] Thread state: elapsed=10000,cpu=3539,max=ReadController:ReadProcessor(820/8%),mem:max=3145728,tot=3145728,free=1294417
[11:17:23] Thread state: elapsed=10000,cpu=3677,max=ReadController:ReadProcessor(930/9%),mem:max=3145728,tot=3145728,free=1179647
[11:17:33] Thread state: elapsed=10000,cpu=3508,max=ReadController:ReadProcessor(860/8%),mem:max=3145728,tot=3145728,free=1042425
[11:17:43] Thread state: elapsed=10000,cpu=3556,max=ReadController:ReadProcessor(860/8%),mem:max=3145728,tot=3145728,free=924259
[11:17:53] Thread state: elapsed=10000,cpu=3077,max=ReadController:ReadProcessor(780/7%),mem:max=3145728,tot=3145728,free=803605
[11:18:03] Thread state: elapsed=10000,cpu=4436,max=tag:constraints(1060/10%),mem:max=3145728,tot=3145728,free=674124
[11:18:13] Thread state: elapsed=10000,cpu=3217,max=ReadController:ReadProcessor(810/8%),mem:max=3145728,tot=3145728,free=555006
[11:18:23] Thread state: elapsed=10000,cpu=3676,max=ReadController:ReadProcessor(920/9%),mem:max=3145728,tot=3145728,free=446225
[11:18:33] Thread state: elapsed=10000,cpu=3056,max=PeerControlScheduler(760/7%),mem:max=3145728,tot=3145728,free=1765753
[11:18:43] Thread state: elapsed=10000,cpu=3753,max=ReadController:ReadProcessor(870/8%),mem:max=3145728,tot=3145728,free=1641582
[11:18:53] Thread state: elapsed=10000,cpu=7537,max=Global Status Checker(4050/40%),mem:max=3145728,tot=3145728,free=209251
[11:18:53]     java.base@21.0.3/java.io.FileDescriptor.sync0(Native Method), java.base@21.0.3/java.io.FileDescriptor.sync(FileDescriptor.java:212), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:314), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.setResumeData(DownloadManagerStateImpl.java:1194), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:1032), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:987), app//com.biglybt.core.disk.impl.resume.RDResumeHandler.saveResumeData(RDResumeHandler.java:973), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveResumeData(DiskManagerImpl.java:2654), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveResumeData(DownloadManagerImpl.java:2303), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:488), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[11:19:03] Thread state: elapsed=10000,cpu=3378,max=ReadController:ReadProcessor(830/8%),mem:max=3145728,tot=3145728,free=44873
[11:19:13] Thread state: elapsed=10001,cpu=3971,max=ReadController:ReadProcessor(1010/10%),mem:max=3145728,tot=3145728,free=1219233
[11:19:23] Thread state: elapsed=10000,cpu=3591,max=ReadController:ReadProcessor(960/9%),mem:max=3145728,tot=3145728,free=1097943
[11:19:33] Thread state: elapsed=10000,cpu=3778,max=ReadController:ReadProcessor(960/9%),mem:max=3145728,tot=3145728,free=945291
[11:19:43] Thread state: elapsed=10000,cpu=3940,max=ReadController:ReadProcessor(910/9%),mem:max=3145728,tot=3145728,free=779911
[11:19:53] Thread state: elapsed=10000,cpu=3149,max=PeerControlScheduler(750/7%),mem:max=3145728,tot=3145728,free=672604
[11:20:03] Thread state: elapsed=10000,cpu=4147,max=tag:constraints(920/9%),mem:max=3145728,tot=3145728,free=547430
[11:20:13] Thread state: elapsed=10000,cpu=3286,max=PeerControlScheduler(780/7%),mem:max=3145728,tot=3145728,free=449681
[11:20:23] Thread state: elapsed=10000,cpu=3567,max=PeerControlScheduler(780/7%),mem:max=3145728,tot=3145728,free=339912
[11:20:33] Thread state: elapsed=10000,cpu=4070,max=tag:constraints(1160/11%),mem:max=3145728,tot=3145728,free=1769387
[11:20:43] Thread state: elapsed=10000,cpu=3567,max=PeerControlScheduler(820/8%),mem:max=3145728,tot=3145728,free=1665251
[11:20:53] Thread state: elapsed=10000,cpu=3819,max=PeerControlScheduler(850/8%),mem:max=3145728,tot=3145728,free=1566558
[11:21:03] Thread state: elapsed=10000,cpu=3801,max=PeerControlScheduler(870/8%),mem:max=3145728,tot=3145728,free=1424736
[11:21:13] Thread state: elapsed=10000,cpu=3377,max=PeerControlScheduler(830/8%),mem:max=3145728,tot=3145728,free=1330161
[11:21:23] Thread state: elapsed=10000,cpu=3661,max=PeerControlScheduler(890/8%),mem:max=3145728,tot=3145728,free=1217505
[11:21:33] Thread state: elapsed=10001,cpu=4529,max=tag:constraints(1230/12%),mem:max=3145728,tot=3145728,free=1073497
[11:21:43] Thread state: elapsed=10000,cpu=3517,max=PeerControlScheduler(800/8%),mem:max=3145728,tot=3145728,free=979580
[11:21:53] Thread state: elapsed=10000,cpu=3217,max=PeerControlScheduler(760/7%),mem:max=3145728,tot=3145728,free=888291
[11:22:03] Thread state: elapsed=10000,cpu=3009,max=PeerControlScheduler(730/7%),mem:max=3145728,tot=3145728,free=751191
[11:22:13] Thread state: elapsed=10000,cpu=3267,max=PeerControlScheduler(740/7%),mem:max=3145728,tot=3145728,free=664561
[11:22:23] Thread state: elapsed=10000,cpu=3097,max=PeerControlScheduler(680/6%),mem:max=3145728,tot=3145728,free=570459
[11:22:33] Thread state: elapsed=10000,cpu=3697,max=tag:constraints(1210/12%),mem:max=3145728,tot=3145728,free=1763983
[11:22:43] Thread state: elapsed=10000,cpu=3167,max=PeerControlScheduler(740/7%),mem:max=3145728,tot=3145728,free=1646883
[11:22:53] Thread state: elapsed=10000,cpu=3036,max=PeerControlScheduler(700/7%),mem:max=3145728,tot=3145728,free=1553495
[11:23:03] Thread state: elapsed=10000,cpu=3116,max=PeerControlScheduler(720/7%),mem:max=3145728,tot=3145728,free=1441229
[11:23:13] Thread state: elapsed=10000,cpu=3286,max=PeerControlScheduler(790/7%),mem:max=3145728,tot=3145728,free=1337955
[11:23:23] Thread state: elapsed=10000,cpu=3237,max=PeerControlScheduler(800/8%),mem:max=3145728,tot=3145728,free=1247948
[11:23:33] Thread state: elapsed=10001,cpu=4508,max=tag:constraints(1250/12%),mem:max=3145728,tot=3145728,free=1114759
[11:23:43] Thread state: elapsed=10000,cpu=3428,max=PeerControlScheduler(770/7%),mem:max=3145728,tot=3145728,free=1011245
[11:23:53] Thread state: elapsed=10000,cpu=2968,max=PeerControlScheduler(680/6%),mem:max=3145728,tot=3145728,free=914087
[11:24:03] Thread state: elapsed=10002,cpu=8138,max=Global Status Checker(3390/33%),mem:max=3145728,tot=3145728,free=651954
[11:24:03]     app//com.biglybt.core.torrent.impl.TOTorrentFileImpl.serializeToMap(TOTorrentFileImpl.java:513), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToMap(TOTorrentImpl.java:520), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToByteArray(TOTorrentImpl.java:369), app//com.biglybt.core.torrent.impl.TOTorrentImpl.serialiseToBEncodedFile(TOTorrentImpl.java:232), app//com.biglybt.core.util.TorrentUtils$torrentDelegate.serialiseToBEncodedFile(TorrentUtils.java:4324), app//com.biglybt.core.download.impl.DownloadManagerStateImpl$CachedStateWrapper.serialiseToBEncodedFile(DownloadManagerStateImpl.java:5331), app//com.biglybt.core.util.TorrentUtils.writeToFile(TorrentUtils.java:476), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.saveSupport(DownloadManagerStateImpl.java:1472), app//com.biglybt.core.download.impl.DownloadManagerStateImpl.save(DownloadManagerStateImpl.java:1393), app//com.biglybt.core.disk.impl.DiskManagerImpl.storeFileDownloaded(DiskManagerImpl.java:4034), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveStateSupport(DiskManagerImpl.java:4054), app//com.biglybt.core.disk.impl.DiskManagerImpl.saveState(DiskManagerImpl.java:4042), app//com.biglybt.core.download.impl.DownloadManagerImpl.saveDownload(DownloadManagerImpl.java:2328), app//com.biglybt.core.global.impl.GlobalManagerImpl.exportDownloadStateToMapSupport(GlobalManagerImpl.java:3493), app//com.biglybt.core.global.impl.GlobalManagerImpl.saveDownloads(GlobalManagerImpl.java:3150), app//com.biglybt.core.global.impl.GlobalManagerImpl$Checker.runSupport(GlobalManagerImpl.java:429), app//com.biglybt.core.util.AEThread.run(AEThread.java:72)
[11:24:13] Thread state: elapsed=10000,cpu=3758,max=Global Status Checker(970/9%),mem:max=3145728,tot=3145728,free=1608355
[11:24:23] Thread state: elapsed=10002,cpu=2956,max=PeerControlScheduler(750/7%),mem:max=3145728,tot=3145728,free=1512665
[11:24:33] Thread state: elapsed=10000,cpu=3226,max=tag:constraints(980/9%),mem:max=3145728,tot=3145728,free=1780450
[11:24:43] Thread state: elapsed=10000,cpu=2817,max=PeerControlScheduler(660/6%),mem:max=3145728,tot=3145728,free=1680292
[11:24:53] Thread state: elapsed=10000,cpu=2956,max=PeerControlScheduler(690/6%),mem:max=3145728,tot=3145728,free=1575698
[11:25:03] Thread state: elapsed=10000,cpu=3897,max=tag:constraints(1070/10%),mem:max=3145728,tot=3145728,free=1456701
[11:25:13] Thread state: elapsed=10001,cpu=2956,max=PeerControlScheduler(670/6%),mem:max=3145728,tot=3145728,free=1354246
[11:25:23] Thread state: elapsed=10000,cpu=2927,max=PeerControlScheduler(670/6%),mem:max=3145728,tot=3145728,free=1252814
[11:25:33] Thread state: elapsed=10000,cpu=4456,max=tag:constraints(1030/10%),mem:max=3145728,tot=3145728,free=1059524
[11:25:43] Thread state: elapsed=10000,cpu=3049,max=PeerControlScheduler(670/6%),mem:max=3145728,tot=3145728,free=965439
[11:25:53] Thread state: elapsed=10001,cpu=3168,max=PeerControlScheduler(680/6%),mem:max=3145728,tot=3145728,free=847332
[11:26:03] Thread state: elapsed=10002,cpu=4129,max=tag:constraints(1100/11%),mem:max=3145728,tot=3145728,free=732039
[11:26:13] Thread state: elapsed=10003,cpu=2878,max=PeerControlScheduler(640/6%),mem:max=3145728,tot=3145728,free=620337
[11:26:23] Thread state: elapsed=10001,cpu=3287,max=PeerControlScheduler(730/7%),mem:max=3145728,tot=3145728,free=526434
[11:26:33] Thread state: elapsed=10000,cpu=3437,max=tag:constraints(1080/10%),mem:max=3145728,tot=3145728,free=1768628
[11:26:43] Thread state: elapsed=10000,cpu=3057,max=PeerControlScheduler(680/6%),mem:max=3145728,tot=3145728,free=1650399
[11:26:53] Thread state: elapsed=10000,cpu=2887,max=PeerControlScheduler(670/6%),mem:max=3145728,tot=3145728,free=1545949
[11:27:03] Thread state: elapsed=10000,cpu=3816,max=tag:constraints(1050/10%),mem:max=3145728,tot=3145728,free=1419180
[11:27:13] Thread state: elapsed=10000,cpu=2827,max=PeerControlScheduler(680/6%),mem:max=3145728,tot=3145728,free=1329738
[11:27:23] Thread state: elapsed=10000,cpu=2915,max=PeerControlScheduler(700/7%),mem:max=3145728,tot=3145728,free=1201432
[11:27:33] Thread state: elapsed=10001,cpu=2876,max=PeerControlScheduler(690/6%),mem:max=3145728,tot=3145728,free=1065678
[11:27:43] Thread state: elapsed=10000,cpu=2867,max=PeerControlScheduler(670/6%),mem:max=3145728,tot=3145728,free=975543
[11:27:53] Thread state: elapsed=10000,cpu=2916,max=PeerControlScheduler(670/6%),mem:max=3145728,tot=3145728,free=872159
[11:28:03] Thread state: elapsed=10001,cpu=3886,max=tag:constraints(1000/10%),mem:max=3145728,tot=3145728,free=749968
[11:28:13] Thread state: elapsed=10000,cpu=2526,max=PeerControlScheduler(660/6%),mem:max=3145728,tot=3145728,free=683532
[11:28:24] Thread state: elapsed=10000,cpu=2580,max=PeerControlScheduler(690/6%),mem:max=3145728,tot=3145728,free=624339
[11:28:34] Thread state: elapsed=10000,cpu=2046,max=PeerControlScheduler(540/5%),mem:max=3145728,tot=3145728,free=1787325
[11:28:44] Thread state: elapsed=10000,cpu=2416,max=PeerControlScheduler(660/6%),mem:max=3145728,tot=3145728,free=1711665
[11:28:54] Thread state: elapsed=10000,cpu=2397,max=PeerControlScheduler(640/6%),mem:max=3145728,tot=3145728,free=1655540
[11:29:04] Thread state: elapsed=10000,cpu=3477,max=tag:constraints(1060/10%),mem:max=3145728,tot=3145728,free=1577712

No freeze yet with the extended max heap size, but at this rate I expect it some time tomorrow.

2peer commented 1 month ago

OK. It happened again (even with 0.5GB heap size increase, 3GB total), but the good news is that I think I have the culprit. I read up a little bit about java memory profiling and I managed to create a heap dump & use VisualVM to look at the situation.

The result: The heap contains about 2GB of LogEvents (screenshots from VisualVM attached).

So I guess there is no limit on the amount of log entries kept in memory (at least if I don't have the "Options->Logging: Enable logging to file" enabled)? The beta release also seems to produce a lot of log events (which might be OK, but again if logged to disk it would be at the rate of about 1.3GB/day).

I guess I will disable the logging completely for now (which kind of goes against the point of running the Beta channel releases). At least until I have a confirmation / way to prevent this.

logevents_1 logevents_2

Side observation:

There also seems to be about 330MB of byte[] arrays. I have the cache size set to 32MB. Might be OK, but maybe something worth looking into?

bytearrays

For future reference:

Is it normal for Java app to freeze up like this? From what I read it should eventually crash on java.lang.OutOfMemoryError but with most of the CPU cycles spend on GC, that could take a very long time. Maybe there are ways to prevent this?
Was the low memory warning removed from the BiglyBT codebase? Because I distinctly remember getting it a few times before (back in the day I even used to run it at 768MB :)

parg commented 1 month ago

Nice. I'll take a look. The byte[] total includes lots of stuff including piece hashes - if it grows significantly then there might be an issue but I would expect it to be quite large if you have a lot of downloads active.

parg commented 1 month ago

Don't suppose you can drill down any find out what object is retaining the LogEvent objects?

parg commented 1 month ago

Are you using the console/telnet UI ?

2peer commented 1 month ago

Yes I'm using telnet UI to dynamically adjust the upload speed limit (about once every 30s). (EDIT: 30s not 20)

2peer commented 1 month ago

Don't suppose you can drill down any find out what object is retaining the LogEvent objects?

I think you might have nailed it., I'm looking at: elementData in java.util.ArrayList#217976 : 163,701 elements 24 B (0%) n/a which in turn is referenced by: errorLogEvents in com.biglybt.ui.console.ConsoleInput#406 : Console Input: Telnet Console 85 160 B (0%) n/a

Also I see a lot of ConsoleInput instances (4680) that probably didn't get destroyed after the telnet connection was closed.

parg commented 1 month ago

I can see a leak in the Console UI (used by telnet UI) that results in LogEvents being accumulated - fixed next beta.

2peer commented 1 month ago

Thanks. Much appreciated.

parg commented 1 month ago

How's the latest beta performing?

2peer commented 1 month ago

Fine so far (running for several days, memory use is stable / no leaks).

parg commented 1 month ago

coolio

BiglySoftware / BiglyBT

Possible memory leak in the Beta channel releases #3332