Kolibri server is not running

Hi everyone!

Thank you for creating this wonderful application called Kolibri.
I cannot run it the sever is not running. it is just showing “Kolibir server is starting” but nothing happens. I use Win 7 and it is 32bit.

Thank you!

Hi -

What version of Kolibri are you running?

Would you mind looking in the log files and checking for errors?

thank you
Devon

Thanks Devon! I am using 0.12.12 version, the latest version.
After installation, it runs server but after restarting computer or closing Kolibri it does not.

Kolibri Log File
INFO 2019-04-29 20:09:19,569 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-29 20:09:20,145 cli Kolibri running for the first time.
INFO 2019-04-29 20:09:20,146 cli We don’t yet use pre-migrated database seeds, so you’re going to have to wait a bit while we create a blank database…

INFO 2019-04-29 20:09:54,375 cli Automatically enabling applications.
INFO 2019-04-29 20:09:54,376 cli Running update routines for new version…
INFO 2019-04-29 20:09:59,825 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-29 20:09:59,844 cli Running ‘kolibri start’ as daemon (system service)
ERROR 2019-04-29 20:10:00,077 cli Could not detect an IP address that Kolibri binds to, but try opening up the following addresses:

INFO 2019-04-29 20:10:00,132 cli Going to daemon mode, logging to C:\Users\jmekhteyari.kolibri\server.log
INFO 2019-04-29 20:10:00,149 ping Attempting a ping.
INFO 2019-04-29 20:10:00,394 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-29 20:10:00,395 vacuumsqlite Next Vacuum at 3AM local server time (in 409.98333333333335 minutes).
WARNING 2019-04-29 20:10:00,867 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 20:10:01,520 utils Started new MESSAGEPROCESSOR thread ID#happy-ink-early-ceiling
INFO 2019-04-29 20:10:01,521 utils Started new SCHEDULER thread ID#potato-georgia-bluebird-nuts
INFO 2019-04-29 20:10:01,523 utils Started new WORKERMESSAGEHANDLER thread ID#neptune-hot-finch-network
INFO 2019-04-29 20:10:01,740 wsgi Starting Kolibri
INFO 2019-04-29 20:25:00,867 ping Attempting a ping.
WARNING 2019-04-29 20:25:00,949 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 20:40:00,951 ping Attempting a ping.
WARNING 2019-04-29 20:40:01,027 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 20:55:01,027 ping Attempting a ping.
WARNING 2019-04-29 20:55:01,100 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 21:10:01,102 ping Attempting a ping.
WARNING 2019-04-29 21:10:01,192 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 21:25:01,193 ping Attempting a ping.
WARNING 2019-04-29 21:25:01,276 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 21:40:01,278 ping Attempting a ping.
WARNING 2019-04-29 21:40:01,361 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-29 21:55:01,362 ping Attempting a ping.
WARNING 2019-04-29 21:55:01,412 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-30 05:12:27,531 ping Attempting a ping.
WARNING 2019-04-30 05:12:33,891 ping Ping failed (could not connect). Trying again in 15.0 minutes.
INFO 2019-04-30 05:12:37,195 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 05:12:37,195 vacuumsqlite Next Vacuum at 3AM local server time (in 1307.3666666666666 minutes).
INFO 2019-04-30 05:26:44,613 importchannel Downloading data for channel id 8b28761bac075deeb66adc6c80ef119c
INFO 2019-04-30 05:27:33,419 channel_import Importing ContentTag data
INFO 2019-04-30 05:27:33,419 channel_import Importing ContentNode_related data
INFO 2019-04-30 05:27:33,419 channel_import Importing ContentNode_has_prerequisite data
INFO 2019-04-30 05:27:33,419 channel_import Importing ContentNode_tags data
INFO 2019-04-30 05:27:33,419 channel_import Importing ContentNode data
INFO 2019-04-30 05:27:33,529 channel_import Importing Language data
INFO 2019-04-30 05:27:33,529 channel_import Importing File data
INFO 2019-04-30 05:27:33,656 channel_import Importing LocalFile data
INFO 2019-04-30 05:27:33,674 channel_import Importing AssessmentMetaData data
INFO 2019-04-30 05:27:33,691 channel_import Importing ChannelMetadata data
INFO 2019-04-30 05:27:33,895 ping Attempting a ping.
INFO 2019-04-30 05:27:34,960 annotation Setting availability of File objects based on LocalFile availability
INFO 2019-04-30 05:27:36,299 annotation Setting availability of non-topic ContentNode objects based on File availability
INFO 2019-04-30 05:27:36,949 annotation Setting availability of ContentNode objects with children for 2 levels
INFO 2019-04-30 05:27:36,954 annotation Setting availability of ContentNode objects with children for level 2
INFO 2019-04-30 05:27:36,976 annotation Setting availability of ContentNode objects with children for level 1
INFO 2019-04-30 05:27:37,056 annotation Setting totals of coach content ContentNode objects with children for 2 levels
INFO 2019-04-30 05:27:37,061 annotation Setting totals of coach content ContentNode objects with children for level 2
INFO 2019-04-30 05:27:37,084 annotation Setting totals of coach content ContentNode objects with children for level 1
INFO 2019-04-30 05:27:38,016 ping Ping succeeded! (response: {‘id’: 223196, ‘messages’: []})
INFO 2019-04-30 05:27:40,275 ping Sleeping for 1440.0 minutes.
INFO 2019-04-30 05:35:55,097 importchannel Downloading data for channel id 1ceff53605e55bef987d88e0908658c5
ERROR 2019-04-30 05:45:12,941 importchannel An error occurred during channel import: (sqlite3.DatabaseError) database disk image is malformed [SQL: “SELECT name FROM sqlite_master WHERE type=‘table’ ORDER BY name”] (Background on this error at: http://sqlalche.me/e/4xp6)
WARNING 2019-04-30 05:45:13,162 base Job 9c26f8838bce4888959f6013d1c23d9c raised an exception: Traceback (most recent call last):
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1193, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\default.py”, line 509, in do_execute
cursor.execute(statement, parameters)
sqlite3.DatabaseError: database disk image is malformed

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 75, in handle_finished_future
result = future.result()
File “C:\Python34\lib\concurrent\futures_base.py”, line 395, in result
return self.__get_result()
File “C:\Python34\lib\concurrent\futures_base.py”, line 354, in __get_result
raise self.exception
File “C:\Python34\lib\concurrent\futures\thread.py”, line 54, in run
result = self.fn(*self.args, **self.kwargs)
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 149, in wrap
raise e
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 145, in wrap
return f(*args, **kwargs)
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\common\classes.py”, line 120, in y
return func(*args, **kwargs)
File "C:\Python34\lib\site-packages\kolibri\dist\django\core\management_init
.py", line 131, in call_command
return command.execute(*args, **defaults)
File “C:\Python34\lib\site-packages\kolibri\dist\django\core\management\base.py”, line 330, in execute
output = self.handle(*args, **options)
File “C:\Python34\lib\site-packages\kolibri\core\tasks\management\commands\base.py”, line 89, in handle
return self.handle_async(*args, **options)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 145, in handle_async
self.download_channel(options[“channel_id”], options[“baseurl”])
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 78, in download_channel
self._transfer(DOWNLOAD_METHOD, channel_id, baseurl)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 101, in _transfer
finished = self._start_file_transfer(filetransfer, channel_id, dest)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 135, in _start_file_transfer
retry_import(e, skip_404=False)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\import_export_content.py”, line 116, in retry_import
raise e
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 120, in _start_file_transfer
import_channel_by_id(channel_id, self.is_cancelled)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 24, in import_channel_by_id
channel_import.import_channel_from_local_db(channel_id, cancel_check=cancel_check)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channel_import.py”, line 673, in import_channel_from_local_db
import_manager = initialize_import_manager(channel_id, cancel_check=cancel_check)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channel_import.py”, line 647, in initialize_import_manager
channel_metadata = read_channel_metadata_from_db_file(get_content_database_file_path(channel_id))
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channels.py”, line 59, in read_channel_metadata_from_db_file
source = Bridge(sqlite_file_path=channeldbpath)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\sqlalchemybridge.py”, line 216, in init
db_matches_schema(self.Base, self.session)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\check_schema_db.py”, line 24, in db_matches_schema
tables = iengine.get_table_names()
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\reflection.py”, line 201, in get_table_names
self.bind, schema, info_cache=self.info_cache)
File “”, line 2, in get_table_names
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\reflection.py”, line 54, in cache
ret = fn(self, con, *args, **kw)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\dialects\sqlite\base.py”, line 1160, in get_table_names
rs = connection.execute(s)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 2075, in execute
return connection.execute(statement, *multiparams, **params)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 942, in execute
return self._execute_text(object, multiparams, params)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1104, in _execute_text
statement, parameters
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1200, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1413, in _handle_dbapi_exception
exc_info
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\util\compat.py”, line 265, in raise_from_cause
reraise(type(exception), exception, tb=exc_tb, cause=cause)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\util\compat.py”, line 248, in reraise
raise value.with_traceback(tb)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1193, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\default.py”, line 509, in do_execute
cursor.execute(statement, parameters)
sqlalchemy.exc.DatabaseError: (sqlite3.DatabaseError) database disk image is malformed [SQL: “SELECT name FROM sqlite_master WHERE type=‘table’ ORDER BY name”] (Background on this error at: http://sqlalche.me/e/4xp6)

INFO 2019-04-30 05:49:39,217 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 05:49:49,762 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
ERROR 2019-04-30 05:49:50,360 cli During graceful shutdown, server says: Unclean shutdown (7)
ERROR 2019-04-30 05:49:50,474 cli Not responding, killing with force
INFO 2019-04-30 05:51:24,314 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 05:51:25,517 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 05:51:25,580 cli Running update routines for new version…
INFO 2019-04-30 05:55:25,684 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 05:55:27,216 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 05:55:27,239 cli Running update routines for new version…
INFO 2019-04-30 05:58:39,069 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
ERROR 2019-04-30 05:58:39,602 cli During graceful shutdown, server says: Failed to start (check log file: D:.kolibri\server.log) (6)
ERROR 2019-04-30 05:58:39,633 cli Not responding, killing with force
ERROR 2019-04-30 05:58:39,664 server Could not read .pid file

INFO 2019-04-30 06:00:49,324 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 06:00:51,361 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 06:00:51,381 cli Running update routines for new version…
INFO 2019-04-30 06:01:33,565 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
ERROR 2019-04-30 06:01:34,282 cli During graceful shutdown, server says: Failed to start (check log file: C:\Users\jmekhteyari.kolibri\server.log) (6)
ERROR 2019-04-30 06:01:34,314 cli Not responding, killing with force
ERROR 2019-04-30 06:01:34,330 server Could not read .pid file

INFO 2019-04-30 06:01:50,327 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 06:01:52,274 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 06:01:52,289 cli Running update routines for new version…
INFO 2019-04-30 06:02:07,756 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 06:02:09,949 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 06:02:09,964 cli Running update routines for new version…
INFO 2019-04-30 06:02:11,093 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 06:02:13,194 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 06:02:13,210 cli Running update routines for new version…
INFO 2019-04-30 06:03:51,040 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
ERROR 2019-04-30 06:03:51,656 cli During graceful shutdown, server says: Failed to start (check log file: C:\Users\jmekhteyari.kolibri\server.log) (6)
ERROR 2019-04-30 06:03:51,691 cli Not responding, killing with force
ERROR 2019-04-30 06:03:51,713 server Could not read .pid file

INFO 2019-04-30 09:40:39,113 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 09:40:40,346 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 09:40:40,359 cli Running update routines for new version…
INFO 2019-04-30 09:43:37,727 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
ERROR 2019-04-30 09:43:38,349 cli During graceful shutdown, server says: Failed to start (check log file: C:\Users\jmekhteyari.kolibri\server.log) (6)
ERROR 2019-04-30 09:43:38,394 cli Not responding, killing with force
ERROR 2019-04-30 09:43:38,419 server Could not read .pid file

INFO 2019-04-30 09:44:07,001 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-04-30 09:44:08,682 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-04-30 09:44:08,702 cli Running update routines for new version…
INFO 2019-05-07 14:07:41,386 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
INFO 2019-05-07 14:07:43,137 vacuumsqlite Sqlite database Vacuum finished.
INFO 2019-05-07 14:07:43,153 cli Running update routines for new version…
INFO 2019-05-07 14:12:06,086 apps Running Kolibri with the following settings: kolibri.deployment.default.settings.base
ERROR 2019-05-07 14:12:06,685 cli During graceful shutdown, server says: Failed to start (check log file: C:\Users\jmekhteyari.kolibri\server.log) (6)
ERROR 2019-05-07 14:12:06,786 cli Not responding, killing with force
ERROR 2019-05-07 14:12:06,810 server Could not read .pid file

Do you see anything in server.log?

Yes Devon I see server.log.

Server.log
e[37mINFO Attempting a ping.e[0m
e[37mINFO Sqlite database Vacuum finished.e[0m
e[37mINFO Next Vacuum at 3AM local server time (in 409.98333333333335 minutes).e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Started new MESSAGEPROCESSOR thread ID#happy-ink-early-ceilinge[0m
e[37mINFO Started new SCHEDULER thread ID#potato-georgia-bluebird-nutse[0m
e[37mINFO Started new WORKERMESSAGEHANDLER thread ID#neptune-hot-finch-networke[0m
e[37mINFO Starting Kolibrie[0m
INFO:cherrypy.error:[29/Apr/2019:20:10:01] ENGINE Bus STARTING
INFO:cherrypy.error:[29/Apr/2019:20:10:02] ENGINE Serving on http://0.0.0.0:8080
INFO:cherrypy.error:[29/Apr/2019:20:10:02] ENGINE Bus STARTED
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:18] “GET /static/default_frontend/default_frontend-0.12.2.js HTTP/1.1” 200 335860 “http://127.0.0.1:8080/setup_wizard/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:18] “GET /static/setup_wizard/setup_wizard0.12.2.css HTTP/1.1” 200 3715 “http://127.0.0.1:8080/setup_wizard/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:18] “GET /static/setup_wizard/setup_wizard-0.12.2.js HTTP/1.1” 200 17792 “http://127.0.0.1:8080/setup_wizard/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:19] “GET /static/assets/fonts/noto-full.NotoSans.700.woff HTTP/1.1” 200 433488 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:19] “GET /static/assets/fonts/noto-full.NotoSans.400.woff HTTP/1.1” 200 432828 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:20] “GET /static/assets/logo.ico HTTP/1.1” 200 5430 “http://127.0.0.1:8080/setup_wizard/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:58] “GET /static/device_management_module/device_management_module0.12.2.css HTTP/1.1” 200 1409 “http://127.0.0.1:8080/device/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:58] “GET /static/device_management_module/device_management_module-0.12.2.js HTTP/1.1” 200 60078 “http://127.0.0.1:8080/device/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:10:59] “GET /static/default_frontend/kolibri-logo.svg?625360a31b0628f83d4ca2ef861dbd30 HTTP/1.1” 200 10220 “http://127.0.0.1:8080/device/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:11:11] “GET /static/user_module/user_module-0.12.2.js HTTP/1.1” 200 22653 “http://127.0.0.1:8080/user/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:11:11] “GET /static/user_module/user_module0.12.2.css HTTP/1.1” 200 1150 “http://127.0.0.1:8080/user/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:11:16] “GET /static/user_module/background.jpg?28fe1b0b4573aa9a6539bd223faca617 HTTP/1.1” 200 211311 “http://127.0.0.1:8080/user/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:11:39] “GET /static/learn_module/learn_module0.12.2.css HTTP/1.1” 200 3203 “http://127.0.0.1:8080/learn/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:11:39] “GET /static/learn_module/learn_module-0.12.2.js HTTP/1.1” 200 84185 “http://127.0.0.1:8080/learn/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:15:53] “GET /static/facility_management_module/facility_management_module0.12.2.css HTTP/1.1” 200 699 “http://127.0.0.1:8080/facility/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:15:53] “GET /static/facility_management_module/facility_management_module-0.12.2.js HTTP/1.1” 200 37396 “http://127.0.0.1:8080/facility/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:16:23] “GET /static/coach_module/coach_module0.12.2.css HTTP/1.1” 200 4219 “http://127.0.0.1:8080/coach/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [29/Apr/2019:20:16:23] “GET /static/coach_module/coach_module-0.12.2.js HTTP/1.1” 200 148332 “http://127.0.0.1:8080/coach/” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Attempting a ping.e[0m
e[33mWARNING Ping failed (could not connect). Trying again in 15.0 minutes.e[0m
e[37mINFO Sqlite database Vacuum finished.e[0m
e[37mINFO Next Vacuum at 3AM local server time (in 1307.3666666666666 minutes).e[0m
INFO:cherrypy.access.112401656:127.0.0.1 - - [30/Apr/2019:05:24:34] “GET /static/assets/fonts/noto-full.NotoSansGujarati.700.woff HTTP/1.1” 200 187244 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [30/Apr/2019:05:26:08] “GET /static/assets/fonts/noto-full.NotoSansArabic.700.woff HTTP/1.1” 200 176396 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [30/Apr/2019:05:26:08] “GET /static/assets/fonts/noto-full.NotoSansArabic.400.woff HTTP/1.1” 200 163056 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [30/Apr/2019:05:26:08] “GET /static/assets/fonts/noto-full.NotoSansDevanagari.700.woff HTTP/1.1” 200 190804 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [30/Apr/2019:05:26:10] “GET /static/assets/fonts/noto-full.NotoSansGujarati.400.woff HTTP/1.1” 200 186112 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
INFO:cherrypy.access.112401656:127.0.0.1 - - [30/Apr/2019:05:26:10] “GET /static/assets/fonts/noto-full.NotoSansDevanagari.400.woff HTTP/1.1” 200 181776 “http://127.0.0.1:8080/static/assets/fonts/noto-full.en.modern.css?v=0.12.2” “Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.132”
e[37mINFO Downloading data for channel id 8b28761bac075deeb66adc6c80ef119ce[0m
e[37mINFO Importing ContentTag datae[0m
e[37mINFO Importing ContentNode_related datae[0m
e[37mINFO Importing ContentNode_has_prerequisite datae[0m
e[37mINFO Importing ContentNode_tags datae[0m
e[37mINFO Importing ContentNode datae[0m
e[37mINFO Importing Language datae[0m
e[37mINFO Importing File datae[0m
e[37mINFO Importing LocalFile datae[0m
e[37mINFO Importing AssessmentMetaData datae[0m
e[37mINFO Importing ChannelMetadata datae[0m
e[37mINFO Attempting a ping.e[0m
e[37mINFO Setting availability of File objects based on LocalFile availabilitye[0m
e[37mINFO Setting availability of non-topic ContentNode objects based on File availabilitye[0m
e[37mINFO Setting availability of ContentNode objects with children for 2 levelse[0m
e[37mINFO Setting availability of ContentNode objects with children for level 2e[0m
e[37mINFO Setting availability of ContentNode objects with children for level 1e[0m
e[37mINFO Setting totals of coach content ContentNode objects with children for 2 levelse[0m
e[37mINFO Setting totals of coach content ContentNode objects with children for level 2e[0m
e[37mINFO Setting totals of coach content ContentNode objects with children for level 1e[0m
e[37mINFO Ping succeeded! (response: {‘id’: 223196, ‘messages’: []})e[0m
e[37mINFO Sleeping for 1440.0 minutes.e[0m
e[37mINFO Downloading data for channel id 1ceff53605e55bef987d88e0908658c5e[0m
e[31mERROR An error occurred during channel import: (sqlite3.DatabaseError) database disk image is malformed [SQL: “SELECT name FROM sqlite_master WHERE type=‘table’ ORDER BY name”] (Background on this error at: http://sqlalche.me/e/4xp6)e[0m
e[33mWARNING Job 9c26f8838bce4888959f6013d1c23d9c raised an exception: Traceback (most recent call last):
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1193, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\default.py”, line 509, in do_execute
cursor.execute(statement, parameters)
sqlite3.DatabaseError: database disk image is malformed

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 75, in handle_finished_future
result = future.result()
File “C:\Python34\lib\concurrent\futures_base.py”, line 395, in result
return self.__get_result()
File “C:\Python34\lib\concurrent\futures_base.py”, line 354, in __get_result
raise self.exception
File “C:\Python34\lib\concurrent\futures\thread.py”, line 54, in run
result = self.fn(*self.args, **self.kwargs)
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 149, in wrap
raise e
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 145, in wrap
return f(*args, **kwargs)
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\common\classes.py”, line 120, in y
return func(*args, **kwargs)
File "C:\Python34\lib\site-packages\kolibri\dist\django\core\management_init
.py", line 131, in call_command
return command.execute(*args, **defaults)
File “C:\Python34\lib\site-packages\kolibri\dist\django\core\management\base.py”, line 330, in execute
output = self.handle(*args, **options)
File “C:\Python34\lib\site-packages\kolibri\core\tasks\management\commands\base.py”, line 89, in handle
return self.handle_async(*args, **options)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 145, in handle_async
self.download_channel(options[“channel_id”], options[“baseurl”])
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 78, in download_channel
self._transfer(DOWNLOAD_METHOD, channel_id, baseurl)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 101, in _transfer
finished = self._start_file_transfer(filetransfer, channel_id, dest)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 135, in _start_file_transfer
retry_import(e, skip_404=False)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\import_export_content.py”, line 116, in retry_import
raise e
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 120, in _start_file_transfer
import_channel_by_id(channel_id, self.is_cancelled)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 24, in import_channel_by_id
channel_import.import_channel_from_local_db(channel_id, cancel_check=cancel_check)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channel_import.py”, line 673, in import_channel_from_local_db
import_manager = initialize_import_manager(channel_id, cancel_check=cancel_check)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channel_import.py”, line 647, in initialize_import_manager
channel_metadata = read_channel_metadata_from_db_file(get_content_database_file_path(channel_id))
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channels.py”, line 59, in read_channel_metadata_from_db_file
source = Bridge(sqlite_file_path=channeldbpath)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\sqlalchemybridge.py”, line 216, in init
db_matches_schema(self.Base, self.session)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\check_schema_db.py”, line 24, in db_matches_schema
tables = iengine.get_table_names()
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\reflection.py”, line 201, in get_table_names
self.bind, schema, info_cache=self.info_cache)
File “”, line 2, in get_table_names
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\reflection.py”, line 54, in cache
ret = fn(self, con, *args, **kw)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\dialects\sqlite\base.py”, line 1160, in get_table_names
rs = connection.execute(s)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 2075, in execute
return connection.execute(statement, *multiparams, **params)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 942, in execute
return self._execute_text(object, multiparams, params)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1104, in _execute_text
statement, parameters
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1200, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1413, in _handle_dbapi_exception
exc_info
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\util\compat.py”, line 265, in raise_from_cause
reraise(type(exception), exception, tb=exc_tb, cause=cause)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\util\compat.py”, line 248, in reraise
raise value.with_traceback(tb)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1193, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\default.py”, line 509, in do_execute
cursor.execute(statement, parameters)
sqlalchemy.exc.DatabaseError: (sqlite3.DatabaseError) database disk image is malformed [SQL: “SELECT name FROM sqlite_master WHERE type=‘table’ ORDER BY name”] (Background on this error at: http://sqlalche.me/e/4xp6)
e[0m
WARNING:iceqube.worker.backends.base:Job 9c26f8838bce4888959f6013d1c23d9c raised an exception: Traceback (most recent call last):
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1193, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\default.py”, line 509, in do_execute
cursor.execute(statement, parameters)
sqlite3.DatabaseError: database disk image is malformed

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 75, in handle_finished_future
result = future.result()
File “C:\Python34\lib\concurrent\futures_base.py”, line 395, in result
return self.__get_result()
File “C:\Python34\lib\concurrent\futures_base.py”, line 354, in __get_result
raise self.exception
File “C:\Python34\lib\concurrent\futures\thread.py”, line 54, in run
result = self.fn(*self.args, **self.kwargs)
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 149, in wrap
raise e
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\worker\backends\inmem.py”, line 145, in wrap
return f(*args, **kwargs)
File “C:\Python34\lib\site-packages\kolibri\dist\iceqube\common\classes.py”, line 120, in y
return func(*args, **kwargs)
File "C:\Python34\lib\site-packages\kolibri\dist\django\core\management_init
.py", line 131, in call_command
return command.execute(*args, **defaults)
File “C:\Python34\lib\site-packages\kolibri\dist\django\core\management\base.py”, line 330, in execute
output = self.handle(*args, **options)
File “C:\Python34\lib\site-packages\kolibri\core\tasks\management\commands\base.py”, line 89, in handle
return self.handle_async(*args, **options)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 145, in handle_async
self.download_channel(options[“channel_id”], options[“baseurl”])
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 78, in download_channel
self._transfer(DOWNLOAD_METHOD, channel_id, baseurl)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 101, in _transfer
finished = self._start_file_transfer(filetransfer, channel_id, dest)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 135, in _start_file_transfer
retry_import(e, skip_404=False)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\import_export_content.py”, line 116, in retry_import
raise e
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 120, in _start_file_transfer
import_channel_by_id(channel_id, self.is_cancelled)
File “C:\Python34\lib\site-packages\kolibri\core\content\management\commands\importchannel.py”, line 24, in import_channel_by_id
channel_import.import_channel_from_local_db(channel_id, cancel_check=cancel_check)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channel_import.py”, line 673, in import_channel_from_local_db
import_manager = initialize_import_manager(channel_id, cancel_check=cancel_check)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channel_import.py”, line 647, in initialize_import_manager
channel_metadata = read_channel_metadata_from_db_file(get_content_database_file_path(channel_id))
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\channels.py”, line 59, in read_channel_metadata_from_db_file
source = Bridge(sqlite_file_path=channeldbpath)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\sqlalchemybridge.py”, line 216, in init
db_matches_schema(self.Base, self.session)
File “C:\Python34\lib\site-packages\kolibri\core\content\utils\check_schema_db.py”, line 24, in db_matches_schema
tables = iengine.get_table_names()
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\reflection.py”, line 201, in get_table_names
self.bind, schema, info_cache=self.info_cache)
File “”, line 2, in get_table_names
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\reflection.py”, line 54, in cache
ret = fn(self, con, *args, **kw)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\dialects\sqlite\base.py”, line 1160, in get_table_names
rs = connection.execute(s)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 2075, in execute
return connection.execute(statement, *multiparams, **params)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 942, in execute
return self._execute_text(object, multiparams, params)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1104, in _execute_text
statement, parameters
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1200, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1413, in _handle_dbapi_exception
exc_info
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\util\compat.py”, line 265, in raise_from_cause
reraise(type(exception), exception, tb=exc_tb, cause=cause)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\util\compat.py”, line 248, in reraise
raise value.with_traceback(tb)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\base.py”, line 1193, in _execute_context
context)
File “C:\Python34\lib\site-packages\kolibri\dist\sqlalchemy\engine\default.py”, line 509, in do_execute
cursor.execute(statement, parameters)
sqlalchemy.exc.DatabaseError: (sqlite3.DatabaseError) database disk image is malformed [SQL: “SELECT name FROM sqlite_master WHERE type=‘table’ ORDER BY name”] (Background on this error at: http://sqlalche.me/e/4xp6)

Thanks for the info!

We believe this issue should be addressed by a change in our upcoming patch release, 0.12.3. That should be available for download by the end of next week.

For reference, this is the change we believe should fix it: https://github.com/learningequality/kolibri/pull/5419

Thanks Devon! I love Kolibri! I love you and your team.
JM

Thanks Devon! The problem is solved. :white_check_mark::smiley: