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.3

    When 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 email

    kopano/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 PASTE

    mail.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


  • Kopano

    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.


Log in to reply