jswope00 / flex-grader Goto Github PK

Hello,

I have been testing the module in ginkgo, but this one writes the evaluation, but not so in the grade book. Looking for the error I found this in the log.

Aug 22 17:27:53 ginkgo [service_variant=lms][celery.worker.job][env:sandbox] INFO [ginkgo 2553] [job.py:401] - Task openedx.core.djangoapps.content.block_structure.tasks.update_course_in_cache_v2[f0986317-7780-4f8a-851a-070d473b980f] succeeded in 0.783316667017s: None Aug 22 17:27:53 ginkgo [service_variant=lms][openedx.core.djangoapps.content.block_structure.store][env:sandbox] INFO [ginkgo 14319] [store.py:176] - BlockStructure: Read from cache; <openedx.core.djangoapps.content.block_structure.store.StubModel object at 0x7efe38170 710>, size: 51853 Aug 22 17:28:15 ginkgo [service_variant=lms][openedx.core.djangoapps.content.block_structure.store][env:sandbox] INFO [ginkgo 8349] [store.py:160] - BlockStructure: Added to cache; <openedx.core.djangoapps.content.block_structure.store.StubModel object at 0x7f989dfcc09 0>, size: 51853 Aug 22 17:28:15 ginkgo [service_variant=lms][celery.worker.job][env:sandbox] INFO [ginkgo 2553] [job.py:401] - Task openedx.core.djangoapps.content.block_structure.tasks.update_course_in_cache_v2[f7a6a8e9-6fdc-48b2-a5d6-5c2880a3cc21] succeeded in 0.782671022986s: None Aug 22 17:28:25 ginkgo [service_variant=lms][flexible_grader.flexible_grader][env:sandbox] INFO [ginkgo 14288] [flexible_grader.py:315] - Init for course:course-v1:UVP+CID001+2017_08 module:block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16 f2549bda student:Ricardo Aug 22 17:28:41 ginkgo [service_variant=lms][submissions.api][env:sandbox] INFO [ginkgo 14303] [api.py:891] - Created submission uuid=3fe59d35-8789-11e7-888e-000c29c8226b for (course_id=course-v1:UVP+CID001+2017_08, item_id=block-v1:UVP+CID001+2017_08+type@flexible_gra der+block@8f0e518244c7493eb7552f16f2549bda, anonymous_student_id=5e752c97a0f6ca3c059c00944477af62) Aug 22 17:28:41 ginkgo [service_variant=lms][submissions.api][env:sandbox] INFO [ginkgo 14303] [api.py:923] - Score of (95/100) set for submission 3fe59d35-8789-11e7-888e-000c29c8226b Aug 22 17:28:41 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] INFO [ginkgo 14303] [logged_task.py:31] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] submitted with arguments None, {'only_if _higher': None, 'user_id': 10L, 'expected_modified_time': 1503440921, 'event_transaction_type': u'edx.grades.problem.submitted', 'score_db_table': 'submissions', 'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', 'course_id': u'course-v1:UVP+CID001+2017_08 ', 'anonymous_user_id': u'5e752c97a0f6ca3c059c00944477af62', 'score_deleted': False, 'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'} Aug 22 17:28:41 ginkgo [service_variant=lms][lms.djangoapps.grades.signals.handlers][env:sandbox] INFO [ginkgo 14303] [handlers.py:235] - Grades: Request async calculation of subsection grades with args: anonymous_user_id:5e752c97a0f6ca3c059c00944477af62, course_id:cou rse-v1:UVP+CID001+2017_08, modified:2017-08-22 22:28:41.078790+00:00, score_db_table:submissions, signal:<django.dispatch.dispatcher.Signal object at 0x7efe3b1e9410>, usage_id:block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda, user_ id:10, weighted_earned:95, weighted_possible:100. Task [5c30def5-f5a9-472a-82c2-a051b478e47b] Aug 22 17:28:41 ginkgo [service_variant=lms][flexible_grader.flexible_grader][env:sandbox] INFO [ginkgo 14303] [flexible_grader.py:426] - enter_grade for course:course-v1:UVP+CID001+2017_08 module:block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb 7552f16f2549bda student:Ricardo Aug 22 17:28:41 ginkgo [service_variant=lms][celery.worker.strategy][env:sandbox] INFO [ginkgo 2553] [strategy.py:55] - Received task: lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] eta:[2017-08-22 18:28:43.127312-04:0 0] Aug 22 17:28:43 ginkgo [service_variant=lms][lms.djangoapps.grades.tasks][env:sandbox] INFO [ginkgo 8349] [tasks.py:231] - Grades: tasks._has_database_updated_with_new_score is False. Task ID: 5c30def5-f5a9-472a-82c2-a051b478e47b. Kwargs: {u'only_if_higher': None, u'us er_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+2017_08', u'anonymous _user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'}. Found modified time: None Aug 22 17:28:43 ginkgo [service_variant=lms][celery.worker.strategy][env:sandbox] INFO [ginkgo 2553] [strategy.py:55] - Received task: lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] eta:[2017-08-22 18:29:13.440830-04:0 0] Aug 22 17:28:43 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] INFO [ginkgo 8349] [logged_task.py:31] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] submitted with arguments (), {u'only_if_h igher': None, u'user_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+201 7_08', u'anonymous_user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'} Aug 22 17:28:43 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] WARNING [ginkgo 8349] [logged_task.py:41] - [5c30def5-f5a9-472a-82c2-a051b478e47b] retried due to Traceback (most recent call last): File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/trace.py", line 240, in trace_task R = retval = fun(*args, **kwargs) File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/trace.py", line 438, in __protected_call__ return self.run(*args, **kwargs) File "/edx/app/edxapp/edx-platform/lms/djangoapps/grades/tasks.py", line 131, in recalculate_subsection_grade_v3 _recalculate_subsection_grade(self, **kwargs) File "/edx/app/edxapp/edx-platform/lms/djangoapps/grades/tasks.py", line 194, in _recalculate_subsection_grade raise self.retry(kwargs=kwargs, exc=exc) File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/task.py", line 684, in retry raise ret Retry: Retry in 30s: DatabaseNotReadyError() Aug 22 17:28:44 ginkgo [service_variant=lms][celery.worker.job][env:sandbox] INFO [ginkgo 2553] [job.py:415] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] retry: Retry in 30s: DatabaseNotReadyError() Aug 22 17:29:00 ginkgo [service_variant=lms][openedx.core.djangoapps.content.block_structure.store][env:sandbox] INFO [ginkgo 14319] [store.py:176] - BlockStructure: Read from cache; <openedx.core.djangoapps.content.block_structure.store.StubModel object at 0x7efe37c0e c10>, size: 51853 Aug 22 17:29:00 ginkgo [service_variant=lms][lms.djangoapps.grades.new.course_grade_factory][env:sandbox] INFO [ginkgo 14319] [course_grade_factory.py:226] - Grades: Update, Course: course_key: course-v1:UVP+CID001+2017_08, version: 599cafe04bb6770ec7aa9b50, edited_on: 2017-08-22 22:27:44.716000+00:00, grading_policy: +7GbmtBCgpiyczTqAYd2HoXna5o=, User: 10, Course Grade: percent: 0.0, letter_grade: None, passed: False, persisted: False Aug 22 17:29:13 ginkgo [service_variant=lms][lms.djangoapps.grades.tasks][env:sandbox] INFO [ginkgo 8349] [tasks.py:231] - Grades: tasks._has_database_updated_with_new_score is False. Task ID: 5c30def5-f5a9-472a-82c2-a051b478e47b. Kwargs: {u'only_if_higher': None, u'us er_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+2017_08', u'anonymous _user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'}. Found modified time: None Aug 22 17:29:13 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] INFO [ginkgo 8349] [logged_task.py:31] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] submitted with arguments (), {u'only_if_h igher': None, u'user_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+201 7_08', u'anonymous_user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'} Aug 22 17:29:13 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] WARNING [ginkgo 8349] [logged_task.py:41] - [5c30def5-f5a9-472a-82c2-a051b478e47b] retried due to Traceback (most recent call last): File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/trace.py", line 240, in trace_task R = retval = fun(*args, **kwargs) File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/trace.py", line 438, in __protected_call__ return self.run(*args, **kwargs) File "/edx/app/edxapp/edx-platform/lms/djangoapps/grades/tasks.py", line 131, in recalculate_subsection_grade_v3 _recalculate_subsection_grade(self, **kwargs) File "/edx/app/edxapp/edx-platform/lms/djangoapps/grades/tasks.py", line 194, in _recalculate_subsection_grade raise self.retry(kwargs=kwargs, exc=exc) File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/task.py", line 684, in retry raise ret Retry: Retry in 30s: DatabaseNotReadyError() Aug 22 17:29:13 ginkgo [service_variant=lms][celery.worker.strategy][env:sandbox] INFO [ginkgo 2553] [strategy.py:55] - Received task: lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] eta:[2017-08-22 18:29:43.746677-04:0 0] Aug 22 17:29:13 ginkgo [service_variant=lms][celery.worker.job][env:sandbox] INFO [ginkgo 2553] [job.py:415] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] retry: Retry in 30s: DatabaseNotReadyError() Aug 22 17:29:44 ginkgo [service_variant=lms][lms.djangoapps.grades.tasks][env:sandbox] INFO [ginkgo 8349] [tasks.py:231] - Grades: tasks._has_database_updated_with_new_score is False. Task ID: 5c30def5-f5a9-472a-82c2-a051b478e47b. Kwargs: {u'only_if_higher': None, u'us er_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+2017_08', u'anonymous _user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'}. Found modified time: None Aug 22 17:29:44 ginkgo [service_variant=lms][celery.worker.strategy][env:sandbox] INFO [ginkgo 2553] [strategy.py:55] - Received task: lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] eta:[2017-08-22 18:30:14.450284-04:0 0] Aug 22 17:29:44 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] INFO [ginkgo 8349] [logged_task.py:31] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] submitted with arguments (), {u'only_if_h igher': None, u'user_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+201 7_08', u'anonymous_user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'} Aug 22 17:29:44 ginkgo [service_variant=lms][celery_utils.logged_task][env:sandbox] WARNING [ginkgo 8349] [logged_task.py:41] - [5c30def5-f5a9-472a-82c2-a051b478e47b] retried due to Traceback (most recent call last): File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/trace.py", line 240, in trace_task R = retval = fun(*args, **kwargs) File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/trace.py", line 438, in __protected_call__ return self.run(*args, **kwargs) File "/edx/app/edxapp/edx-platform/lms/djangoapps/grades/tasks.py", line 131, in recalculate_subsection_grade_v3 _recalculate_subsection_grade(self, **kwargs) File "/edx/app/edxapp/edx-platform/lms/djangoapps/grades/tasks.py", line 194, in _recalculate_subsection_grade raise self.retry(kwargs=kwargs, exc=exc) File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/celery/app/task.py", line 684, in retry raise ret Retry: Retry in 30s: DatabaseNotReadyError() Aug 22 17:29:44 ginkgo [service_variant=lms][celery.worker.job][env:sandbox] INFO [ginkgo 2553] [job.py:415] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] retry: Retry in 30s: DatabaseNotReadyError() Aug 22 17:30:00 ginkgo [service_variant=lms][openedx.core.lib.log_utils][env:sandbox] INFO [ginkgo 14287] [log_utils.py:36] - ApiKeyHeaderPermission used: ip="127.0.0.1", path="/notifier_api/v1/users/" Aug 22 17:30:14 ginkgo [service_variant=lms][lms.djangoapps.grades.tasks][env:sandbox] INFO [ginkgo 8349] [tasks.py:231] - Grades: tasks._has_database_updated_with_new_score is False. Task ID: 5c30def5-f5a9-472a-82c2-a051b478e47b. Kwargs: {u'only_if_higher': None, u'us er_id': 10, u'expected_modified_time': 1503440921, u'event_transaction_type': u'edx.grades.problem.submitted', u'score_db_table': u'submissions', u'event_transaction_id': u'c9711ed1-5c99-45fa-8009-1442433a3ed4', u'course_id': u'course-v1:UVP+CID001+2017_08', u'anonymous _user_id': u'5e752c97a0f6ca3c059c00944477af62', u'score_deleted': False, u'usage_id': u'block-v1:UVP+CID001+2017_08+type@flexible_grader+block@8f0e518244c7493eb7552f16f2549bda'}. Found modified time: None Aug 22 17:30:15 ginkgo [service_variant=lms][celery.worker.job][env:sandbox] ERROR [ginkgo 2553] [log.py:282] - Task lms.djangoapps.grades.tasks.recalculate_subsection_grade_v3[5c30def5-f5a9-472a-82c2-a051b478e47b] raised unexpected: DatabaseNotReadyError()