edxapp prerequisite installation fails unexpectedly

[jmatsushita]

Hi there,

Awesome to see that there's docker based devstack!

After running make devstack.provision there are a few errors in the run (details below) but the script completes with a green Provisioning complete!.

When connecting to port 18000 the following error shows up in the browser.

OperationalError at /
(1054, "Unknown column 'course_overviews_courseoverview.marketing_url' in 'field list'")

It seems that the problem is the import of the demo course:

TASK [demo : import demo course] ***********************************************
 OK
  Applying publisher.0003_auto_20160801_1757... OK
  Applying publisher.0004_auto_20160810_0854... OK
  Applying publisher.0005_auto_20160901_0003... OK
  Applying publisher.0006_auto_20160902_0726... OK
  Applying publisher.0007_auto_20160905_1020... OK
  Applying publisher.0008_auto_20160928_1015... OK
  Applying publisher.0009_auto_20160929_1927... OK
  Applying publisher.0010_auto_20161006_1151... OK
  Applying publisher.0011_userattributes... OK
  Applying publisher.0012_auto_20161020_0718... OK
  Applying publisher.0013_create_enable_email_notifications_switch... OK
  Applying publisher.0014_create_admin_group... OK
  Applying publisher.0015_auto_20161117_1210... OK
  Applying publisher.0016_auto_20161129_0910... OK
  Applying publisher.0017_auto_20161201_1501... OK
  Applying publisher.0018_create_internal_user_group... OK
  Applying publisher.0019_create_user_groups... OK
  Applying publisher.0020_auto_20161214_1304... OK
  Applying publisher.0021_auto_20161214_1356... OK
  Applying publisher.0022_auto_20161222_2135... OK
  Applying publisher.0023_auto_20161228_1350... OK
  Applying publisher.0024_auto_20170105_1626... OK
  Applying publisher.0025_auto_20170106_1830...changed: [127.0.0.1] => {"changed": true, "cmd": "/edx/app/edxapp/venvs/edxapp/bin/python ./manage.py cms --settings=devstack_docker import /edx/var/edxapp/data /edx/app/demo/edx-demo-course", "delta": "0:00:36.038284", "end": "2017-03-27 13:39:51.407680", "rc": 0, "start": "2017-03-27 13:39:15.369396", "stderr": "/edx/app/edxapp/edx-platform/openedx/core/djangoapps/crawlers/models.py:10: RemovedInDjango19Warning: Model class openedx.core.djangoapps.crawlers.models.CrawlersConfig doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class CrawlersConfig(ConfigurationModel):\n\n2017-03-27 09:39:22,489 WARNING 2338 [py.warnings] base.py:116 - /edx/app/edxapp/edx-platform/openedx/core/djangoapps/crawlers/models.py:10: RemovedInDjango19Warning: Model class openedx.core.djangoapps.crawlers.models.CrawlersConfig doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class CrawlersConfig(ConfigurationModel):\n\n/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:68: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.UserSocialAuth doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class UserSocialAuth(AbstractUserSocialAuth):\n\n2017-03-27 09:39:24,083 WARNING 2338 [py.warnings] base.py:116 - /edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:68: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.UserSocialAuth doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class UserSocialAuth(AbstractUserSocialAuth):\n\n/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:77: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.Nonce doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class Nonce(models.Model, DjangoNonceMixin):\n\n2017-03-27 09:39:24,086 WARNING 2338 [py.warnings] base.py:116 - /edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:77: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.Nonce doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class Nonce(models.Model, DjangoNonceMixin):\n\n/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:88: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.Association doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class Association(models.Model, DjangoAssociationMixin):\n\n2017-03-27 09:39:24,088 WARNING 2338 [py.warnings] base.py:116 - /edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:88: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.Association doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class Association(models.Model, DjangoAssociationMixin):\n\n/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:104: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.Code doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class Code(models.Model, DjangoCodeMixin):\n\n2017-03-27 09:39:24,091 WARNING 2338 [py.warnings] base.py:116 - /edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/social/apps/django_app/default/models.py:104: RemovedInDjango19Warning: Model class social.apps.django_app.default.models.Code doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class Code(models.Model, DjangoCodeMixin):\n\n/edx/app/edxapp/edx-platform/openedx/core/djangoapps/programs/models.py:10: RemovedInDjango19Warning: Model class openedx.core.djangoapps.programs.models.ProgramsApiConfig doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class ProgramsApiConfig(ConfigurationModel):\n\n2017-03-27 09:39:24,294 WARNING 2338 [py.warnings] base.py:116 - /edx/app/edxapp/edx-platform/openedx/core/djangoapps/programs/models.py:10: RemovedInDjango19Warning: Model class openedx.core.djangoapps.programs.models.ProgramsApiConfig doesn't declare an explicit app_label and either isn't in an application in INSTALLED_APPS or else was imported before its application was loaded. This will no longer be supported in Django 1.9.\n  class ProgramsApiConfig(ConfigurationModel):\n\n2017-03-27 09:39:24,856 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:24,891 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:24,917 ERROR 2338 [xmodule.vertical_block] vertical_block.py:129 - Unable to load child when parsing Vertical. Continuing...\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/vertical_block.py\", line 126, in definition_from_xml\n    child_block = system.process_xml(etree.tostring(child, encoding='unicode'))\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/modulestore/xml.py\", line 196, in process_xml\n    id_manager,\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1601, in xblock_from_node\n    self._convert_reference_fields_to_keys(block)  # difference from XBlock.runtime\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1645, in _convert_reference_fields_to_keys\n    field_value = getattr(xblock, field.name)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/xblock/fields.py\", line 507, in __get__\n    value = self.from_json(field_data.get(xblock, self.name))\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/xblock/fields.py\", line 796, in from_json\n    raise TypeError('Value stored in a List must be None or a list, found %s' % type(value))\nTypeError: Value stored in a List must be None or a list, found <type 'str'>\n2017-03-27 09:39:24,919 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:24,952 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:24,985 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,052 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,087 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,113 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,141 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,167 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,192 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,219 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,244 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,621 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,648 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,682 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,773 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,816 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,873 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:25,907 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,699 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,757 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,837 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,894 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,920 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,945 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:26,980 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:27,005 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:27,032 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:27,056 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:27,354 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent pre_publish signal to <function on_pre_publish at 0x7fa41fbdbed8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:27,863 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #0\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 128, in _update_collected\n    self.store.add(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 67, in add\n    self._add_to_cache(serialized_data, bs_model)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 159, in _add_to_cache\n    self._cache.set(cache_key, serialized_data, timeout=config.cache_timeout_in_seconds())\n  File \"/edx/app/edxapp/edx-platform/common/djangoapps/request_cache/middleware.py\", line 96, in wrapper\n    result = f(*args, **kwargs)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/config/__init__.py\", line 39, in cache_timeout_in_seconds\n    return BlockStructureConfiguration.current().cache_timeout_in_seconds\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/config_models/models.py\", line 143, in current\n    current = cls.objects.filter(**key_dict).order_by('-change_date')[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 201, in __getitem__\n    return list(qs)[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 162, in __iter__\n    self._fetch_all()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 965, in _fetch_all\n    self._result_cache = list(self.iterator())\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 238, in iterator\n    results = compiler.execute_sql()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py\", line 840, in execute_sql\n    cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 79, in execute\n    return super(CursorDebugWrapper, self).execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/utils.py\", line 98, in __exit__\n    six.reraise(dj_exc_type, dj_exc_value, traceback)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py\", line 124, in execute\n    return self.cursor.execute(query, args)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/cursors.py\", line 205, in execute\n    self.errorhandler(self, exc, value)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/connections.py\", line 36, in defaulterrorhandler\n    raise errorclass, errorvalue\nProgrammingError: (1146, \"Table 'edxapp.block_structure_config' doesn't exist\")\n2017-03-27 09:39:27,873 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #1\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 128, in _update_collected\n    self.store.add(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 67, in add\n    self._add_to_cache(serialized_data, bs_model)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 159, in _add_to_cache\n    self._cache.set(cache_key, serialized_data, timeout=config.cache_timeout_in_seconds())\n  File \"/edx/app/edxapp/edx-platform/common/djangoapps/request_cache/middleware.py\", line 96, in wrapper\n    result = f(*args, **kwargs)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/config/__init__.py\", line 39, in cache_timeout_in_seconds\n    return BlockStructureConfiguration.current().cache_timeout_in_seconds\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/config_models/models.py\", line 143, in current\n    current = cls.objects.filter(**key_dict).order_by('-change_date')[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 201, in __getitem__\n    return list(qs)[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 162, in __iter__\n    self._fetch_all()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 965, in _fetch_all\n    self._result_cache = list(self.iterator())\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 238, in iterator\n    results = compiler.execute_sql()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py\", line 840, in execute_sql\n    cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 79, in execute\n    return super(CursorDebugWrapper, self).execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/utils.py\", line 98, in __exit__\n    six.reraise(dj_exc_type, dj_exc_value, traceback)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py\", line 124, in execute\n    return self.cursor.execute(query, args)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/cursors.py\", line 205, in execute\n    self.errorhandler(self, exc, value)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/connections.py\", line 36, in defaulterrorhandler\n    raise errorclass, errorvalue\nProgrammingError: (1146, \"Table 'edxapp.block_structure_config' doesn't exist\")\n2017-03-27 09:39:27,880 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #2\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 128, in _update_collected\n    self.store.add(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 67, in add\n    self._add_to_cache(serialized_data, bs_model)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 159, in _add_to_cache\n    self._cache.set(cache_key, serialized_data, timeout=config.cache_timeout_in_seconds())\n  File \"/edx/app/edxapp/edx-platform/common/djangoapps/request_cache/middleware.py\", line 96, in wrapper\n    result = f(*args, **kwargs)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/config/__init__.py\", line 39, in cache_timeout_in_seconds\n    return BlockStructureConfiguration.current().cache_timeout_in_seconds\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/config_models/models.py\", line 143, in current\n    current = cls.objects.filter(**key_dict).order_by('-change_date')[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 201, in __getitem__\n    return list(qs)[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 162, in __iter__\n    self._fetch_all()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 965, in _fetch_all\n    self._result_cache = list(self.iterator())\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 238, in iterator\n    results = compiler.execute_sql()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py\", line 840, in execute_sql\n    cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 79, in execute\n    return super(CursorDebugWrapper, self).execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/utils.py\", line 98, in __exit__\n    six.reraise(dj_exc_type, dj_exc_value, traceback)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py\", line 124, in execute\n    return self.cursor.execute(query, args)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/cursors.py\", line 205, in execute\n    self.errorhandler(self, exc, value)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/connections.py\", line 36, in defaulterrorhandler\n    raise errorclass, errorvalue\nProgrammingError: (1146, \"Table 'edxapp.block_structure_config' doesn't exist\")\n2017-03-27 09:39:27,887 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #3\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 128, in _update_collected\n    self.store.add(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 67, in add\n    self._add_to_cache(serialized_data, bs_model)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 159, in _add_to_cache\n    self._cache.set(cache_key, serialized_data, timeout=config.cache_timeout_in_seconds())\n  File \"/edx/app/edxapp/edx-platform/common/djangoapps/request_cache/middleware.py\", line 96, in wrapper\n    result = f(*args, **kwargs)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/config/__init__.py\", line 39, in cache_timeout_in_seconds\n    return BlockStructureConfiguration.current().cache_timeout_in_seconds\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/config_models/models.py\", line 143, in current\n    current = cls.objects.filter(**key_dict).order_by('-change_date')[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 201, in __getitem__\n    return list(qs)[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 162, in __iter__\n    self._fetch_all()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 965, in _fetch_all\n    self._result_cache = list(self.iterator())\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 238, in iterator\n    results = compiler.execute_sql()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py\", line 840, in execute_sql\n    cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 79, in execute\n    return super(CursorDebugWrapper, self).execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/utils.py\", line 98, in __exit__\n    six.reraise(dj_exc_type, dj_exc_value, traceback)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py\", line 124, in execute\n    return self.cursor.execute(query, args)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/cursors.py\", line 205, in execute\n    self.errorhandler(self, exc, value)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/connections.py\", line 36, in defaulterrorhandler\n    raise errorclass, errorvalue\nProgrammingError: (1146, \"Table 'edxapp.block_structure_config' doesn't exist\")\n2017-03-27 09:39:27,894 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #4\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 128, in _update_collected\n    self.store.add(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 67, in add\n    self._add_to_cache(serialized_data, bs_model)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 159, in _add_to_cache\n    self._cache.set(cache_key, serialized_data, timeout=config.cache_timeout_in_seconds())\n  File \"/edx/app/edxapp/edx-platform/common/djangoapps/request_cache/middleware.py\", line 96, in wrapper\n    result = f(*args, **kwargs)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/config/__init__.py\", line 39, in cache_timeout_in_seconds\n    return BlockStructureConfiguration.current().cache_timeout_in_seconds\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/config_models/models.py\", line 143, in current\n    current = cls.objects.filter(**key_dict).order_by('-change_date')[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 201, in __getitem__\n    return list(qs)[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 162, in __iter__\n    self._fetch_all()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 965, in _fetch_all\n    self._result_cache = list(self.iterator())\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 238, in iterator\n    results = compiler.execute_sql()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py\", line 840, in execute_sql\n    cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 79, in execute\n    return super(CursorDebugWrapper, self).execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/utils.py\", line 98, in __exit__\n    six.reraise(dj_exc_type, dj_exc_value, traceback)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py\", line 124, in execute\n    return self.cursor.execute(query, args)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/cursors.py\", line 205, in execute\n    self.errorhandler(self, exc, value)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/connections.py\", line 36, in defaulterrorhandler\n    raise errorclass, errorvalue\nProgrammingError: (1146, \"Table 'edxapp.block_structure_config' doesn't exist\")\n2017-03-27 09:39:27,901 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #5\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 128, in _update_collected\n    self.store.add(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 67, in add\n    self._add_to_cache(serialized_data, bs_model)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/store.py\", line 159, in _add_to_cache\n    self._cache.set(cache_key, serialized_data, timeout=config.cache_timeout_in_seconds())\n  File \"/edx/app/edxapp/edx-platform/common/djangoapps/request_cache/middleware.py\", line 96, in wrapper\n    result = f(*args, **kwargs)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/config/__init__.py\", line 39, in cache_timeout_in_seconds\n    return BlockStructureConfiguration.current().cache_timeout_in_seconds\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/config_models/models.py\", line 143, in current\n    current = cls.objects.filter(**key_dict).order_by('-change_date')[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 201, in __getitem__\n    return list(qs)[0]\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 162, in __iter__\n    self._fetch_all()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 965, in _fetch_all\n    self._result_cache = list(self.iterator())\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/query.py\", line 238, in iterator\n    results = compiler.execute_sql()\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py\", line 840, in execute_sql\n    cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 79, in execute\n    return super(CursorDebugWrapper, self).execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/utils.py\", line 98, in __exit__\n    six.reraise(dj_exc_type, dj_exc_value, traceback)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/utils.py\", line 64, in execute\n    return self.cursor.execute(sql, params)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py\", line 124, in execute\n    return self.cursor.execute(query, args)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/cursors.py\", line 205, in execute\n    self.errorhandler(self, exc, value)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/MySQLdb/connections.py\", line 36, in defaulterrorhandler\n    raise errorclass, errorvalue\nProgrammingError: (1146, \"Table 'edxapp.block_structure_config' doesn't exist\")\n2017-03-27 09:39:27,991 INFO 2338 [edx.celery.task] tasks.py:157 - Starting XBlockCaches update for course_key: course-v1:edX+DemoX+Demo_Course\n2017-03-27 09:39:28,050 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@course+block@course\n2017-03-27 09:39:28,079 INFO 2338 [edx.celery.task] tasks.py:159 - Ending XBlockCaches update for course_key: course-v1:edX+DemoX+Demo_Course\n2017-03-27 09:39:28,095 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41f0a2848> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,095 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function listen_for_course_publish at 0x7fa41e2f9578> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,095 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41e2d27d0> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,096 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c664668> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,096 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function listen_for_course_publish at 0x7fa41c486cf8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,096 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a0c8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,096 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a398> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: (1054, \"Unknown column 'course_overviews_courseoverview.marketing_url' in 'field list'\")\n2017-03-27 09:39:28,096 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function trigger_update_xblocks_cache_task at 0x7fa41c48a578> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:28,096 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a500> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:31,019 ERROR 2338 [root] content.py:461 - Failed to generate thumbnail for asset-v1:edX+DemoX+Demo_Course+type@asset+block@jsmol_1hxw.png. Exception: cannot identify image file <StringIO.StringIO instance at 0x7fa41b99d368>\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/contentstore/content.py\", line 440, in generate_thumbnail\n    with Image.open(source) as image:\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/PIL/Image.py\", line 2319, in open\n    % (filename if filename else fp))\nIOError: cannot identify image file <StringIO.StringIO instance at 0x7fa41b99d368>\n2017-03-27 09:39:31,050 ERROR 2338 [root] content.py:461 - Failed to generate thumbnail for asset-v1:edX+DemoX+Demo_Course+type@asset+block@jsmol_Al2O3.png. Exception: cannot identify image file <StringIO.StringIO instance at 0x7fa41a8d9200>\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/contentstore/content.py\", line 440, in generate_thumbnail\n    with Image.open(source) as image:\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/PIL/Image.py\", line 2319, in open\n    % (filename if filename else fp))\nIOError: cannot identify image file <StringIO.StringIO instance at 0x7fa41a8d9200>\n2017-03-27 09:39:34,411 INFO 2338 [root] xml_importer.py:290 - No assets.xml file is present with asset metadata.\n2017-03-27 09:39:37,201 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent pre_publish signal to <function on_pre_publish at 0x7fa41fbdbed8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:37,747 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #0\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:38,161 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #1\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:38,413 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #2\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:38,671 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #3\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:38,923 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #4\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:39,177 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #5\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:39,190 INFO 2338 [edx.celery.task] tasks.py:157 - Starting XBlockCaches update for course_key: course-v1:edX+DemoX+Demo_Course\n2017-03-27 09:39:39,214 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@course+block@course\n2017-03-27 09:39:39,248 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@e5eac7e1a5a24f5fa7ed77bb6d136591\n2017-03-27 09:39:39,275 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@175e76c4951144a29d46211361266e0e\n2017-03-27 09:39:39,310 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_bc69a47c6fae\n2017-03-27 09:39:39,342 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@303034da25524878a2e66fb57c91cf85\n2017-03-27 09:39:39,367 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@discussion_5deb6081620d\n2017-03-27 09:39:39,387 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@ffa5817d49e14fec83ad6187cbe16358\n2017-03-27 09:39:39,423 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@e0d7423118ab432582d03e8e8dad8e36\n2017-03-27 09:39:39,439 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@dbe8fc027bcb4fe9afb744d2e8415855\n2017-03-27 09:39:39,454 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@e2cb0e0994f84b0abfa5f4ae42ed9d44\n2017-03-27 09:39:39,468 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@54bb9b142c6c4c22afc62bcb628f0e68\n2017-03-27 09:39:39,482 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@c554538a57664fac80783b99d9d6da7c\n2017-03-27 09:39:39,502 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@4a1bba2a403f40bca5ec245e945b0d76\n2017-03-27 09:39:39,518 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@f480df4ce91347c5ae4301ddf6146238\n2017-03-27 09:39:39,533 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@chapter+block@graded_interactions\n2017-03-27 09:39:39,550 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@700x_editmolB\n2017-03-27 09:39:39,564 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@3c4b575924bf4b75a2f3542df5c354fc\n2017-03-27 09:39:39,581 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@a0effb954cca4759994f1ac9e9434bf4\n2017-03-27 09:39:39,598 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@d6eaa391d2be41dea20b8b1bfbcb1c45\n2017-03-27 09:39:39,610 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@47dbd5f836544e61877a483c0b75606c\n2017-03-27 09:39:39,620 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@932e6f2ce8274072a355a94560216d1a\n2017-03-27 09:39:39,634 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_aae927868e55\n2017-03-27 09:39:39,647 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@f9f3a25e7bab46e583fd1fbbd7a2f6a0\n2017-03-27 09:39:39,657 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@f91d8d31f7cf48ce990f8d8745ae4cfa\n2017-03-27 09:39:39,668 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@e0254b911fa246218bd98bbdadffef06\n2017-03-27 09:39:39,688 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@simulations\n2017-03-27 09:39:39,707 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@graded_simulations\n2017-03-27 09:39:39,730 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@700x_proteinmake\n2017-03-27 09:39:39,752 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@8bb218cccf8d40519a971ff0e4901ccf\n2017-03-27 09:39:39,777 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@logic_gate_problem\n2017-03-27 09:39:39,797 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@9f9e1373cc8243b985c8750cc8acec7d\n2017-03-27 09:39:39,820 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_0270f6de40fc\n2017-03-27 09:39:39,838 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@d0d804e8863c4a95a659c04d8a2b2bc0\n2017-03-27 09:39:39,867 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@2bee8c4248e842a19ba1e73ed8d426c2\n2017-03-27 09:39:39,888 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@12ad4f3ff4c14114a6e629b00e000976\n2017-03-27 09:39:39,904 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@891211e17f9a472290a5f12c7a6626d7\n2017-03-27 09:39:39,921 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@workflow\n2017-03-27 09:39:39,935 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@chapter+block@social_integration\n2017-03-27 09:39:39,949 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@ed5dccf14ae94353961f46fa07217491\n2017-03-27 09:39:39,964 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@501aed9d902349eeb2191fa505548de2\n2017-03-27 09:39:39,979 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@8293139743f34377817d537b69911530\n2017-03-27 09:39:39,994 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_0fab6aa52165\n2017-03-27 09:39:40,010 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@0a3b4139f51a4917a3aff9d519b1eeb6\n2017-03-27 09:39:40,035 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@23e6eda482c04335af2bb265beacaf59\n2017-03-27 09:39:40,050 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@ddede76df71045ffa16de9d1481d2119\n2017-03-27 09:39:40,067 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@video+block@5c90cffecd9b48b188cbfea176bf7fe9\n2017-03-27 09:39:40,084 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@9cee77a606ea4c1aa5440e0ea5d0f618\n2017-03-27 09:39:40,115 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@video+block@7e9b434e6de3435ab99bd3fb25bde807\n2017-03-27 09:39:40,136 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@0d759dee4f9d459c8956136dbde55f02\n2017-03-27 09:39:40,152 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@e3601c0abee6427d8c17e6d6f8fdddd1\n2017-03-27 09:39:40,169 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@chapter+block@interactive_demonstrations\n2017-03-27 09:39:40,184 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@d2e35c1d294b4ba0b3b1048615605d2a\n2017-03-27 09:39:40,220 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@48ecb924d7fe4b66a230137626bfa93e\n2017-03-27 09:39:40,253 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@03f051f9a8814881a3783d2511613aa6\n2017-03-27 09:39:40,269 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@1a810b1a3b2447b998f0917d0e5a802b\n2017-03-27 09:39:40,284 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_98cf62510471\n2017-03-27 09:39:40,294 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@867dddb6f55d410caaa9c1eb9c6743ec\n2017-03-27 09:39:40,306 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@6f7a6670f87147149caeff6afa07a526\n2017-03-27 09:39:40,328 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@6bcccc2d7343416e9e03fd7325b2f232\n2017-03-27 09:39:40,372 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@4d672c5893cb4f1dad0de67d2008522e\n2017-03-27 09:39:40,409 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@ex_practice_limited_checks\n2017-03-27 09:39:40,468 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@5e009378f0b64585baa0a14b155974b9\n2017-03-27 09:39:40,502 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@chapter+block@d8a6192ade314473a78242dfeedfbf5b\n2017-03-27 09:39:40,532 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@75f9562c77bc4858b61f907bb810d974\n2017-03-27 09:39:40,564 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@d45779ad3d024a40a09ad8cc317c0970\n2017-03-27 09:39:40,591 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@chapter+block@1414ffd5143b4b508f739b563ab468b7\n2017-03-27 09:39:40,669 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@4f06b358a96f4d1dae57d6d81acd06f2\n2017-03-27 09:39:40,713 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@700x_pathways\n2017-03-27 09:39:40,724 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@fb79dcbad35b466a8c6364f8ffee9050\n2017-03-27 09:39:40,759 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@82d599b014b246c7a9b5dfc750dc08a9\n2017-03-27 09:39:40,811 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@Sample_Algebraic_Problem\n2017-03-27 09:39:40,835 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@f4a39219742149f781a1dda6f43a623c\n2017-03-27 09:39:40,863 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@chapter+block@9fca584977d04885bc911ea76a9ef29e\n2017-03-27 09:39:40,900 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@ade92343df3d4953a40ab3adc8805390\n2017-03-27 09:39:40,937 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@c6cd4bea43454aaea60ad01beb0cf213\n2017-03-27 09:39:40,957 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@4aba537a78774bd5a862485a8563c345\n2017-03-27 09:39:40,976 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_2dbb0072785e\n2017-03-27 09:39:40,998 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@ex_practice_3\n2017-03-27 09:39:41,022 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@ex_practice_2\n2017-03-27 09:39:41,062 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@07bc32474380492cb34f76e5f9d9a135\n2017-03-27 09:39:41,092 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@67c26b1e826e47aaa29757f62bcd1ad0\n2017-03-27 09:39:41,118 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@5ab88e67d46049b9aa694cb240c39cef\n2017-03-27 09:39:41,131 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@c7e98fd39a6944edb6b286c32e1150ff\n2017-03-27 09:39:41,145 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@cd177caa62444fbca48aa8f843f09eac\n2017-03-27 09:39:41,159 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@html_07d547513285\n2017-03-27 09:39:41,175 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_f04afeac0131\n2017-03-27 09:39:41,192 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@3dc16db8d14842e38324e95d4030b8a0\n2017-03-27 09:39:41,205 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@edx_introduction\n2017-03-27 09:39:41,218 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@python_grader\n2017-03-27 09:39:41,230 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@6244918637ed4ff4b5f94a840a7e4b43\n2017-03-27 09:39:41,259 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_d32bf9b2242c\n2017-03-27 09:39:41,291 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@4f6c1b4e316a419ab5b6bf30e6c708e9\n2017-03-27 09:39:41,323 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@b8cec2a19ebf463f90cd3544c7927b0e\n2017-03-27 09:39:41,361 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@45d46192272c4f6db6b63586520bbdf4\n2017-03-27 09:39:41,402 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@6018785795994726950614ce7d0f38c5\n2017-03-27 09:39:41,432 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_3888db0bc286\n2017-03-27 09:39:41,463 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@78d7d3642f3a4dbabbd1b017861aa5f2\n2017-03-27 09:39:41,496 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@video+block@0b9e39477cf34507a7a48f74be381fdd\n2017-03-27 09:39:41,515 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@b6662b497c094bcc9b870d8270c90c93\n2017-03-27 09:39:41,533 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@0aa7a3bdbe18427795b0c1a1d7c3cb9a\n2017-03-27 09:39:41,551 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@2152d4a4aadc4cb0af5256394a3d1fc7\n2017-03-27 09:39:41,571 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_36e0beb03f0a\n2017-03-27 09:39:41,610 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@19a30717eff543078a5d94ae9d6c18a5\n2017-03-27 09:39:41,630 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@d5a5caaf35e84ebc9a747038465dcfb4\n2017-03-27 09:39:41,647 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@934cc32c177d41b580c8413e561346b3\n2017-03-27 09:39:41,666 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@78e3719e864e45f3bee938461f3c3de6\n2017-03-27 09:39:41,680 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@3f2c11aba9434e459676a7d7acc4d960\n2017-03-27 09:39:41,702 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_1fef54c2b23b\n2017-03-27 09:39:41,715 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@6b6bee43c7c641509da71c9299cc9f5a\n2017-03-27 09:39:41,734 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@e8a5cc2aed424838853defab7be45e42\n2017-03-27 09:39:41,752 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@video+block@636541acbae448d98ab484b028c9a7f6\n2017-03-27 09:39:41,770 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@d6cee45205a449369d7ef8f159b22bdf\n2017-03-27 09:39:41,785 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_0c92347a5c00\n2017-03-27 09:39:41,814 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@c2f7008c9ccf4bd09d5d800c98fb0722\n2017-03-27 09:39:41,843 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@html_49b4494da2f7\n2017-03-27 09:39:41,876 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@030e35c4756a4ddc8d40b95fbbfff4d4\n2017-03-27 09:39:41,915 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@312cb4faed17420e82ab3178fc3e251a\n2017-03-27 09:39:41,968 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@Lab_5B_Mosfet_Amplifier_Experiment\n2017-03-27 09:39:42,036 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@openassessment+block@b24c33ea35954c7889e1d2944d3fe397\n2017-03-27 09:39:42,087 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@ed01bcd164e64038a78964a16eac3edc\n2017-03-27 09:39:42,170 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@26d89b08f75d48829a63520ed8b0037d\n2017-03-27 09:39:42,281 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_c037f3757df1\n2017-03-27 09:39:42,318 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@1b0e2c2c84884b95b1c99fb678cc964c\n2017-03-27 09:39:42,360 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@free_form_simulation\n2017-03-27 09:39:42,386 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@2574c523e97b477a9d72fbb37bfb995f\n2017-03-27 09:39:42,417 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@Sample_ChemFormula_Problem\n2017-03-27 09:39:42,437 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@256f17a44983429fb1a60802203ee4e0\n2017-03-27 09:39:42,458 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@2b94658d2eee4d85ae13f83bc24cfca9\n2017-03-27 09:39:42,477 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@870371212ba04dcf9536d7c7b8f3109e\n2017-03-27 09:39:42,496 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@2889db1677a549abb15eb4d886f95d1c\n2017-03-27 09:39:42,515 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@3169f89efde2452993f2f2d9bc74f5b2\n2017-03-27 09:39:42,534 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@134df56c516a4a0dbb24dd5facef746e\n2017-03-27 09:39:42,551 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@148ae8fa73ea460eb6f05505da0ba6e6\n2017-03-27 09:39:42,564 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@722085be27c84ac693cfebc8ac5da700\n2017-03-27 09:39:42,577 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@d1b84dcd39b0423d9e288f27f0f7f242\n2017-03-27 09:39:42,604 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@discussion+block@412dc8dbb6674014862237b23c1f643f\n2017-03-27 09:39:42,638 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@problem+block@651e0945b77f42e0a4c89b8c3e6f5b3b\n2017-03-27 09:39:42,701 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@html+block@55cbc99f262443d886a25cf84594eafb\n2017-03-27 09:39:42,731 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@6ab9c442501d472c8ed200e367b4edfa\n2017-03-27 09:39:42,751 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@sequential+block@basic_questions\n2017-03-27 09:39:42,772 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+branch@draft-branch+version@58d91610633cd2092241ebb1+type@vertical+block@vertical_ac391cde8a91\n2017-03-27 09:39:42,791 INFO 2338 [edx.celery.task] tasks.py:159 - Ending XBlockCaches update for course_key: course-v1:edX+DemoX+Demo_Course\n2017-03-27 09:39:42,797 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41f0a2848> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,797 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function listen_for_course_publish at 0x7fa41e2f9578> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,797 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41e2d27d0> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,797 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c664668> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,797 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function listen_for_course_publish at 0x7fa41c486cf8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,797 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a0c8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,798 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a398> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: (1054, \"Unknown column 'course_overviews_courseoverview.marketing_url' in 'field list'\")\n2017-03-27 09:39:42,798 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function trigger_update_xblocks_cache_task at 0x7fa41c48a578> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,798 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a500> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:42,814 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/d45779ad3d024a40a09ad8cc317c0970 has more than one definition\n2017-03-27 09:39:42,823 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/55cbc99f262443d886a25cf84594eafb has more than one definition\n2017-03-27 09:39:42,823 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:42,832 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/ade92343df3d4953a40ab3adc8805390 has more than one definition\n2017-03-27 09:39:42,858 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/ed5dccf14ae94353961f46fa07217491 has more than one definition\n2017-03-27 09:39:42,860 ERROR 2338 [xmodule.vertical_block] vertical_block.py:129 - Unable to load child when parsing Vertical. Continuing...\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/vertical_block.py\", line 126, in definition_from_xml\n    child_block = system.process_xml(etree.tostring(child, encoding='unicode'))\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/modulestore/xml.py\", line 196, in process_xml\n    id_manager,\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1601, in xblock_from_node\n    self._convert_reference_fields_to_keys(block)  # difference from XBlock.runtime\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1645, in _convert_reference_fields_to_keys\n    field_value = getattr(xblock, field.name)\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/xblock/fields.py\", line 507, in __get__\n    value = self.from_json(field_data.get(xblock, self.name))\n  File \"/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/xblock/fields.py\", line 796, in from_json\n    raise TypeError('Value stored in a List must be None or a list, found %s' % type(value))\nTypeError: Value stored in a List must be None or a list, found <type 'str'>\n2017-03-27 09:39:42,861 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:42,869 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/9f9e1373cc8243b985c8750cc8acec7d has more than one definition\n2017-03-27 09:39:42,878 WARNING 2338 [xmodule.modulestore.xml] xml.py:169 - Non-unique url_name in xml.  This may break state tracking for content.  url_name=New_Unit.  Content=<vertical display_name=\"New Unit\" parent_sequential_url=\"i4x://edX/DemoX/sequential/simulations\" ind\n2017-03-27 09:39:43,089 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:43,123 WARNING 2338 [xmodule.modulestore.xml] xml.py:169 - Non-unique url_name in xml.  This may break state tracking for content.  url_name=Video_Demonstrations.  Content=<vertical display_name=\"Video Demonstrations\" parent_sequential_url=\"i4x://edX/DemoX/sequential/19a3\n2017-03-27 09:39:43,195 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/openassessment/b24c33ea35954c7889e1d2944d3fe397 has more than one definition\n2017-03-27 09:39:43,196 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:43,204 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/12ad4f3ff4c14114a6e629b00e000976 has more than one definition\n2017-03-27 09:39:43,223 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/030e35c4756a4ddc8d40b95fbbfff4d4 has more than one definition\n2017-03-27 09:39:43,233 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/video/0b9e39477cf34507a7a48f74be381fdd has more than one definition\n2017-03-27 09:39:43,283 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/2b94658d2eee4d85ae13f83bc24cfca9 has more than one definition\n2017-03-27 09:39:43,293 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/problem/700x_editmolB has more than one definition\n2017-03-27 09:39:43,294 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:43,302 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/0aa7a3bdbe18427795b0c1a1d7c3cb9a has more than one definition\n2017-03-27 09:39:43,321 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/problem/Sample_ChemFormula_Problem has more than one definition\n2017-03-27 09:39:43,322 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:43,330 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/4d672c5893cb4f1dad0de67d2008522e has more than one definition\n2017-03-27 09:39:43,349 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/78e3719e864e45f3bee938461f3c3de6 has more than one definition\n2017-03-27 09:39:43,359 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/problem/700x_proteinmake has more than one definition\n2017-03-27 09:39:43,360 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:43,368 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/ed01bcd164e64038a78964a16eac3edc has more than one definition\n2017-03-27 09:39:43,396 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/html/Lab_5B_Mosfet_Amplifier_Experiment has more than one definition\n2017-03-27 09:39:43,396 WARNING 2338 [root] mixins.py:519 - XBlock <class 'xblock.internal.DiscussionXBlockWithMixins'> does not contain field url_name\n2017-03-27 09:39:43,405 WARNING 2338 [xmodule.modulestore.xml] xml.py:233 - i4x://edX/DemoX/discussion/03f051f9a8814881a3783d2511613aa6 has more than one definition\n2017-03-27 09:39:44,308 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent pre_publish signal to <function on_pre_publish at 0x7fa41fbdbed8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:44,909 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #0\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:45,184 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #1\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:45,458 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #2\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:45,731 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #3\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:46,006 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #4\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:46,280 ERROR 2338 [edx.celery.task] tasks.py:71 - update_course_in_cache encountered unknown error. Retry #5\nTraceback (most recent call last):\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/tasks.py\", line 54, in _call_and_retry_if_needed\n    api_method(course_key)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/api.py\", line 29, in update_course_in_cache\n    return get_block_structure_manager(course_key).update_collected_if_needed()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 115, in update_collected_if_needed\n    self._update_collected()\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/manager.py\", line 127, in _update_collected\n    BlockStructureTransformers.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/openedx/core/djangoapps/content/block_structure/transformers.py\", line 80, in collect\n    transformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/blocks_api.py\", line 50, in collect\n    StudentViewTransformer.collect(block_structure)\n  File \"/edx/app/edxapp/edx-platform/lms/djangoapps/course_api/blocks/transformers/student_view.py\", line 63, in collect\n    student_view_data = block.student_view_data()\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in student_view_data\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/video_module/video_module.py\", line 949, in <dictcomp>\n    for lang in self.available_translations(transcripts_info, verify_assets=False)\n  File \"/edx/app/edxapp/edx-platform/common/lib/xmodule/xmodule/x_module.py\", line 1480, in handler_url\n    return descriptor_global_handler_url(block, handler_name, suffix, query, thirdparty)\n  File \"/edx/app/edxapp/edx-platform/cms/lib/xblock/runtime.py\", line 14, in handler_url\n    raise NotImplementedError(\"edX Studio doesn't support third-party xblock handler urls\")\nNotImplementedError: edX Studio doesn't support third-party xblock handler urls\n2017-03-27 09:39:46,295 INFO 2338 [edx.celery.task] tasks.py:157 - Starting XBlockCaches update for course_key: course-v1:edX+DemoX+Demo_Course\n2017-03-27 09:39:46,355 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@e5eac7e1a5a24f5fa7ed77bb6d136591\n2017-03-27 09:39:46,449 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@175e76c4951144a29d46211361266e0e\n2017-03-27 09:39:46,521 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_bc69a47c6fae\n2017-03-27 09:39:46,540 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@303034da25524878a2e66fb57c91cf85\n2017-03-27 09:39:46,578 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@discussion_5deb6081620d\n2017-03-27 09:39:46,633 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@ffa5817d49e14fec83ad6187cbe16358\n2017-03-27 09:39:46,636 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@e0d7423118ab432582d03e8e8dad8e36\n2017-03-27 09:39:46,638 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@dbe8fc027bcb4fe9afb744d2e8415855\n2017-03-27 09:39:46,640 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@e2cb0e0994f84b0abfa5f4ae42ed9d44\n2017-03-27 09:39:46,643 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@54bb9b142c6c4c22afc62bcb628f0e68\n2017-03-27 09:39:46,645 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@c554538a57664fac80783b99d9d6da7c\n2017-03-27 09:39:46,851 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@4a1bba2a403f40bca5ec245e945b0d76\n2017-03-27 09:39:46,896 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@f480df4ce91347c5ae4301ddf6146238\n2017-03-27 09:39:46,941 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@700x_editmolB\n2017-03-27 09:39:46,986 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@3c4b575924bf4b75a2f3542df5c354fc\n2017-03-27 09:39:46,989 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@a0effb954cca4759994f1ac9e9434bf4\n2017-03-27 09:39:46,991 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@d6eaa391d2be41dea20b8b1bfbcb1c45\n2017-03-27 09:39:46,994 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@47dbd5f836544e61877a483c0b75606c\n2017-03-27 09:39:46,997 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@932e6f2ce8274072a355a94560216d1a\n2017-03-27 09:39:46,999 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_aae927868e55\n2017-03-27 09:39:47,001 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@f9f3a25e7bab46e583fd1fbbd7a2f6a0\n2017-03-27 09:39:47,004 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@f91d8d31f7cf48ce990f8d8745ae4cfa\n2017-03-27 09:39:47,006 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@e0254b911fa246218bd98bbdadffef06\n2017-03-27 09:39:47,009 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@simulations\n2017-03-27 09:39:47,124 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@graded_simulations\n2017-03-27 09:39:47,177 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@700x_proteinmake\n2017-03-27 09:39:47,194 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@8bb218cccf8d40519a971ff0e4901ccf\n2017-03-27 09:39:47,222 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@logic_gate_problem\n2017-03-27 09:39:47,280 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@9f9e1373cc8243b985c8750cc8acec7d\n2017-03-27 09:39:47,283 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_0270f6de40fc\n2017-03-27 09:39:47,286 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@d0d804e8863c4a95a659c04d8a2b2bc0\n2017-03-27 09:39:47,289 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@2bee8c4248e842a19ba1e73ed8d426c2\n2017-03-27 09:39:47,292 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@12ad4f3ff4c14114a6e629b00e000976\n2017-03-27 09:39:47,295 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@891211e17f9a472290a5f12c7a6626d7\n2017-03-27 09:39:47,332 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@workflow\n2017-03-27 09:39:47,366 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@ed5dccf14ae94353961f46fa07217491\n2017-03-27 09:39:47,404 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@501aed9d902349eeb2191fa505548de2\n2017-03-27 09:39:47,407 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@8293139743f34377817d537b69911530\n2017-03-27 09:39:47,409 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_0fab6aa52165\n2017-03-27 09:39:47,412 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@0a3b4139f51a4917a3aff9d519b1eeb6\n2017-03-27 09:39:47,414 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@23e6eda482c04335af2bb265beacaf59\n2017-03-27 09:39:47,417 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@ddede76df71045ffa16de9d1481d2119\n2017-03-27 09:39:47,420 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@video+block@5c90cffecd9b48b188cbfea176bf7fe9\n2017-03-27 09:39:47,422 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@9cee77a606ea4c1aa5440e0ea5d0f618\n2017-03-27 09:39:47,425 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@video+block@7e9b434e6de3435ab99bd3fb25bde807\n2017-03-27 09:39:47,427 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@0d759dee4f9d459c8956136dbde55f02\n2017-03-27 09:39:47,430 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@e3601c0abee6427d8c17e6d6f8fdddd1\n2017-03-27 09:39:47,433 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@d2e35c1d294b4ba0b3b1048615605d2a\n2017-03-27 09:39:47,435 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@48ecb924d7fe4b66a230137626bfa93e\n2017-03-27 09:39:47,437 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@03f051f9a8814881a3783d2511613aa6\n2017-03-27 09:39:47,440 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@1a810b1a3b2447b998f0917d0e5a802b\n2017-03-27 09:39:47,442 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_98cf62510471\n2017-03-27 09:39:47,444 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@867dddb6f55d410caaa9c1eb9c6743ec\n2017-03-27 09:39:47,447 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@6f7a6670f87147149caeff6afa07a526\n2017-03-27 09:39:47,449 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@6bcccc2d7343416e9e03fd7325b2f232\n2017-03-27 09:39:47,452 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@4d672c5893cb4f1dad0de67d2008522e\n2017-03-27 09:39:47,454 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@ex_practice_limited_checks\n2017-03-27 09:39:47,457 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@5e009378f0b64585baa0a14b155974b9\n2017-03-27 09:39:47,460 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@75f9562c77bc4858b61f907bb810d974\n2017-03-27 09:39:47,512 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@d45779ad3d024a40a09ad8cc317c0970\n2017-03-27 09:39:47,515 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@4f06b358a96f4d1dae57d6d81acd06f2\n2017-03-27 09:39:47,519 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@700x_pathways\n2017-03-27 09:39:47,521 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@fb79dcbad35b466a8c6364f8ffee9050\n2017-03-27 09:39:47,524 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@82d599b014b246c7a9b5dfc750dc08a9\n2017-03-27 09:39:47,527 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@Sample_Algebraic_Problem\n2017-03-27 09:39:47,530 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@f4a39219742149f781a1dda6f43a623c\n2017-03-27 09:39:47,533 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@ade92343df3d4953a40ab3adc8805390\n2017-03-27 09:39:47,536 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@c6cd4bea43454aaea60ad01beb0cf213\n2017-03-27 09:39:47,538 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@4aba537a78774bd5a862485a8563c345\n2017-03-27 09:39:47,541 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_2dbb0072785e\n2017-03-27 09:39:47,543 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@ex_practice_3\n2017-03-27 09:39:47,546 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@ex_practice_2\n2017-03-27 09:39:47,548 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@07bc32474380492cb34f76e5f9d9a135\n2017-03-27 09:39:47,550 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@67c26b1e826e47aaa29757f62bcd1ad0\n2017-03-27 09:39:47,553 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@5ab88e67d46049b9aa694cb240c39cef\n2017-03-27 09:39:47,555 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@c7e98fd39a6944edb6b286c32e1150ff\n2017-03-27 09:39:47,558 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@cd177caa62444fbca48aa8f843f09eac\n2017-03-27 09:39:47,560 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@html_07d547513285\n2017-03-27 09:39:47,563 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_f04afeac0131\n2017-03-27 09:39:47,565 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@3dc16db8d14842e38324e95d4030b8a0\n2017-03-27 09:39:47,567 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@edx_introduction\n2017-03-27 09:39:47,570 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@python_grader\n2017-03-27 09:39:47,573 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@6244918637ed4ff4b5f94a840a7e4b43\n2017-03-27 09:39:47,576 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_d32bf9b2242c\n2017-03-27 09:39:47,578 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@4f6c1b4e316a419ab5b6bf30e6c708e9\n2017-03-27 09:39:47,581 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@b8cec2a19ebf463f90cd3544c7927b0e\n2017-03-27 09:39:47,583 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@45d46192272c4f6db6b63586520bbdf4\n2017-03-27 09:39:47,586 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@6018785795994726950614ce7d0f38c5\n2017-03-27 09:39:47,588 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_3888db0bc286\n2017-03-27 09:39:47,590 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@78d7d3642f3a4dbabbd1b017861aa5f2\n2017-03-27 09:39:47,593 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@video+block@0b9e39477cf34507a7a48f74be381fdd\n2017-03-27 09:39:47,596 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@b6662b497c094bcc9b870d8270c90c93\n2017-03-27 09:39:47,599 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@0aa7a3bdbe18427795b0c1a1d7c3cb9a\n2017-03-27 09:39:47,601 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@2152d4a4aadc4cb0af5256394a3d1fc7\n2017-03-27 09:39:47,604 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_36e0beb03f0a\n2017-03-27 09:39:47,606 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@19a30717eff543078a5d94ae9d6c18a5\n2017-03-27 09:39:47,608 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@d5a5caaf35e84ebc9a747038465dcfb4\n2017-03-27 09:39:47,610 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@934cc32c177d41b580c8413e561346b3\n2017-03-27 09:39:47,613 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@78e3719e864e45f3bee938461f3c3de6\n2017-03-27 09:39:47,615 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@3f2c11aba9434e459676a7d7acc4d960\n2017-03-27 09:39:47,618 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_1fef54c2b23b\n2017-03-27 09:39:47,620 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@6b6bee43c7c641509da71c9299cc9f5a\n2017-03-27 09:39:47,623 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@e8a5cc2aed424838853defab7be45e42\n2017-03-27 09:39:47,626 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@video+block@636541acbae448d98ab484b028c9a7f6\n2017-03-27 09:39:47,629 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@d6cee45205a449369d7ef8f159b22bdf\n2017-03-27 09:39:47,631 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_0c92347a5c00\n2017-03-27 09:39:47,634 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@c2f7008c9ccf4bd09d5d800c98fb0722\n2017-03-27 09:39:47,637 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@html_49b4494da2f7\n2017-03-27 09:39:47,640 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@030e35c4756a4ddc8d40b95fbbfff4d4\n2017-03-27 09:39:47,642 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@312cb4faed17420e82ab3178fc3e251a\n2017-03-27 09:39:47,645 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@Lab_5B_Mosfet_Amplifier_Experiment\n2017-03-27 09:39:47,648 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@openassessment+block@b24c33ea35954c7889e1d2944d3fe397\n2017-03-27 09:39:47,651 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@ed01bcd164e64038a78964a16eac3edc\n2017-03-27 09:39:47,653 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@26d89b08f75d48829a63520ed8b0037d\n2017-03-27 09:39:47,655 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_c037f3757df1\n2017-03-27 09:39:47,658 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@1b0e2c2c84884b95b1c99fb678cc964c\n2017-03-27 09:39:47,661 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@free_form_simulation\n2017-03-27 09:39:47,663 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@2574c523e97b477a9d72fbb37bfb995f\n2017-03-27 09:39:47,666 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@Sample_ChemFormula_Problem\n2017-03-27 09:39:47,669 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@256f17a44983429fb1a60802203ee4e0\n2017-03-27 09:39:47,672 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@2b94658d2eee4d85ae13f83bc24cfca9\n2017-03-27 09:39:47,675 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@870371212ba04dcf9536d7c7b8f3109e\n2017-03-27 09:39:47,677 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@2889db1677a549abb15eb4d886f95d1c\n2017-03-27 09:39:47,680 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@3169f89efde2452993f2f2d9bc74f5b2\n2017-03-27 09:39:47,682 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@134df56c516a4a0dbb24dd5facef746e\n2017-03-27 09:39:47,685 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@148ae8fa73ea460eb6f05505da0ba6e6\n2017-03-27 09:39:47,688 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@722085be27c84ac693cfebc8ac5da700\n2017-03-27 09:39:47,690 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@d1b84dcd39b0423d9e288f27f0f7f242\n2017-03-27 09:39:47,693 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@412dc8dbb6674014862237b23c1f643f\n2017-03-27 09:39:47,696 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@651e0945b77f42e0a4c89b8c3e6f5b3b\n2017-03-27 09:39:47,699 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@55cbc99f262443d886a25cf84594eafb\n2017-03-27 09:39:47,701 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@6ab9c442501d472c8ed200e367b4edfa\n2017-03-27 09:39:47,703 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@sequential+block@basic_questions\n2017-03-27 09:39:47,706 INFO 2338 [edx.celery.task] tasks.py:117 - Updating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@vertical_ac391cde8a91\n2017-03-27 09:39:47,723 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@video+block@af7fe1335eb841cd81ce31c7ee8eb069\n2017-03-27 09:39:47,742 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@45c7cedb4bfe46f4a68c78787151cfb5\n2017-03-27 09:39:47,762 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@9d5104b502f24ee89c3d2f4ce9d347cf\n2017-03-27 09:39:47,785 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@video+block@ab98b0e385e64445ae97e730ffdf17e7\n2017-03-27 09:39:47,806 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@4e592689563243c484af947465eaef0d\n2017-03-27 09:39:47,854 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@ffcd6351126d4ca984409180e41d1b51\n2017-03-27 09:39:47,903 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@87fa6792d79f4862be098e5169e93339\n2017-03-27 09:39:47,937 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@a79d59cd72034188a71d388f4954a606\n2017-03-27 09:39:47,958 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@2d3efa8db04346548bd5e5374de77628\n2017-03-27 09:39:47,974 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@f0e6d90842c44cc7a50fd1a18a7dd982\n2017-03-27 09:39:47,994 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@discussion+block@1c8d47c425724346a7968fa1bc745dcd\n2017-03-27 09:39:48,012 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@fb6b62dbec4348528629cf2232b86aea\n2017-03-27 09:39:48,030 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@8f89194410954e768bde1764985454a7\n2017-03-27 09:39:48,045 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@html+block@eb469ec408fa4ab1a9b86c634ca9bfa9\n2017-03-27 09:39:48,063 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@9b9687073e904ae197799dc415df899f\n2017-03-27 09:39:48,088 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@7efc7bf4a47b4a6cb6595c32cde7712a\n2017-03-27 09:39:48,102 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@problem+block@d7daeff25e4f4026bdd269ae69e03e02\n2017-03-27 09:39:48,114 INFO 2338 [edx.celery.task] tasks.py:132 - Creating XBlockCache with usage_key: block-v1:edX+DemoX+Demo_Course+type@vertical+block@d91b9e5d8bc64d57a1332d06bf2f2193\n2017-03-27 09:39:48,127 INFO 2338 [edx.celery.task] tasks.py:159 - Ending XBlockCaches update for course_key: course-v1:edX+DemoX+Demo_Course\n2017-03-27 09:39:48,132 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41f0a2848> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,132 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function listen_for_course_publish at 0x7fa41e2f9578> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,132 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41e2d27d0> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,132 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c664668> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,132 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function listen_for_course_publish at 0x7fa41c486cf8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,133 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a0c8> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,133 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a398> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: (1054, \"Unknown column 'course_overviews_courseoverview.marketing_url' in 'field list'\")\n2017-03-27 09:39:48,133 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function trigger_update_xblocks_cache_task at 0x7fa41c48a578> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None\n2017-03-27 09:39:48,133 INFO 2338 [xmodule.modulestore.django] django.py:207 - Sent course_published signal to <function _listen_for_course_publish at 0x7fa41c48a500> with kwargs {'course_key': CourseLocator('edX', 'DemoX', 'Demo_Course', None, None)}. Response was: None", "stdout": "Importing.  Data_dir=/edx/var/edxapp/data, source_dirs=('/edx/app/demo/edx-demo-course',)\nSeeding forum roles for course course-v1:edX+DemoX+Demo_Course", "stdout_lines": ["Importing.  Data_dir=/edx/var/edxapp/data, source_dirs=('/edx/app/demo/edx-demo-course',)", "Seeding forum roles for course course-v1:edX+DemoX+Demo_Course"], "warnings": []}

[clintonb]

The demo course doesn't affect migrations. Migrations should have been run earlier, but may have failed. We've seen that in the past. Please post the complete output of running make devstack.provision.

[jmatsushita]

Thanks for your help. Here it is https://gist.github.com/jmatsushita/d0b0876e48e7a45f0f39d3d7863e5369

[clintonb]

---> pavelib.servers.update_db
---> pavelib.prereqs.install_prereqs
---> pavelib.prereqs.install_node_prereqs
Node prereqs unchanged, skipping...
---> pavelib.prereqs.install_python_prereqs
---> pavelib.prereqs.uninstall_python_packages
pip freeze
pip uninstall --disable-pip-version-check -y edxval
Uninstalling edxval-0.0.13:
Exception:
Traceback (most recent call last):
  File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/pip/basecommand.py", line 215, in main
    status = self.run(options, args)
  File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/pip/commands/uninstall.py", line 76, in run
    requirement_set.uninstall(auto_confirm=options.yes)
  File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/pip/req/req_set.py", line 346, in uninstall
    req.uninstall(auto_confirm=auto_confirm)
  File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/pip/req/req_install.py", line 754, in uninstall
    paths_to_remove.remove(auto_confirm)
  File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/pip/req/req_uninstall.py", line 115, in remove
    renames(path, new_path)
  File "/edx/app/edxapp/venvs/edxapp/local/lib/python2.7/site-packages/pip/utils/__init__.py", line 267, in renames
    shutil.move(old, new)
  File "/usr/lib/python2.7/shutil.py", line 300, in move
    rmtree(src)
  File "/usr/lib/python2.7/shutil.py", line 256, in rmtree
    onerror(os.rmdir, path, sys.exc_info())
  File "/usr/lib/python2.7/shutil.py", line 254, in rmtree
    os.rmdir(path)
OSError: [Errno 39] Directory not empty: '/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info'

Pre-reqs are failing to install, preventing the migrations from being run. @nedbat do we still need to uninstall these packages? Can we take steps to get rid of that script?

[nedbat]

Why is it failing to uninstall edxval? That seems odd.

We almost certainly don't need to still uninstall edxval, but this code was intended to be lightweight and idempotent, as a way to prevent infuriating devstack problems. We occasionally need to add new packages to the uninstall list, so I'd be very hesitant to remove the uninstall code completely. Do we think this is something particular to edxval?

[jmatsushita]

Thanks, splitting the file did make the error feedback come at the right time indeed. https://gist.github.com/jmatsushita/bfcea18fb5fe9b94a9b8ce47b4ac6d22

I'll try to investigate why edxval uninstall fails.

[jmatsushita]

There's something quite strange going on with the file system in the docker image:

ls -la /edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/

# chown -R edxapp:edxapp /edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edx_enterprise-0.27.0.dist-info': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.12-py2.7.egg-info': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/pa11ycrawler-1.6.1.dist-info': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/xblock_poll-1.2.5-py2.7.egg-info': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info/PKG-INFO': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info/SOURCES.txt': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info/dependency_links.txt': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info/installed-files.txt': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info/requires.txt': No such file or directory
chown: cannot access `/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info/top_level.txt': No such file or directory

I'm checking if it could be a file system issue on the machine I'm using. And I'll take a look at the docker build files.

[jmatsushita]

The edxops/edxapp:devstack container on the docker hub has this problem. Rebuilding a fresh edxapp container from edx/configuration (i.e. make docker.build.edxapp) doesn't result in the same unaccessible files in the site-packages repo.

Not exactly sure how this all came to be (the unaccessbile files are old versions of the packages, so maybe a race condition with the build and the pip package registry being updated?).

It might be a good idea to:

• Rebuild this somewhere (check the site-packages directory for corruption) and push to the docker registry.
• Start versioning the devstack on the docker registry.

[clintonb]

Thanks for bringing this to my attention. I have been building the images locally from a branch of edx/configuration. The problem is most likely due to the fact that, since edxapp takes so long to build, I have been using previous builds as a base image (e.g. FROM edxops/edxapp:devstack). I will build one from scratch, and push it.

[jmatsushita]

Excellent! It makes sense. I did bump into another obstacle after rebuilding from edx/configuration master branch (a missing devstack.sh entrypoint) so thanks for pointing me to the branch you've been using!

[clintonb]

A new image has been pushed to Docker Hub. Let me know if you are still having issues.

[jmatsushita]

Well unfortunately with the locally rebuilt image from the branch, I do get rid of the unaccessible files but the make devstack.provision still fails with the same error message.

OSError: [Errno 39] Directory not empty: '/edx/app/edxapp/venvs/edxapp/lib/python2.7/site-packages/edxval-0.0.13-py2.7.egg-info'

I'm trying with a fresh pull but I think it's something else.

[jmatsushita]

This issue seems to point at a Kernel and Docker overlay file system interaction. I'm looking into it further.

[jmatsushita]

I confirm that this issue came from a problem with the docker storage backend and the kernel! Sorry: it had nothing to do with EdX.

[clintonb]

No worries. This is still good information to know for future issues. Thanks!