Only first address in CC: field receives message.
-
When sending a message with multiple users in CC: field, only the first user receives a message with contents. All additional users listed in CC: field receive notification that a message has arrived with the subject - but when they open the message, it is blank…
I am using
Outlook 2010
Zarafa-client 7.2.4-52167
Kopano 8.5.5.5.0
postfix 3.1.0-3ubuntu0.3When I open the user’s mailbox in the web-app, the message does not appear to exist
I have reviewed logs, and have not found anything obvious. Any help would be appreciated. -
Update. This issue has become annoying again. I have found the following entries in logs.
This occurred after a user CC; 6 users on an emailkopano/server.log:
BEGIN PASTE--------->
Mon Nov 19 15:16:12 2018: [error ] SQL [00000031] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 165, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TJ\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T��\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TJ\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T��\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000015] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 165, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TF\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T�
\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TF\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T�
\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000036] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 166, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TD\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�TF\�\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TD\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�TF\�\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000013] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 166, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TE\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T\�\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TE\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T\�\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000039] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 165, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TH\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�Tl�\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TH\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�Tl�\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000019] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 167, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TL\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T\�\�\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TL\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T\�\�\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000025] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 165, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TI\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T��
\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TI\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T��
\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
Mon Nov 19 15:16:12 2018: [error ] SQL [00000014] Failed: Deadlock found when trying to get lock; try restarting transaction, Query Size: 166, Query: “REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TK\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T\��
\0\0\0’, 0, 0)”
Mon Nov 19 15:16:12 2018: [error ] KDatabase::I_Update() query failed: REPLACE INTO changes(change_type, sourcekey, parentsourcekey, sourcesync, flags) VALUES (4097, ‘Վ�\�J\�J{���ײ\�TK\�\0\0\0’, ‘Վ�\�J\�J{���ײ\�T\��
\0\0\0’, 0, 0): Deadlock found when trying to get lock; try restarting transaction
<---------END PASTEmail.log
BEGIN PASTE --------->Nov 19 15:16:11 redactedmailservername postfix/smtpd[7845]: connect from localhost[127.0.0.1]
Nov 19 15:16:11 redactedmailservername postfix/smtpd[7845]: EC6771EA001: client=localhost[127.0.0.1]
Nov 19 15:16:11 redactedmailservername postfix/cleanup[7847]: EC6771EA001: message-id=kcim.5bf31a0b.1efb.3bb58b4226d596a7@redactedmailservername.FRFCanada.local
Nov 19 15:16:11 redactedmailservername postfix/qmgr[3131]: EC6771EA001: from=corrina@redacteddomain.com, size=11616, nrcpt=9 (queue active)
Nov 19 15:16:11 redactedmailservername postfix/smtpd[7845]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=9 data=1 quit=1 commands=13
Nov 19 15:16:11 redactedmailservername postfix/local[7935]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:11 redactedmailservername postfix/local[7937]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:11 redactedmailservername postfix/local[7938]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:12 redactedmailservername postfix/local[7940]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:12 redactedmailservername postfix/local[7942]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:12 redactedmailservername postfix/local[7945]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:12 redactedmailservername postfix/local[7949]: warning: dict_nis_init: NIS domain name not set - NIS lookups disabled
Nov 19 15:16:12 redactedmailservername postfix/pipe[7968]: EC6771EA001: to=donk@redacteddomain.com, relay=kopano, delay=0.68, delays=0.01/0.02/0/0.65, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7946]: EC6771EA001: to=accounts@redacteddomain.com, relay=kopano, delay=0.68, delays=0.01/0.01/0/0.66, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7963]: EC6771EA001: to=partsdesk@redacteddomain.com, relay=kopano, delay=0.7, delays=0.01/0.04/0/0.65, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7893]: EC6771EA001: to=don@redacteddomain.com, relay=kopano, delay=0.7, delays=0.01/0.02/0/0.68, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7972]: EC6771EA001: to=skyeb@redacteddomain.com, relay=kopano, delay=0.71, delays=0.01/0.06/0/0.64, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7865]: EC6771EA001: to=aaron@redacteddomain.com, relay=kopano, delay=0.71, delays=0.01/0.01/0/0.69, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7970]: EC6771EA001: to=mark@redacteddomain.com, relay=kopano, delay=0.72, delays=0.01/0.03/0/0.68, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7955]: EC6771EA001: to=felix@redacteddomain.com, relay=kopano, delay=0.73, delays=0.01/0.02/0/0.7, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/pipe[7951]: EC6771EA001: to=rolf@redacteddomain.com, relay=kopano, delay=0.73, delays=0.01/0.05/0/0.67, dsn=2.0.0, status=sent (delivered via kopano service)
Nov 19 15:16:12 redactedmailservername postfix/qmgr[3131]: EC6771EA001: removed
<--------END PASTE -
On an up to date system this is for me working as expected. I compose a message with webapp, add me to the to and seven other users in cc. send the message and all seven recipients have the message.
Deadlock found when trying to get lock; try restarting transaction
Generally this is an indicator that you are either missing tuning of the mysql database (see https://documentation.kopano.io/kopanocore_administrator_manual/performance_tuning.html) or your hardware is simply too slow for your users load.