[horde] Activesync - Error saving state, checking if this is due to previous synckey

Thu Dec 7 01:24:34 UTC 2017

Quoting Lucas (wb7.eu) <lg at wb7.eu>:

> Hello,
>
> since today I have the odd problem that one Outlook 2016 client stopped
> syncing with Horde - it is just trying to connect all the time, but not
> syncing anymore without any error. This issue does not affect any other
> device. The following ActiveSync-Log was created:
>
> [19409][2017-12-03T22:59:06+01:00] INFO: COLLECTIONS: Initializing state for
> collection: INBOX, synckey: {5a207f65-78ec-43a9-aa69-476b00000000}19
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Loading state for synckey
> {5a207f65-78ec-43a9-aa69-476b00000000}19
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Initializing message diff
> engine for F71c41963 (INBOX)
> [19409][2017-12-03T22:59:06+01:00] >>>:
> Horde_Core_ActiveSync_Driver::getSyncStamp(INBOX, 1512325575);
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Using SYNCSTAMP 1512338346
> for F71c41963.
> [19409][2017-12-03T22:59:06+01:00] >>>:
> Horde_Core_ActiveSync_Driver::getServerChanges(INBOX, 1512325575,
> 1512338346, 0, 0, 1, 100, )
> [19409][2017-12-03T22:59:06+01:00] >>>: INBOX IMAP PREVIOUS MODSEQ: 10523
> [19409][2017-12-03T22:59:06+01:00] >>>: IMAP status:
> a:4:{s:8:"messages";i:3112;s:7:"uidnext";i:9934;s:11:"uidvalidity";i:1422798
> 076;s:13:"highestmodseq";i:10525;}
> [19409][2017-12-03T22:59:06+01:00] >>>: CONDSTORE and CHANGES
> [19409][2017-12-03T22:59:06+01:00] >>>: Found 0 deleted messages.
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Found 1 message changes in
> F71c41963.
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Checking for client initiated
> changes.
> [19409][2017-12-03T22:59:06+01:00] >>>: Old SYNCKEY:
> {5a207f65-78ec-43a9-aa69-476b00000000}19, New SYNCKEY:
> {5a207f65-78ec-43a9-aa69-476b00000000}20
> [19409][2017-12-03T22:59:06+01:00] O:  <Folders>
> [19409][2017-12-03T22:59:06+01:00] O:   <Folder>
> [19409][2017-12-03T22:59:06+01:00] O:    <SyncKey>
> [19409][2017-12-03T22:59:06+01:00] O:
> {5a207f65-78ec-43a9-aa69-476b00000000}20
> [19409][2017-12-03T22:59:06+01:00] O:    </SyncKey>
> [19409][2017-12-03T22:59:06+01:00] O:    <FolderId>
> [19409][2017-12-03T22:59:06+01:00] O:     F71c41963
> [19409][2017-12-03T22:59:06+01:00] O:    </FolderId>
> [19409][2017-12-03T22:59:06+01:00] O:    <Status>
> [19409][2017-12-03T22:59:06+01:00] O:     1
> [19409][2017-12-03T22:59:06+01:00] O:    </Status>
> [19409][2017-12-03T22:59:06+01:00] >>>:
> Horde_Core_ActiveSync_Driver::getMessage(INBOX, 9933)
> [19409][2017-12-03T22:59:06+01:00] >>>: Building MIME Message.
> [19409][2017-12-03T22:59:06+01:00] >>>: Checking MIMETRUNCATION: 0,
> ServerData: 528
> [19409][2017-12-03T22:59:06+01:00] >>>: Fetching verb for
> <1054841784.21.1512334576271 at cmb360>.
> [19409][2017-12-03T22:59:06+01:00] O:    <Commands>
> [19409][2017-12-03T22:59:06+01:00] O:     <Add>
> [19409][2017-12-03T22:59:06+01:00] O:      <ServerEntryId>
> [19409][2017-12-03T22:59:06+01:00] O:       9933
> [19409][2017-12-03T22:59:06+01:00] O:      </ServerEntryId>
> [19409][2017-12-03T22:59:06+01:00] O:      <Data>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:To>
> [19409][2017-12-03T22:59:06+01:00] O:        lucas at domain.de
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:To>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:From>
> [19409][2017-12-03T22:59:06+01:00] O:
> buerger-cert-newsletter at noreply.bund.de
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:From>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:Subject>
> [19409][2017-12-03T22:59:06+01:00] O:        BSI - Bestellung des
> Newsletters - Bitte um Bestätigung
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:Subject>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:Reply-To>
> [19409][2017-12-03T22:59:06+01:00] O:
> buerger-cert-newsletter at noreply.bund.de
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:Reply-To>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:DateReceived>
> [19409][2017-12-03T22:59:06+01:00] O:        2017-12-03T20:56:16.000Z
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:DateReceived>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:DisplayTo>
> [19409][2017-12-03T22:59:06+01:00] O:        lucas at domain.de
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:DisplayTo>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:ThreadTopic>
> [19409][2017-12-03T22:59:06+01:00] O:        BSI - Bestellung des
> Newsletters - Bitte um Bestätigung
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:ThreadTopic>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:Importance>
> [19409][2017-12-03T22:59:06+01:00] O:        1
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:Importance>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:Read>
> [19409][2017-12-03T22:59:06+01:00] O:        1
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:Read>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:MessageClass>
> [19409][2017-12-03T22:59:06+01:00] O:        IPM.Note
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:MessageClass>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:InternetCPID>
> [19409][2017-12-03T22:59:06+01:00] O:        65001
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:InternetCPID>
> [19409][2017-12-03T22:59:06+01:00] O:       <AirSyncBase:NativeBodyType>
> [19409][2017-12-03T22:59:06+01:00] O:        1
> [19409][2017-12-03T22:59:06+01:00] O:       </AirSyncBase:NativeBodyType>
> [19409][2017-12-03T22:59:06+01:00] O:       <AirSyncBase:Body>
> [19409][2017-12-03T22:59:06+01:00] O:        <AirSyncBase:Type>
> [19409][2017-12-03T22:59:06+01:00] O:         4
> [19409][2017-12-03T22:59:06+01:00] O:        </AirSyncBase:Type>
> [19409][2017-12-03T22:59:06+01:00] O:        <AirSyncBase:EstimatedDataSize>
> [19409][2017-12-03T22:59:06+01:00] O:         528
> [19409][2017-12-03T22:59:06+01:00] O:
> </AirSyncBase:EstimatedDataSize>
> [19409][2017-12-03T22:59:06+01:00] O:        <AirSyncBase:Truncated>
> [19409][2017-12-03T22:59:06+01:00] O:         0
> [19409][2017-12-03T22:59:06+01:00] O:        </AirSyncBase:Truncated>
> [19409][2017-12-03T22:59:06+01:00] O:        <AirSyncBase:Data>
> [19409][2017-12-03T22:59:06+01:00] O:         Return-Path:
> <SRS0=YD6g=C7=noreply.bund.de=buerger-cert-newsletter at mail01.server.de>
> Delivered-To: <lucas at domain.de>
> Received: from mail01.server.de by mail01.server.de (Dovecot) with
>  LMTP id lodjBwJlJFqBLAAAP96zEQ for <lucas at domain.de>; Sun, 03 Dec 2017
>  21:56:34 +0100
> Received: from localhost (localhost [127.0.0.1]) by mail01.server.de
>  (Postfix) with ESMTP id 1945AD19B1 for <lucas at domain.de>; Sun,  3 Dec
>  2017 21:56:34 +0100 (CET)
> Received: from newsletter.bund.de (newsletter.bund.de [77.87.229.56]) by
>  mail01.server.de (Postfix) with ESMTPS id 70C6AD19AD for
>  <lucas at domain.de>; Sun,  3 Dec 2017 21:56:28 +0100 (CET)
> X-Virus-Scanned: Debian amavisd-new at mail01.server.de
> X-Spam-Flag: NO
> X-Spam-Score: -1.253
> X-Spam-Level:
> X-Spam-Status: No, score=-1.253 required=5 tests=[BAYES_00=-1.9,
>  HEADER_FROM_DIFFERENT_DOMAINS=0.001, RCVD_IN_MSPIKE_H3=-0.01,
>  RCVD_IN_MSPIKE_WL=-0.01, SPF_SOFTFAIL=0.665, URIBL_BLOCKED=0.001]
>  autolearn=no autolearn_force=no
> Date: Sun, 3 Dec 2017 21:56:16 +0100 (CET)
> From: buerger-cert-newsletter at noreply.bund.de
> To: lucas at domain.de
> Message-ID: <1054841784.21.1512334576271 at cmb360>
> Subject: BSI - Bestellung des Newsletters - Bitte um
>  =?utf-8?b?QmVzdMOkdGlndW5n?=
> MIME-Version: 1.0
> Content-Type: text/plain; charset=utf-8
> Content-Transfer-Encoding: quoted-printable
>
> Sehr geehrter BSI-Nutzer,=20=20
> Sie=20haben soeben ein kostenloses B=C3=BCrger-CERT-Abo bestellt. Vielen Da=
> nk f=C3=BCr Ihr Interesse.
> Klicken Sie bitte auf folgenden Link um die Bestellung zu best=C3=A4tigen.
> =20
> https://www.bsi-fuer-buerger.de/SiteGlobals/Forms/Newsletter/Buerger-CERT/N=
> ewsletter_Bestaetigen_Formular.html?code=3D12c8931c8d0238090f52&s=3Dlucas%40
> domain.de&c=3Dsubscribe&n=3Dbuerger-cert-newsletter_pgp=20=20
> =20
> Falls die Seite nicht aufgerufen wird, kopieren Sie bitte den Link und f=C3=
> =BCgen ihn in die Adresszeile Ihres Browsers ein.
>
> [19409][2017-12-03T22:59:06+01:00] O:        </AirSyncBase:Data>
> [19409][2017-12-03T22:59:06+01:00] O:       </AirSyncBase:Body>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:Flag />
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:ContentClass>
> [19409][2017-12-03T22:59:06+01:00] O:        urn:content-classes:message
> [19409][2017-12-03T22:59:06+01:00] O:       </POOMMAIL:ContentClass>
> [19409][2017-12-03T22:59:06+01:00] O:       <POOMMAIL:Categories />
> [19409][2017-12-03T22:59:06+01:00] O:      </Data>
> [19409][2017-12-03T22:59:06+01:00] O:     </Add>
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Updating state during change
> [19409][2017-12-03T22:59:06+01:00] >>>: Peak memory usage after message:
> 2097152
> [19409][2017-12-03T22:59:06+01:00] O:    </Commands>
> [19409][2017-12-03T22:59:06+01:00] >>>: STATE: Saving state:
> a:8:{i:0;s:40:"{5a207f65-78ec-43a9-aa69-476b00000000}20";i:1;O:21:"Horde_Db_
> Value_Binary":2:{s:9:"*_value";s:4788:"C:28:"Horde_ActiveSync_Folder_Imap":4
> 745:{{"s":{"messages":3112,"uidnext":9934,"uidvalidity":1422798076,"highestm
> odseq":10525},"m":"1,3,11,13,82,105,109,111:113,118,120:121,135:136,141:144,
> 147,152,166,168,173,192,232:233,235,252,311,464:468,470:477,484,488:489,494,
> 499,531:535,539:541,754,869,872,906,908:912,916:918,921:922,924:926,932,946,
> 1017,1071,1108,1143:1150,1173,1175,1198,1237,1252,1305:1306,1371,1380,1472,1
> 659,1663:1664,1675:1678,1685,1701,1731:1732,1769,1844,1846,1855,1867,1870,19
> 06,1909:1910,1959:1960,1975:1976,1997,2005,2129,2175,2190,2215,2219,2304,232
> 0,2380,2399,2490,2492,2560,2564,2590,2642:2647,2651,2653,2661,2766,2769:2770
> ,2772,2775,2777,2781,2784:2787,2825:2827,2829:2830,2876,2927,2933,2940,2943,
> 2945,2955,3024,3026,3038,3054,3057,3079,3087,3089,3110,3112,3116,3140,3189,3
> 218,3271,3278:3279,3310,3317,3324,3327,3333:3337,3370:3371,3414,3437,3440,34
> 45:3446,3453:3455,3461,3511,3515,3520:3521,3524,3526:3527,3531:3532,3544:354
> 5,3624,3626,3631:3634,3642:3643,3728:3729,3805:3806,3906,3958,4013,4107,4246
> ,4265,4318,4335,4339,4371,4374,4377,4394:4395,4439,4482,4486:4490,4502:4504,
> 4506,4516,4520,4523:4526,4529,4531,4535:4536,4543:4544,4550,4556:4558,4566,4
> 574,4582:4587,4589,4592:4596,4598,4613:4614,4629,4632:4633,4635:4637,4642:46
> 43,4649,4686,4690,4698,4710,4718,4722:4723,4725,4735:4740,4761:4764,4767:477
> 5,4781:4784,4792:4797,4803,4805:4808,4814:4820,4826:4828,4830:4833,4839:4840
> ,4842,4844,4949:4950,4952:4958,4960,4967:4970,4976:4988,5013,5021:5022,5034,
> 5079:5081,5089,5095:5099,5107,5112,5116,5125:5127,5131,5133:5134,5151,5176,5
> 179,5183,5188,5190,5193:5194,5196:5200,5202:5209,5211:5228,5230,5232:5255,52
> 57:5261,5263:5270,5272:5276,5278:5279,5281,5283,5285:5291,5293:5297,5299:530
> 3,5305:5310,5312,5314:5315,5317:5321,5323:5324,5327:5330,5333:5336,5339:5344
> ,5346:5348,5350:5351,5353:5369,5371:5373,5375,5377:5382,5386,5388:5391,5395,
> 5397:5399,5401:5402,5407:5411,5413:5418,5420,5426:5430,5432:5437,5440:5445,5
> 447:5450,5452:5458,5462,5465,5467:5468,5470:5471,5474:5488,5492,5495:5499,55
> 02,5505:5507,5510:5511,5518:5519,5521:5523,5526,5531:5533,5536:5537,5539:554
> 2,5546,5549,5552:5553,5555,5560,5562:5564,5571:5574,5579,5582:5584,5590:5593
> ,5596:5598,5601:5602,5604,5606:5608,5612,5616:5618,5621,5624,5628:5632,5638,
> 5640:5642,5644:5645,5647,5651:5652,5654,5657,5660:5661,5664,5671:5674,5676,5
> 681:5684,5688,5690:5691,5695,5697,5700:5701,5704,5706,5708:5709,5711:5715,57
> 18:5723,5725,5727,5734,5736:5742,5747:5748,5750,5752,5754:5755,5758:5759,576
> 1:5762,5764,5766,5770:5772,5777,5779:5780,5784:5785,5788,5795:5796,5798,5802
> ,5806,5808,5812,5820,5822:5823,5825,5827:5829,5831:5832,5836:5837,5839,5841,
> 5846:5858,5860:5876,5878,5880:5884,5886:5904,5906:5919,5921:5927,5929:5933,5
> 935:5948,5950:5963,5965:6005,6007:6010,6012:6039,6041:6046,6048:6091,6093:61
> 16,6119:6131,6133:6166,6170,6172:6173,6176,6181:6214,6217,6219,6221:6234,623
> 6:6254,6256:6271,6281,6284:6285,6297,6310,6312:6322,6324:6331,6334:6344,6347
> :6348,6353:6383,6385:6425,6427:6464,6466:6494,6496:6549,6555:6560,6562:6579,
> 6581:6587,6589:6673,6677,6695:6876,6878:6886,6888:6890,6893,6903:7166,7344,7
> 544,7609,7770,7899,8012:8013,8063,8249,8258,8262,8264,8282:8294,8296,8300:83
> 99,8402:8404,8407:8410,8413:8423,8427:8428,8432:8434,8437:8439,8442:8451,845
> 4:8455,8458,8461:8471,8473:8475,8480:8486,8488:8517,8520:8537,8539,8541,8545
> ,8547,8550,8557,8567:8568,8570,8572:8578,8581,8583,8585,8589,8596,8598:8599,
> 8607,8613,8615,8629,8633,8643:8644,8651,8657:8661,8666,8668:8669,8672:8674,8
> 677:8678,8680:8681,8685,8689:8690,8692,8699,8706,8711,8722,8725,8733,8736:87
> 38,8750:8751,8758,8761,8763,8777,8784:8785,8787,8789,8801:8803,8807,8820:882
> 1,8825,8830,8833:8837,8840,8843,8847:8850,8853,8858,8861,8864:8865,8867:8871
> ,8873:8874,8879:8880,8882,8884:8893,8896,8898,8921:8922,8924,8926:8928,8930:
> 8931,8937:8938,8940,8943:8948,8951:8953,8956:8957,8960:8962,8965,8967,8971,8
> 979,8981:8982,8984,8986,8990,8992,8994,8997,9002,9005:9007,9013:9014,9019:90
> 20,9028,9031,9037:9039,9042,9046:9047,9050,9054,9057:9058,9065:9068,9072,907
> 5,9077:9080,9082,9084:9087,9089:9090,9092,9094,9097:9102,9105:9109,9111:9112
> ,9114:9124,9126:9129,9134,9136:9138,9140,9142:9143,9145:9148,9150:9154,9156,
> 9158:9167,9169:9171,9173,9175:9178,9180:9183,9185:9186,9188:9189,9191:9198,9
> 201:9227,9229:9258,9260:9270,9272:9280,9282:9284,9288:9317,9321,9323,9326:93
> 33,9336:9348,9351:9352,9359,9361,9364,9366,9368:9376,9378:9379,9381:9383,939
> 0,9394:9396,9402:9430,9432:9441,9443:9450,9453:9454,9456:9459,9464:9466,9468
> :9469,9472:9474,9476:9477,9479:9480,9482,9484,9487:9489,9492:9493,9496:9497,
> 9500,9503,9506,9509:9510,9513,9518:9519,9522,9525,9528:9530,9533:9534,9537:9
> 538,9541:9542,9545:9546,9549:9560,9562:9631,9633:9641,9643:9651,9654:9655,96
> 57:9704,9720:9721,9727:9734,9737:9780,9784:9854,9856:9933","f":"INBOX","c":"
> Email","lsd":0,"sd":0,"hi":true,"v":2}}";s:10:"*_stream";N;}i:2;s:32:"0E7062
> 08ED364055BD1FBD618B474D0E";i:3;i:1512338346;i:4;s:9:"F71c41963";i:5;s:19:"l
> ucas at domain.de";i:6;i:0;i:7;i:1512338346;}
> [19409][2017-12-03T22:59:06+01:00] NOTICE: STATE: Error saving state,
> checking if this is due to previous synckey
> {5a207f65-78ec-43a9-aa69-476b00000000}20 not being accepted by client.
> [19409][2017-12-03T22:59:06+01:00] O:   </Folder>
> [19409][2017-12-03T22:59:06+01:00] >>>: Collection output peak memory usage:
> 2097152
>
> What can I do in this situation?

My assumption is that the client is not accepting the response being  
sent, and is re-issuing the previous request again. This is normal to  
happen *sometimes* when there are communication errors, but if it is  
happening constantly, there is probably something wrong with the data  
structure being sent vs what the client expects. I haven't had much  
time to test against OL 2016 all that much, but at a quick glance I  
don't see anything specifically wrong with that response. I will have  
to look at it in more depth to be sure.

For now, you can try to remove that specific email from the inbox and  
see if it is something specific about the data in that mail that is  
causing the client to reject that response.

>
> Thanks in advance,
> Lucas
>
> --
> Horde mailing list
> Frequently Asked Questions: http://horde.org/faq/
> To unsubscribe, mail: horde-unsubscribe at lists.horde.org

-- 
mike
The Horde Project
http://www.horde.org
https://www.facebook.com/hordeproject
https://www.twitter.com/hordeproject
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-keys
Size: 9272 bytes
Desc: PGP Public Key
URL: <https://lists.horde.org/archives/horde/attachments/20171207/b9ecc876/attachment-0002.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 821 bytes
Desc: PGP Digital Signature
URL: <https://lists.horde.org/archives/horde/attachments/20171207/b9ecc876/attachment-0003.bin>