Page MenuHomePhorge
Create Task
Maniphest T713

Windows: akonadiserver crash during start
Closed, ResolvedPublic
Actions

Description

akonadiserver crashes on one of the test systems while starting. We see akonadi_control trying to restart the server a couple of time, which always exists with error code 1, until it gives up.
The server error log looks like this:
Database process exited unexpectedly during initial connection!
executable: "C:/Program Files (x86)/Kontact E5/bin/mysqld.exe"
arguments: ("--defaults-file=C:\Users\kolab\AppData\Roaming\.kontact\.local\share/akonadi/mysql.conf", "--datadir=C:\Users\kolab\AppData\Roaming\.kontact\.local\share/akonadi/db_data/", "--shared-memory")
stdout: ""
stderr: "2015-08-20 12:39:19 0 [Warning] option 'wait_timeout': unsigned value 31536000 adjusted to 2147483
2015-08-20 12:39:19 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
"
exit code: 1
process error: "Unknown error"

Details

Ticket Type
Task

Related Objects
Search...

Event Timeline

mollekopf created this task.Aug 20 2015, 12:58 PM
mollekopf raised the priority of this task from to 60.
mollekopf updated the task description. (Show Details)
mollekopf added a project: KDE PIM.
mollekopf changed Ticket Type from Task to Task.
mollekopf subscribed.
mollekopf added a comment.Aug 20 2015, 1:48 PM

mysqld.err

2015-08-20 12:39:19 1000 [Note] Plugin 'FEDERATED' is disabled.
2015-08-20 12:39:19 63c InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2015-08-20 12:39:19 1000 [Note] InnoDB: Using atomics to ref count buffer pool pages
2015-08-20 12:39:19 1000 [Note] InnoDB: The InnoDB memory heap is disabled
2015-08-20 12:39:19 1000 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2015-08-20 12:39:19 1000 [Note] InnoDB: Memory barrier is not used
2015-08-20 12:39:19 1000 [Note] InnoDB: Compressed tables use zlib 1.2.3
2015-08-20 12:39:19 1000 [Note] InnoDB: Not using CPU crc32 instructions
2015-08-20 12:39:19 1000 [Note] InnoDB: Initializing buffer pool, size = 80.0M
2015-08-20 12:39:19 1000 [Note] InnoDB: Completed initialization of buffer pool
2015-08-20 12:39:19 1000 [ERROR] InnoDB: Cannot create log files because data files are corrupt or the database was not shut down cleanly after creating the data files.
2015-08-20 12:39:19 1000 [ERROR] Plugin 'InnoDB' init function returned error.
2015-08-20 12:39:19 1000 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2015-08-20 12:39:19 1000 [ERROR] Unknown/unsupported storage engine: innodb
2015-08-20 12:39:19 1000 [ERROR] Aborting

2015-08-20 12:39:19 1000 [Note] Binlog end
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'partition'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_METRICS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMPMEM'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_LOCKS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_TRX'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'BLACKHOLE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'ARCHIVE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'MRG_MYISAM'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'MyISAM'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'MEMORY'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'CSV'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'sha256_password'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'mysql_old_password'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'mysql_native_password'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'binlog'
2015-08-20 12:39:19 1000 [Note] C:\Program Files (x86)\Kontact E5\bin\mysqld.exe: Shutdown complete

2015-08-20 13:44:34 296 [Note] Plugin 'FEDERATED' is disabled.
2015-08-20 13:44:34 820 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2015-08-20 13:44:34 296 [Note] InnoDB: Using atomics to ref count buffer pool pages
2015-08-20 13:44:34 296 [Note] InnoDB: The InnoDB memory heap is disabled
2015-08-20 13:44:34 296 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2015-08-20 13:44:34 296 [Note] InnoDB: Memory barrier is not used
2015-08-20 13:44:34 296 [Note] InnoDB: Compressed tables use zlib 1.2.3
2015-08-20 13:44:34 296 [Note] InnoDB: Not using CPU crc32 instructions
2015-08-20 13:44:34 296 [Note] InnoDB: Initializing buffer pool, size = 80.0M
2015-08-20 13:44:34 296 [Note] InnoDB: Completed initialization of buffer pool
2015-08-20 13:44:34 296 [ERROR] InnoDB: Cannot create log files because data files are corrupt or the database was not shut down cleanly after creating the data files.
2015-08-20 13:44:34 296 [ERROR] Plugin 'InnoDB' init function returned error.
2015-08-20 13:44:34 296 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2015-08-20 13:44:34 296 [ERROR] Unknown/unsupported storage engine: innodb
2015-08-20 13:44:34 296 [ERROR] Aborting

2015-08-20 13:44:34 296 [Note] Binlog end
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'partition'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_METRICS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMPMEM'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_LOCKS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_TRX'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'BLACKHOLE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'ARCHIVE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'MRG_MYISAM'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'MyISAM'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'MEMORY'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'CSV'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'sha256_password'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'mysql_old_password'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'mysql_native_password'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'binlog'
2015-08-20 13:44:34 296 [Note] mysqld.exe: Shutdown complete

2015-08-20 13:47:04 2500 [Note] Plugin 'FEDERATED' is disabled.
2015-08-20 13:47:04 a9c InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2015-08-20 13:47:04 2500 [Note] InnoDB: Using atomics to ref count buffer pool pages
2015-08-20 13:47:04 2500 [Note] InnoDB: The InnoDB memory heap is disabled
2015-08-20 13:47:04 2500 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2015-08-20 13:47:04 2500 [Note] InnoDB: Memory barrier is not used
2015-08-20 13:47:04 2500 [Note] InnoDB: Compressed tables use zlib 1.2.3
2015-08-20 13:47:04 2500 [Note] InnoDB: Not using CPU crc32 instructions
2015-08-20 13:47:04 2500 [Note] InnoDB: Initializing buffer pool, size = 80.0M
2015-08-20 13:47:04 2500 [Note] InnoDB: Completed initialization of buffer pool
2015-08-20 13:47:04 2500 [ERROR] InnoDB: Cannot create log files because data files are corrupt or the database was not shut down cleanly after creating the data files.
2015-08-20 13:47:04 2500 [ERROR] Plugin 'InnoDB' init function returned error.
2015-08-20 13:47:04 2500 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2015-08-20 13:47:04 2500 [ERROR] Unknown/unsupported storage engine: innodb
2015-08-20 13:47:04 2500 [ERROR] Aborting

2015-08-20 13:47:04 2500 [Note] Binlog end
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'partition'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_METRICS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMPMEM'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_LOCKS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_TRX'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'BLACKHOLE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'ARCHIVE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'MRG_MYISAM'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'MyISAM'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'MEMORY'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'CSV'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'sha256_password'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'mysql_old_password'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'mysql_native_password'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'binlog'
2015-08-20 13:47:04 2500 [Note] mysqld.exe: Shutdown complete

mollekopf added a comment.Aug 20 2015, 1:48 PM

mysqld.err

2015-08-20 12:39:19 1000 [Note] Plugin 'FEDERATED' is disabled.
2015-08-20 12:39:19 63c InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2015-08-20 12:39:19 1000 [Note] InnoDB: Using atomics to ref count buffer pool pages
2015-08-20 12:39:19 1000 [Note] InnoDB: The InnoDB memory heap is disabled
2015-08-20 12:39:19 1000 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2015-08-20 12:39:19 1000 [Note] InnoDB: Memory barrier is not used
2015-08-20 12:39:19 1000 [Note] InnoDB: Compressed tables use zlib 1.2.3
2015-08-20 12:39:19 1000 [Note] InnoDB: Not using CPU crc32 instructions
2015-08-20 12:39:19 1000 [Note] InnoDB: Initializing buffer pool, size = 80.0M
2015-08-20 12:39:19 1000 [Note] InnoDB: Completed initialization of buffer pool
2015-08-20 12:39:19 1000 [ERROR] InnoDB: Cannot create log files because data files are corrupt or the database was not shut down cleanly after creating the data files.
2015-08-20 12:39:19 1000 [ERROR] Plugin 'InnoDB' init function returned error.
2015-08-20 12:39:19 1000 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2015-08-20 12:39:19 1000 [ERROR] Unknown/unsupported storage engine: innodb
2015-08-20 12:39:19 1000 [ERROR] Aborting

2015-08-20 12:39:19 1000 [Note] Binlog end
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'partition'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_METRICS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMPMEM'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_CMP'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_LOCKS'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'INNODB_TRX'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'BLACKHOLE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'ARCHIVE'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'MRG_MYISAM'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'MyISAM'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'MEMORY'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'CSV'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'sha256_password'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'mysql_old_password'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'mysql_native_password'
2015-08-20 12:39:19 1000 [Note] Shutting down plugin 'binlog'
2015-08-20 12:39:19 1000 [Note] C:\Program Files (x86)\Kontact E5\bin\mysqld.exe: Shutdown complete

2015-08-20 13:44:34 296 [Note] Plugin 'FEDERATED' is disabled.
2015-08-20 13:44:34 820 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2015-08-20 13:44:34 296 [Note] InnoDB: Using atomics to ref count buffer pool pages
2015-08-20 13:44:34 296 [Note] InnoDB: The InnoDB memory heap is disabled
2015-08-20 13:44:34 296 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2015-08-20 13:44:34 296 [Note] InnoDB: Memory barrier is not used
2015-08-20 13:44:34 296 [Note] InnoDB: Compressed tables use zlib 1.2.3
2015-08-20 13:44:34 296 [Note] InnoDB: Not using CPU crc32 instructions
2015-08-20 13:44:34 296 [Note] InnoDB: Initializing buffer pool, size = 80.0M
2015-08-20 13:44:34 296 [Note] InnoDB: Completed initialization of buffer pool
2015-08-20 13:44:34 296 [ERROR] InnoDB: Cannot create log files because data files are corrupt or the database was not shut down cleanly after creating the data files.
2015-08-20 13:44:34 296 [ERROR] Plugin 'InnoDB' init function returned error.
2015-08-20 13:44:34 296 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2015-08-20 13:44:34 296 [ERROR] Unknown/unsupported storage engine: innodb
2015-08-20 13:44:34 296 [ERROR] Aborting

2015-08-20 13:44:34 296 [Note] Binlog end
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'partition'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_METRICS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMPMEM'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_CMP'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_LOCKS'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'INNODB_TRX'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'BLACKHOLE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'ARCHIVE'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'MRG_MYISAM'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'MyISAM'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'MEMORY'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'CSV'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'sha256_password'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'mysql_old_password'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'mysql_native_password'
2015-08-20 13:44:34 296 [Note] Shutting down plugin 'binlog'
2015-08-20 13:44:34 296 [Note] mysqld.exe: Shutdown complete

2015-08-20 13:47:04 2500 [Note] Plugin 'FEDERATED' is disabled.
2015-08-20 13:47:04 a9c InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator.
2015-08-20 13:47:04 2500 [Note] InnoDB: Using atomics to ref count buffer pool pages
2015-08-20 13:47:04 2500 [Note] InnoDB: The InnoDB memory heap is disabled
2015-08-20 13:47:04 2500 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2015-08-20 13:47:04 2500 [Note] InnoDB: Memory barrier is not used
2015-08-20 13:47:04 2500 [Note] InnoDB: Compressed tables use zlib 1.2.3
2015-08-20 13:47:04 2500 [Note] InnoDB: Not using CPU crc32 instructions
2015-08-20 13:47:04 2500 [Note] InnoDB: Initializing buffer pool, size = 80.0M
2015-08-20 13:47:04 2500 [Note] InnoDB: Completed initialization of buffer pool
2015-08-20 13:47:04 2500 [ERROR] InnoDB: Cannot create log files because data files are corrupt or the database was not shut down cleanly after creating the data files.
2015-08-20 13:47:04 2500 [ERROR] Plugin 'InnoDB' init function returned error.
2015-08-20 13:47:04 2500 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2015-08-20 13:47:04 2500 [ERROR] Unknown/unsupported storage engine: innodb
2015-08-20 13:47:04 2500 [ERROR] Aborting

2015-08-20 13:47:04 2500 [Note] Binlog end
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'partition'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_METRICS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMPMEM'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_CMP'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_LOCKS'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'INNODB_TRX'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'BLACKHOLE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'ARCHIVE'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'MRG_MYISAM'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'MyISAM'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'MEMORY'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'CSV'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'sha256_password'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'mysql_old_password'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'mysql_native_password'
2015-08-20 13:47:04 2500 [Note] Shutting down plugin 'binlog'
2015-08-20 13:47:04 2500 [Note] mysqld.exe: Shutdown complete

mollekopf added a comment.Aug 20 2015, 2:24 PM

The innodb_force_recovery option doensn't seem to have any effect on this.

Looks like the only option is deleting the database.

mollekopf added a comment.Aug 20 2015, 2:44 PM

Couldn't find any reliable way to trigger the problem

mollekopf moved this task from Backlog to Windows on the KDE PIM board.Dec 1 2015, 11:33 AM
mollekopf added a comment.Mar 8 2016, 6:05 PM

What seems to work reliably to reproduce the problem is:

  • Have an existing akonadi db
  • reinstall kontact
  • start kontact

The akonadiserver will repeatedly crash, and that seems to corrupt the mysql database. From there on the mysql database can no longer be started at all.

mollekopf added a comment.EditedMar 8 2016, 6:05 PM
[652] QDBusConnection: session D-Bus connection created before QCoreApplication. Application may misbehave.
[652] kontact(652) class OrgKdeKLauncherInterface *__cdecl KToolInvocation::klauncher(void):
[652] kontact(652)/kdecore (kdelibs) class OrgKdeKLauncherInterface *__cdecl KToolInvocation::klauncher(void): klauncher not running... launching kdeinit
[652] kontact(652) void __cdecl KToolInvocation::startKdeinit(void):
[652] kontact(652) void __cdecl KToolInvocation::startKdeinit(void): Starting  "C:/Program Files (x86)/Kontact E14/bin/kdeinit4.EXE" ()
[4024] QDBusConnection: session D-Bus connection created before QCoreApplication. Application may misbehave.
[652] kontact(652)/kdecore (KSycoca) bool __thiscall KSycocaPrivate::openDatabase(bool): Trying to open ksycoca from "C:/Users/kolab/AppData/Roaming/.kontact/cache-KOLAB-TEST-PC/ksycoca4"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Mail"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: "kmailpart"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Calendar"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: "korganizerpart"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Notes"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: "zanshin-notes_part"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Todo Lists"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: "zanshin-tasks_part"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Special Dates"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: ""
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Contacts"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: "kaddressbookpart"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): Loading Plugin: "Summary"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): LIBNAMEPART: ""
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): "Plugging New actions" "new_mail"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): "Plugging New actions" "new_contact"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): "Plugging New actions" "new_contactgroup"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::loadPlugins(void): "Plugging New actions" "new_event"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::addPlugin(class KontactInterface::Plugin *):
[652] kontact(652)/kutils (KSettings::Dispatcher) void __cdecl KSettings::Dispatcher::registerComponent(const class KComponentData &,class QObject *,const char *): "kontact"
[652] kontact(652)/kontact (interfaces) class KParts::ReadOnlyPart *__thiscall KontactInterface::Core::createPart(const char *): kmailpart
[652] kontact(652)/kontact (interfaces) class KParts::ReadOnlyPart *__thiscall KontactInterface::Core::createPart(const char *): Creating new KPart
[652] kontact(652)/kontact (interfaces) class KParts::ReadOnlyPart *__thiscall KontactInterface::Core::createPart(const char *): "C:/Program Files (x86)/Kontact E14/lib/kde4/kmailpart.dll"
[652] kontact(652) __thiscall KMailPart::KMailPart(class QWidget *,class QObject *,const class QList<class QVariant> &): InstanceName: "kontact"
[652] kontact(652) __thiscall KMailPart::KMailPart(class QWidget *,class QObject *,const class QList<class QVariant> &): InstanceName: "kontact"
[4024] kded(4024)/kdecore (KSycoca) bool __thiscall KSycocaPrivate::openDatabase(bool): Trying to open ksycoca from "C:/Users/kolab/AppData/Roaming/.kontact/cache-KOLAB-TEST-PC/ksycoca4"
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[652] kontact(652) __thiscall KMKernel::KMKernel(class QObject *): Starting up...
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[652] kontact(652)/libakonadi void __thiscall Akonadi::SessionPrivate::init(const class QByteArray &): "KMail Kernel ETM"
[652] kontact(652)/libakonadi bool __cdecl Akonadi::ServerManager::start(void): executing akonadi_control
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[4024] QWindowsFileSystemWatcherEngine: unknown message '
[652] kontact(652)/libakonadi void __thiscall Akonadi::AgentManagerPrivate::createDBusInterface(void): AgentManager failed to get a valid AgentManager DBus interface. Error is: 1 "org.freedesktop.DBus.Error.NameHasNoOwner" "Could not get owner of name 'org.freedesktop.Akonadi.Control': no such name" 
[652] kontact(652)/libakonadi void __thiscall Akonadi::SessionPrivate::reconnect(void): connectToServer "Akonadi-S-1-5-21-355159466-2866521284-3243934370-1000"
[652] kontact(652)/libakonadi void __thiscall Akonadi::SessionPrivate::socketError(enum QLocalSocket::LocalSocketError): Socket error occurred: "QLocalSocket::connectToServer: Invalid name" 
[652] kontact(652)/libakonadi void __thiscall Akonadi::SessionPrivate::init(const class QByteArray &): ""
[652] kontact(652)/libakonadi void __thiscall Akonadi::SessionPrivate::reconnect(void): connectToServer "Akonadi-S-1-5-21-355159466-2866521284-3243934370-1000"
[652] kontact(652)/libakonadi void __thiscall Akonadi::SessionPrivate::socketError(enum QLocalSocket::LocalSocketError): Socket error occurred: "QLocalSocket::connectToServer: Invalid name" 
[652] kontact(652)/kdepimlibs (mailtransport) void __thiscall MailTransport::TransportManagerPrivate::fillTypes(void): Have SMTP, Sendmail, and 0 Akonadi types.
[652] kontact(652)/kdepimlibs (mailtransport) __thiscall MailTransport::Transport::Transport(const class QString &): "31906"
[652] kontact(652)/kdepimlibs (mailtransport) void __thiscall MailTransport::Transport::usrReadConfig(void): type 0
[652] kontact(652)/kdepimlibs (mailtransport) void __thiscall MailTransport::TransportManager::loadPasswordsAsync(void):
[4024] kded(4024)/kded4 class KDEDModule *__thiscall Kded::loadModule(const class KSharedPtr<class KService> &,bool): Successfully loaded module "winstartmenu"
[652] kontact(652) class OrgKdeKLauncherInterface *__cdecl KToolInvocation::klauncher(void):
[3764] Connecting to deprecated signal QDBusConnectionInterface::serviceOwnerChanged(QString,QString,QString)
[652] kontact(652)/kdeui (Wallet) class OrgKdeKWalletInterface &__thiscall KWallet::KWalletDLauncher::getInterface(void): The kwalletd service has been registered
[652] kontact(652) class KPIMIdentities::IdentityManager *__thiscall KMKernel::identityManager(void):
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652) void __thiscall KMKernel::init(void): KMail init with akonadi server state: 1
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652) __thiscall MessageList::StorageModel::StorageModel(class QAbstractItemModel *,class QItemSelectionModel *,class QObject *): Using model: Akonadi::EntityTreeModel
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652) void __thiscall KDirWatch::removeDir(const class QString &): doesn't know "C:/Users/kolab/AppData/Roaming/.kontact/share/apps/messageviewer/themes/" 
[652] kontact(652) void __thiscall KDirWatch::removeDir(const class QString &): doesn't know "C:/Program Files (x86)/Kontact E14/share/apps/messageviewer/themes/" 
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[4024] kded(4024)/kded4 class KDEDModule *__thiscall Kded::loadModule(const class KSharedPtr<class KService> &,bool): Successfully loaded module "ktimezoned"
[652] kontact(652)/kdecore (K*TimeZone*) class KSystemTimeZonesPrivate *__cdecl KSystemTimeZonesPrivate::instance(void): instance(): ... initialised
[652] kontact(652)/kdecore (K*TimeZone*) void __cdecl KSystemTimeZonesPrivate::readConfig(bool): readConfig(): local zone= "Europe/Berlin"
[652] OFFSET AT ZONE TIME  
[652] Found keyname: "W. Europe Standard Time" with keypath: 0x47ebe58 for zone: "Europe/Berlin" , or in Windows: "W. Europe Standard Time" 
[652] original time in zone time:  QDateTime("Tue 8. Mar 18:01:28 2016") 
[652] Converted to system time:  QDateTime("Tue 8. Mar 17:01:28 2016") 
[652] isDST false 
[652] effective offset 3600 
[652] secondOffset 0x41cff0 
[652] plus 1 QDateTime("Tue 8. Mar 18:01:28 2016") 
[652] minus 1 QDateTime("Tue 8. Mar 16:01:28 2016") 
[652] zone plus 1 QDateTime("Tue 8. Mar 19:01:28 2016") 
[652] zone minus 1 QDateTime("Tue 8. Mar 17:01:28 2016") 
[652] utcconverted QDateTime("Tue 8. Mar 18:01:28 2016") 
[652] result 3600 second 3600 
[652] kontact(652) class QDateTime __thiscall KDateTimePrivate::toUtc(const class KTimeZone &) const: toUtc(): calculated ->  QDateTime("Tue 8. Mar 17:01:28 2016")
[652] OFFSET AT ZONE TIME  
[652] original time in zone time:  QDateTime("Tue 8. Mar 19:01:28 2016") 
[652] Converted to system time:  QDateTime("Tue 8. Mar 18:01:28 2016") 
[652] isDST false 
[652] effective offset 3600 
[652] secondOffset 0x0 
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/kdecore (trader) class QList<class KSharedPtr<class KService> > __thiscall KMimeTypeTrader::query(const class QString &,const class QString &,const class QString &) const: query for mimeType  "application/pdf" ,  "KParts/ReadOnlyPart"  : returning  0  offers
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/kutils (KSettings::Dispatcher) void __cdecl KSettings::Dispatcher::registerComponent(const class KComponentData &,class QObject *,const char *): "kmail2"
[652] kontact(652)/kontact (core) void __thiscall Kontact::MainWindow::slotActivePartChanged(class KParts::Part *): "Part activated:" KMailPart(0x41327a0) "with stack id." 1
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[652] kontact(652) void __thiscall KMailPart::guiActivateEvent(class KParts::GUIActivateEvent *):
[3780] QDBusConnection: session D-Bus connection created before QCoreApplication. Application may misbehave.
[652] kontact(652) void __thiscall MessageList::Core::View::resizeEvent(class QResizeEvent *): Resize event enter (viewport width is  239 )
[652] kontact(652) void __thiscall MessageList::Core::View::resizeEvent(class QResizeEvent *): Resize event enter (viewport width is  779 )
[652] kontact(652) void __thiscall MessageList::Core::View::resizeEvent(class QResizeEvent *): Resize event enter (viewport width is  785 )
[652] kontact(652)/libkdepim void __cdecl KPIM::ReminderClient::startDaemon(void): Couldn't find autostart/korgac.desktop! 
[652] QDBusConnection: name 'org.kde.kwalletd' had owner '' but we thought it was ':1.9'
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::startFirstListJob(void): GEN true false false
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::startFirstListJob(void): GEN true false true
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::startFirstListJob(void): GEN true false true
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::startFirstListJob(void): GEN true false true
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[3780] knotify(3780)/kdecore (KSycoca) bool __thiscall KSycocaPrivate::openDatabase(bool): Trying to open ksycoca from "C:/Users/kolab/AppData/Roaming/.kontact/cache-KOLAB-TEST-PC/ksycoca4"
[652] kontact(652) void __thiscall MessageList::Core::View::resizeEvent(class QResizeEvent *): Resize event enter (viewport width is  785 )
[652] kontact(652)/libakonadi enum Akonadi::ServerManager::State __cdecl Akonadi::ServerManager::state(void): Akonadi server is already starting up
[3764] ProcessControl: Application 'akonadiserver' returned with exit code 1 (Unknown error)
[3764] ProcessControl: Application 'akonadiserver' returned with exit code 1 (Unknown error)
[3764] ProcessControl: Application 'akonadiserver' returned with exit code 1 (Unknown error)
[3764] ProcessControl: Application 'akonadiserver' returned with exit code 1 (Unknown error)
[3764] "akonadiserver" crashed too often and will not be restarted! 
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::collectionFetchJobDone(class KJob *): Job error:  "Unknown error." for collection: () 
[652] 
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::collectionFetchJobDone(class KJob *): Job error:  "Unknown error." for collection: () 
[652] 
[652] kontact(652) void __thiscall MessageList::Widget::slotTagsFetched(class KJob *): Failed to load tags  "Unknown error." 
[652] kontact(652) void __thiscall MailCommon::FilterManager::slotFinishedTagListing(class KJob *): failed to retrieve tags  "Unknown error." 
[652] kontact(652) void __thiscall KMail::TagActionManager::finishedTagListing(class KJob *): "Unknown error." 
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::collectionFetchJobDone(class KJob *): Job error:  "Unknown error." for collection: () 
[652] 
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::collectionFetchJobDone(class KJob *): Job error:  "Unknown error." for collection: () 
[652] 
[652] kontact(652)/libakonadi void __thiscall Akonadi::EntityTreeModelPrivate::collectionFetchJobDone(class KJob *): Job error:  "Unknown error." for collection: () 
[652] 
[652] kontact(652) void __thiscall KMKernel::akonadiStateChanged(enum Akonadi::ServerManager::State): KMKernel has akonadi state changed to: 4
[652] kontact(652)/libakonadi void __thiscall Akonadi::Control::Private::serverStateChanged(enum Akonadi::ServerManager::State): 4
mollekopf added a comment.Mar 8 2016, 6:10 PM

In the process monitor we can see that a single akonadi_control process tries to start 4 akonadiserver instances in quick succession (~2s in between). This is probably because the akonadiserver repeatedly crashes for some reason.

mollekopf added a parent task: T574: windows: mysql database is corrupt after some restarts.Mar 14 2016, 1:04 PM
mollekopf raised the priority of this task from 60 to High.Mar 14 2016, 1:55 PM
mollekopf moved this task from Windows to Need Resolution on the KDE PIM board.Apr 5 2016, 10:57 AM
mollekopf added a project: Restricted Project.Apr 5 2016, 2:54 PM
mollekopf moved this task from Restricted Project Column to Restricted Project Column on the Restricted Project board.Apr 5 2016, 2:55 PM
mollekopf added a project: Restricted Project.Apr 12 2016, 9:46 AM
mollekopf added a comment.Apr 14 2016, 2:58 PM

the akonadiserver crashes in this code section:

mDatabaseProcess = new QProcess;
mDatabaseProcess->start( mysqldPath, arguments );
if ( !mDatabaseProcess->waitForStarted() ) {
  akError() << "Could not start database server!";
  akError() << "executable:" << mysqldPath;
  akError() << "arguments:" << arguments;
  akFatal() << "process error:" << mDatabaseProcess->errorString();
}

const QLatin1String initCon( "initConnection" );
{
  QSqlDatabase db = QSqlDatabase::addDatabase( QLatin1String( "QMYSQL" ), initCon );
  apply( db );

  db.setDatabaseName( QString() ); // might not exist yet, then connecting to the actual db will fail
  if ( !db.isValid() ) {
    akFatal() << "Invalid database object during database server startup";
  }

  bool opened = false;
  for ( int i = 0; i < 120; ++i ) {
    opened = db.open();
    if ( opened ) {
      break;
    }
    if ( mDatabaseProcess->waitForFinished( 500 ) ) {
      akError() << "Database process exited unexpectedly during initial connection!";
      akError() << "executable:" << mysqldPath;
      akError() << "arguments:" << arguments;
      akError() << "stdout:" << mDatabaseProcess->readAllStandardOutput();
      akError() << "stderr:" << mDatabaseProcess->readAllStandardError();
      akError() << "exit code:" << mDatabaseProcess->exitCode();
      akFatal() << "process error:" << mDatabaseProcess->errorString();
    }
  }

So the fault is really in mysqld, that for some reason can't start (well, obviously it can't with the corrupt db, but we don't know why it got corrupt in the first place).

mollekopf closed this task as Resolved.Apr 15 2016, 7:41 PM
mollekopf claimed this task.

This has been fixed by starting mysqld as a system service that is no longer started by akonadi.

mollekopf added a comment.Apr 15 2016, 7:42 PM

The first installer with this change is Kontact-E14-2016-04-15-06-11.exe