Description of problem: Version-Release number of selected component (if applicable): How reproducible: Steps to Reproduce: 1. Create repo 2. Sync and publish repo 3. View task list Actual results: Each task has the same time listed for start and finish. Expected results: Start and finish time should be at least 1 second apart. You can see this by looking at the out put in the logs. Additional info:
The title of the bug is misleading. After some investigation I was able to determine that this occurs for tasks that inherit from pulp.server.async.tasks.Task and that call another Task synchronously. Celery assigned the Task called synchronously the same id as the task that called it. Because we use an upsert when creating a TaskStatus, the start time gets changed when the synchronous Task is run. To avoid this problem we should not have an Task call any other Task synchronously. This will always create erroneous TaskStatus start times. However, regular celery tasks can still be called synchronously. The fix for this will be part of the fix for https://bugzilla.redhat.com/show_bug.cgi?id=1120671
This was fixed when another bug got fixed. Here is the PR that fixed both: https://github.com/pulp/pulp/pull/1588
2.6.0-0.7.beta
>> rpm -qa pulp-server pulp-server-2.6.0-0.7.beta.el6.noarch >> pulp-admin tasks list .... Operations: sync Resources: epel_6_1 (repository) State: Successful Start Time: 2015-02-13T14:03:21Z Finish Time: 2015-02-13T14:41:21Z Task Id: 36d8e151-ae58-45ea-8bbc-3f19935ff4df Operations: delete Resources: epel_6_1 (repository) State: Successful Start Time: 2015-02-13T14:53:43Z Finish Time: 2015-02-13T14:53:46Z Task Id: 4da8d6bf-4163-4241-b53b-1ade249f6e90 Operations: publish Resources: epel_6_1 (repository) State: Successful Start Time: 2015-02-13T14:41:21Z Finish Time: 2015-02-13T14:42:40Z Task Id: 605d3858-bb17-46b2-a49f-36dc5a5bd9a7 Operations: publish Resources: epel_6_1 (repository) State: Successful Start Time: 2015-02-13T14:50:42Z Finish Time: 2015-02-13T14:51:30Z Task Id: 5406d2da-fce3-4008-8e13-9b433bb649c8 celery.worker.job:INFO: Task pulp.server.managers.repo.sync.sync[36d8e151-ae58-45ea-8bbc-3f19935ff4df] succeeded in 2279.51823536s: <pulp.server.async.tasks.TaskResult object at 0x36bb290> celery.worker.job:INFO: Task pulp.server.managers.repo.publish.publish[605d3858-bb17-46b2-a49f-36dc5a5bd9a7] succeeded in 78.835292676s: {'exception': None, 'repo_id': u'epel_6_1', 'traceback': None, 'started': '2015-02-13T14:41:21Z', '_ns':... celery.worker.job:INFO: Task pulp.server.managers.repo.publish.publish[5406d2da-fce3-4008-8e13-9b433bb649c8] succeeded in 48.202181368s: {'exception': None, 'repo_id': u'epel_6_1', 'traceback': None, 'started': '2015-02-13T14:50:42Z', '_ns':... celery.worker.job:INFO: Task pulp.server.tasks.repository.delete[4da8d6bf-4163-4241-b53b-1ade249f6e90] succeeded in 2.726432841s: <pulp.server.async.tasks.TaskResult object at 0x7f5424011910>
Moved to https://pulp.plan.io/issues/675