P3906 Erratic Frame times

[Pette Broad]

This is an odd one, running on Windows with XP Pro. Starts out normally, a steady 8 and a half minutes per step, then right after the 6 hour autosend the steps leap up to 13 minutes then it does an amazing 3 minutes 18 seconds frame. After that there are a few more blips with one very long frame and one very short frame. Unit finished from Frame 73 onwards as per normal.

Code: Select all

[18:42:04] Project: 3906 (Run 536, Clone 2, Gen 1)
[18:42:04] 
[18:42:04] Assembly optimizations on if available.
[18:42:04] Entering M.D.
[18:42:10] Working on Lig in water
[18:42:10] Completed 0 out of 500000 steps  (0)
[18:42:10] Extra SSE2 boost OK
[18:48:10] Timer requesting checkpoint
[18:50:43] Completed 5000 out of 500000 steps  (1)
[18:56:43] Timer requesting checkpoint
[18:53:53] Completed 10000 out of 500000 steps  (2)
[18:59:53] Timer requesting checkpoint
[19:02:28] Completed 15000 out of 500000 steps  (3)
[19:08:28] Timer requesting checkpoint
[19:11:04] Completed 20000 out of 500000 steps  (4)
[19:17:04] Timer requesting checkpoint
[19:19:38] Completed 25000 out of 500000 steps  (5)
[19:25:38] Timer requesting checkpoint
[19:28:10] Completed 30000 out of 500000 steps  (6)
[19:34:10] Timer requesting checkpoint
[19:36:41] Completed 35000 out of 500000 steps  (7)
[19:42:41] Timer requesting checkpoint
[19:45:12] Completed 40000 out of 500000 steps  (8)
[19:51:12] Timer requesting checkpoint
[19:53:42] Completed 45000 out of 500000 steps  (9)
[19:59:42] Timer requesting checkpoint
[20:02:15] Completed 50000 out of 500000 steps  (10)
[20:08:15] Timer requesting checkpoint
[20:10:49] Completed 55000 out of 500000 steps  (11)
[20:16:49] Timer requesting checkpoint
[20:19:26] Completed 60000 out of 500000 steps  (12)
[20:25:26] Timer requesting checkpoint
[20:27:59] Completed 65000 out of 500000 steps  (13)
[20:33:59] Timer requesting checkpoint
[20:36:32] Completed 70000 out of 500000 steps  (14)
[20:42:32] Timer requesting checkpoint
[20:45:02] Completed 75000 out of 500000 steps  (15)
[20:51:02] Timer requesting checkpoint
[20:53:33] Completed 80000 out of 500000 steps  (16)
[20:59:33] Timer requesting checkpoint
[21:02:00] Completed 85000 out of 500000 steps  (17)
[21:08:00] Timer requesting checkpoint
[21:10:31] Completed 90000 out of 500000 steps  (18)
[21:16:31] Timer requesting checkpoint
[21:19:02] Completed 95000 out of 500000 steps  (19)
[21:25:02] Timer requesting checkpoint
[21:27:34] Completed 100000 out of 500000 steps  (20)
[21:33:34] Timer requesting checkpoint
[21:36:05] Completed 105000 out of 500000 steps  (21)
[21:42:05] Timer requesting checkpoint
[21:44:34] Completed 110000 out of 500000 steps  (22)
[21:50:34] Timer requesting checkpoint
[21:53:08] Completed 115000 out of 500000 steps  (23)
[21:59:09] Timer requesting checkpoint
[22:01:47] Completed 120000 out of 500000 steps  (24)
[22:07:47] Timer requesting checkpoint
[22:10:19] Completed 125000 out of 500000 steps  (25)
[22:16:19] Timer requesting checkpoint
[22:18:55] Completed 130000 out of 500000 steps  (26)
[22:24:55] Timer requesting checkpoint
[22:27:25] Completed 135000 out of 500000 steps  (27)
[22:33:25] Timer requesting checkpoint
[22:36:00] Completed 140000 out of 500000 steps  (28)
[22:42:01] Timer requesting checkpoint
[22:44:36] Completed 145000 out of 500000 steps  (29)
[22:50:37] Timer requesting checkpoint
[22:53:15] Completed 150000 out of 500000 steps  (30)
[22:59:15] Timer requesting checkpoint
[23:01:51] Completed 155000 out of 500000 steps  (31)
[23:02:57] - Autosending finished units...
[23:02:57] Trying to send all finished work units
[23:02:57] + No unsent completed units remaining.
[23:02:57] - Autosend completed
[23:07:51] Timer requesting checkpoint
[23:10:44] Completed 160000 out of 500000 steps  (32)
[23:16:44] Timer requesting checkpoint
[23:22:44] Timer requesting checkpoint
[23:23:36] Completed 165000 out of 500000 steps  (33)
[23:29:37] Timer requesting checkpoint
[23:35:37] Timer requesting checkpoint
[23:36:46] Completed 170000 out of 500000 steps  (34)
[23:42:47] Timer requesting checkpoint
[23:48:47] Timer requesting checkpoint
[23:49:58] Completed 175000 out of 500000 steps  (35)
[23:53:16] Completed 180000 out of 500000 steps  (36)
[23:59:17] Timer requesting checkpoint
[00:01:48] Completed 185000 out of 500000 steps  (37)
[00:07:49] Timer requesting checkpoint
[00:10:26] Completed 190000 out of 500000 steps  (38)
[00:16:27] Timer requesting checkpoint
[00:22:28] Timer requesting checkpoint
[00:22:34] Completed 195000 out of 500000 steps  (39)
[00:28:35] Timer requesting checkpoint
[00:34:35] Timer requesting checkpoint
[00:37:52] Completed 200000 out of 500000 steps  (40)
[00:38:52] Completed 205000 out of 500000 steps  (41)
-----------------------------------------------------------------
[12:31:43] Completed 345000 out of 500000 steps  (69)
[12:37:42] Timer requesting checkpoint
[12:40:18] Completed 350000 out of 500000 steps  (70)
[12:46:17] Timer requesting checkpoint
[13:22:25] Timer requesting checkpoint
[13:23:45] Completed 355000 out of 500000 steps  (71)
[13:29:46] Timer requesting checkpoint
[13:32:20] Completed 360000 out of 500000 steps  (72)
[13:38:21] Timer requesting checkpoint
[13:40:56] Completed 365000 out of 500000 steps  (73)

Pete

[sortofageek]

Could something else be using CPU cycles intermittently? Such as a disk defragmenter which runs off and on continuously or a malware checker or ...? Watch Processes in Task Manager to see where CPU cycles are going.

[Pette Broad]

Nothing that I know of, its pretty much a dedicated machine with only a few things installed, anyway, I'll check in the morning to see if there's anything I've forgotten about. I was only alerted to the problem by FahMon which was showing the core as "Hung". BTW, Its a dual core machine and the other core was also working on a P3906 that was showing 8mins 30 frames throughout.

Thanks,

Pete

[sortofageek]

Sometimes I have to restart FAHMon to keep it from holding on to too many CPU cycles. Lately I have been doing that about once a day.