3)
Message boards :
LHCb Application :
v0.05 task doing something
(Message 2868)
Posted 20 Apr 2016 by Zurlistuta [Puglia]
Post:
I've just completed two LHCb tasks and I notced, in both, a huge difference between RUN time and CPU time, as you can see from the log below the VM is checkpointing every 6000sec but the CPU time progress is much slower, I would say it almost stucks.
Is it normal? Is it designed to behave like this?
Guest Log: [INFO] LHCb application starting. Check log files.
2016-04-18 15:07:05 (6948): Status Report: Job Duration: '129600.000000'
2016-04-18 15:07:05 (6948): Status Report: Elapsed Time: '6007.239822'
2016-04-18 15:07:05 (6948): Status Report: CPU Time: '2541.911494'
2016-04-18 16:47:12 (6948): Status Report: Job Duration: '129600.000000'
2016-04-18 16:47:12 (6948): Status Report: Elapsed Time: '12013.953079'
2016-04-18 16:47:12 (6948): Status Report: CPU Time: '5408.289468'
2016-04-18 18:27:18 (6948): Status Report: Job Duration: '129600.000000'
2016-04-18 18:27:18 (6948): Status Report: Elapsed Time: '18020.601826'
2016-04-18 18:27:18 (6948): Status Report: CPU Time: '5788.807107'
2016-04-18 20:07:24 (6948): Status Report: Job Duration: '129600.000000'
2016-04-18 20:07:24 (6948): Status Report: Elapsed Time: '24026.204441'
2016-04-18 20:07:24 (6948): Status Report: CPU Time: '5814.204070'
2016-04-18 21:47:32 (6948): Status Report: Job Duration: '129600.000000'
2016-04-18 21:47:32 (6948): Status Report: Elapsed Time: '30033.947329'
2016-04-18 21:47:32 (6948): Status Report: CPU Time: '5856.948344'
2016-04-18 22:52:58 (6948): VM state change detected. (old = 'running', new = 'paused')
2016-04-18 23:02:13 (6948): VM state change detected. (old = 'paused', new = 'running')
2016-04-18 23:37:05 (6948): Status Report: Job Duration: '129600.000000'
2016-04-18 23:37:05 (6948): Status Report: Elapsed Time: '36042.177277'
2016-04-18 23:37:05 (6948): Status Report: CPU Time: '5889.474553'
2016-04-19 01:17:10 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 01:17:10 (6948): Status Report: Elapsed Time: '42047.886404'
2016-04-19 01:17:10 (6948): Status Report: CPU Time: '5899.271416'
2016-04-19 02:57:16 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 02:57:16 (6948): Status Report: Elapsed Time: '48053.161978'
2016-04-19 02:57:16 (6948): Status Report: CPU Time: '5908.491075'
2016-04-19 04:37:22 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 04:37:22 (6948): Status Report: Elapsed Time: '54059.110138'
2016-04-19 04:37:22 (6948): Status Report: CPU Time: '5925.588784'
2016-04-19 06:17:27 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 06:17:27 (6948): Status Report: Elapsed Time: '60064.484722'
2016-04-19 06:17:27 (6948): Status Report: CPU Time: '5935.042445'
2016-04-19 07:57:33 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 07:57:33 (6948): Status Report: Elapsed Time: '66070.527393'
2016-04-19 07:57:33 (6948): Status Report: CPU Time: '5943.606900'
2016-04-19 09:37:41 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 09:37:41 (6948): Status Report: Elapsed Time: '72078.546314'
2016-04-19 09:37:41 (6948): Status Report: CPU Time: '5957.381788'
2016-04-19 11:17:49 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 11:17:49 (6948): Status Report: Elapsed Time: '78086.054672'
2016-04-19 11:17:49 (6948): Status Report: CPU Time: '5975.571505'
2016-04-19 12:57:54 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 12:57:54 (6948): Status Report: Elapsed Time: '84091.767300'
2016-04-19 12:57:54 (6948): Status Report: CPU Time: '5991.936010'
2016-04-19 14:37:59 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 14:37:59 (6948): Status Report: Elapsed Time: '90096.293278'
2016-04-19 14:37:59 (6948): Status Report: CPU Time: '6008.659317'
2016-04-19 16:18:08 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 16:18:08 (6948): Status Report: Elapsed Time: '96105.301325'
2016-04-19 16:18:08 (6948): Status Report: CPU Time: '6023.978615'
2016-04-19 17:58:17 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 17:58:17 (6948): Status Report: Elapsed Time: '102114.648916'
2016-04-19 17:58:17 (6948): Status Report: CPU Time: '6038.549108'
2016-04-19 19:38:24 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 19:38:24 (6948): Status Report: Elapsed Time: '108121.281160'
2016-04-19 19:38:24 (6948): Status Report: CPU Time: '6051.575192'
2016-04-19 21:18:30 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 21:18:30 (6948): Status Report: Elapsed Time: '114127.192314'
2016-04-19 21:18:30 (6948): Status Report: CPU Time: '6065.194079'
2016-04-19 22:58:34 (6948): Status Report: Job Duration: '129600.000000'
2016-04-19 22:58:34 (6948): Status Report: Elapsed Time: '120130.984198'
2016-04-19 22:58:34 (6948): Status Report: CPU Time: '6077.767760'
2016-04-20 00:38:38 (6948): Status Report: Job Duration: '129600.000000'
2016-04-20 00:38:38 (6948): Status Report: Elapsed Time: '126135.766706'
2016-04-20 00:38:38 (6948): Status Report: CPU Time: '6090.513042'
2016-04-20 01:36:31 (6948): Powering off VM.
|