Project

Profile

Help

Issue #675

closed

task start time and finish time are reported as the same

Added by dkliban@redhat.com about 9 years ago. Updated almost 5 years ago.

Status:
CLOSED - CURRENTRELEASE
Priority:
High
Category:
-
Sprint/Milestone:
-
Start date:
Due date:
Estimated time:
Severity:
3. High
Version:
2.6 Beta
Platform Release:
2.6.0
OS:
Triaged:
Yes
Groomed:
No
Sprint Candidate:
No
Tags:
Pulp 2
Sprint:
Quarter:

Description

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:

+ This bug was cloned from Bugzilla Bug #1185446 +

Actions #1

Updated by dkliban@redhat.com about 9 years ago

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 comment was cloned from Bugzilla #1185446 comment 1 +

Actions #2

Updated by dkliban@redhat.com about 9 years ago

This was fixed when another bug got fixed. Here is the PR that fixed both:

https://github.com/pulp/pulp/pull/1588

+ This comment was cloned from Bugzilla #1185446 comment 2 +

Actions #3

Updated by cduryee about 9 years ago

2.6.0-0.7.beta

+ This comment was cloned from Bugzilla #1185446 comment 3 +

Actions #4

Updated by igulina@redhat.com about 9 years ago

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: &lt;pulp.server.async.tasks.TaskResult object at 0x7f5424011910&gt;

+ This comment was cloned from Bugzilla #1185446 comment 4 +

Actions #5

Updated by bmbouter about 9 years ago

  • Severity changed from High to 3. High
Actions #6

Updated by rbarlow almost 9 years ago

  • Status changed from 6 to CLOSED - CURRENTRELEASE
Actions #8

Updated by bmbouter almost 5 years ago

  • Tags Pulp 2 added

Also available in: Atom PDF