Navigation

    Kopano
    • Register
    • Login
    • Search
    • Categories
    • Get Official Kopano Support
    • Recent
    Statement regarding the closure of the Kopano community forum and the end of the community edition

    ZPush Issue after zarafa to Kopana migration

    Z-Push when using Kopano
    3
    21
    7006
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • goudduif
      goudduif last edited by fbartels

      Hello,
      After the migration from Zarafa, to Kopano, I’ve some strange issues.
      First off all here a overview of the current install.

      0_1490028952126_upload-6c466df3-86bb-45d8-88be-2ae1c92e1192

      Different users have issues with the Mail app on android device, to receive new mail’s,
      We run already a resync to the device.
      But same issue.
      So I wipe the device, install all apps on it and sync them again.
      With the first sync, they receive all their mail, but later on the don’t receive new mails.
      And when the Sync the device, they didn’t receive their personal Address book on their device.

      While everything is correct available in the webApp.
      And I found the following in the Z-push-error.log

      20/03/2017 17:33:33 [20731] [FATAL] [user1] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:33:33 [20731] [FATAL] [user1] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:46:19 [21229] [FATAL] [user2] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:46:19 [21229] [FATAL] [user2] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:51:57 [21310] [FATAL] [user2] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:51:57 [21310] [FATAL] [user2] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:53:22 [21303] [FATAL] [user3] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      20/03/2017 17:53:22 [21303] [FATAL] [user3] Fatal error: Unknown:0 - Module 'mapi' already loaded (32)
      
      1 Reply Last reply Reply Quote 0
      • Sebastian
        Sebastian Kopano last edited by

        Somewhere in your configuration you include the mapi.so twice.
        Check the files in /etc/php5/apache/ and the conf.d directory.

        If this doesn’t fix the issue, please create a wbxml log for one user with this problem.
        More information about that is available here:
        https://wiki.z-hub.io/display/ZP/Debugging

        Cheers,
        Sebastian

        1 Reply Last reply Reply Quote 0
        • goudduif
          goudduif last edited by

          the strange issue is that I still find Zarafa items
          0_1490101167704_upload-5400ffd5-2d07-4ff3-a0aa-124cf6b94b6e

          And in the mods-available folder
          0_1490101223061_upload-5f6e6cc8-2f5d-4dbe-9247-a95c657a4c7d

          the extension=mapi.so is available, in the 20-kopano.ini , as wel in the Zarafa.ini
          when I add $specialLogUsers = array(‘user1@domain.com’,‘user1.’);
          restart the service with, service restart apache2
          resync the device, he didn’t create a new error log file.

          zarafa-admin -a -u user2 show me some message sync error’s
          Reason: Message was causing loop (2)
          While I’ve already submit the clear command.

          1 Reply Last reply Reply Quote 0
          • Sebastian
            Sebastian Kopano last edited by

            If you keep the zarafa.ini in this folder (even with a prefix) it’s still going to be included. So you need to remove the file or comment (#) the extension=mapi.so line.

            For specialLogUsers you should add only your username. If you username is ‘user1’ have it like this:

            $specialLogUsers = array('user1');
            

            Your example has a “.” which then doesn’t match the username.

            Messages that are causing loops need to be re-queued so they get synchronized again. This is not that easy with emails, if you really need this email on the mobile, resync the folder it’s in.
            For appointments, contacts and tasks you can just open the item in WebApp, modify something and save to have the object re-queued.

            1 Reply Last reply Reply Quote 0
            • goudduif
              goudduif last edited by fbartels

              Thanks a lot for all your support,
              I remeve the zarafa items, that solved the MAPI issue.
              Now I’ve still some devices, with a sync issue.
              The most off them are already solved.

              Broken object:  'SyncAppointment' ignored on '2017-03-21 21:45'
                     Information:    Subject: 'XXXXXXX' - On: '2017-04-05 19:30'
                     Reason:         Message was causing loop (2)
                     Item/Parent id: Udd5bb:c8b30938bf774f6897130e721f8d3ed62a3501000000/Udd5bb
              

              Can you tell me how I can solve this issue?

              1 Reply Last reply Reply Quote 0
              • goudduif
                goudduif last edited by goudduif

                Sorry, I’ve still some issues with z-push
                As you can read above, there where old zarafa items active, which one I remove, but I receive still a lot off errors in the error log.
                Can you tell me what is the right config file While I found the following
                0_1490174411105_upload-e516b95b-db5f-43eb-807a-e83f1731e541
                0_1490174463552_upload-53cfaec4-7b0b-4f1c-984c-db283f694b93 and on on the /use/share/z-push
                0_1490174505034_upload-e982d585-51c6-4863-9147-cc5e0075e3f1

                While I foung the following in the apache.conf file.

                0_1490174747667_upload-6125651e-c7c6-4945-aa88-b6674f6749ee

                Or is it better, to remove all z-push from the server, and reinstall them?

                1 Reply Last reply Reply Quote 0
                • fbartels
                  fbartels Kopano last edited by

                  your screenshots do not really look like you have the correct z-push installed, may it be that you installed it manually in the past?

                  On a fresh z-push installation you should only have the following fils in /etc/z-push:

                  -rw-r--r--   1 root root  2727 Mär  3 21:12 gabsync.conf.php
                  -rw-r--r--   1 root root  3735 Mär  3 21:12 kopano.conf.php
                  -rw-r--r--   1 root root  7855 Mär  3 21:12 policies.ini
                  -rw-r--r--   1 root root 19104 Mär  3 21:12 z-push.conf.php
                  

                  Also there should be more lines in the apache vhost.

                  As for the errors: instead of writing you get a lot of errors, it would be more helpful if you copy & paste the actual error messages. as for the loopdetection messages there should be more information in the default logfiles. if not then you need to enable wbxml, as Sebastian already explained.

                  Regards Felix

                  Resources:
                  https://kopano.com/blog/how-to-get-kopano/
                  https://documentation.kopano.io/
                  https://kb.kopano.io/

                  Support overview:
                  https://kopano.com/support/

                  goudduif 1 Reply Last reply Reply Quote 0
                  • goudduif
                    goudduif @fbartels last edited by fbartels

                    @fbartels
                    So far I can remember, I didn’t install them manual.

                    2/03/2017 11:08:15  [ERROR] [user2] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user2] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user2] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user2] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user2] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15  [ERROR] [user2] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15 [21110] [FATAL] [user2] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21110] [FATAL] [user2] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21110] [FATAL] [user2] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15 [21110] [FATAL] [user2] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15  [ERROR] [user1] trace error: /usr/share/z-push/lib/log/log.php:231 Argument 1 passed to Log::SetSpecialLogUsers() must be of the type array, null given, called in /usr/share/z-push/lib/core/zlog.php on line 154 and defined (4096) - backtrace: 3 steps
                    22/03/2017 11:08:15  [ERROR] [user1] trace error: /usr/share/z-push/lib/log/log.php:231 Argument 1 passed to Log::SetSpecialLogUsers() must be of the type array, null given, called in /usr/share/z-push/lib/core/zlog.php on line 154 and defined (4096) - backtrace: 3 steps
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15 [21128] [FATAL] [user1] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21128] [FATAL] [user1] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21128] [FATAL] [user1] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15 [21128] [FATAL] [user1] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15  [ERROR] [user1] trace error: /usr/share/z-push/lib/log/log.php:231 Argument 1 passed to Log::SetSpecialLogUsers() must be of the type array, null given, called in /usr/share/z-push/lib/core/zlog.php on line 154 and defined (4096) - backtrace: 3 steps
                    22/03/2017 11:08:15  [ERROR] [user1] trace error: /usr/share/z-push/lib/log/log.php:231 Argument 1 passed to Log::SetSpecialLogUsers() must be of the type array, null given, called in /usr/share/z-push/lib/core/zlog.php on line 154 and defined (4096) - backtrace: 3 steps
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15 [21130] [FATAL] [user1] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21130] [FATAL] [user1] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21130] [FATAL] [user1] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15 [21130] [FATAL] [user1] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15  [ERROR] [user1] trace error: /usr/share/z-push/lib/log/log.php:231 Argument 1 passed to Log::SetSpecialLogUsers() must be of the type array, null given, called in /usr/share/z-push/lib/core/zlog.php on line 154 and defined (4096) - backtrace: 3 steps
                    22/03/2017 11:08:15  [ERROR] [user1] trace error: /usr/share/z-push/lib/log/log.php:231 Argument 1 passed to Log::SetSpecialLogUsers() must be of the type array, null given, called in /usr/share/z-push/lib/core/zlog.php on line 154 and defined (4096) - backtrace: 3 steps
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 1:/usr/share/z-push/lib/core/zlog.php:154 - Log->SetSpecialLogUsers()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 2:/usr/share/z-push/lib/core/zlog.php:49 - ZLog::getLogger()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15  [ERROR] [user1] trace: 3:/usr/share/z-push/index.php:47 - ZLog::Initialize()
                    22/03/2017 11:08:15 [21129] [FATAL] [user1] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21129] [FATAL] [user1] FatalMisconfigurationException: The WBXML log users is not an array. - code: 0 - file: /usr/share/z-push/lib/core/zpush.php:300
                    22/03/2017 11:08:15 [21129] [FATAL] [user1] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    22/03/2017 11:08:15 [21129] [FATAL] [user1] Exception: (FatalMisconfigurationException) - The WBXML log users is not an array.
                    

                    When I uninstall Z-Push from appcenter, and remove the etc/z-push, and usr/share/z-push folder, and reinstall them.
                    Will this solve my issue, without that the users lost their data?

                    fbartels 1 Reply Last reply Reply Quote 0
                    • fbartels
                      fbartels Kopano @goudduif last edited by

                      @goudduif regarding the error, could it be that you did not follow the advice in https://forum.kopano.io/topic/131/zpush-issue-after-zarafa-to-kopana-migration/4 ?

                      When I uninstall Z-Push from appcenter, and remove the etc/z-push, and usr/share/z-push folder, and reinstall them.
                      Will this solve my issue, without that the users lost their data?

                      Thats hard to say with the information that you have provided. The worst “data loss” that you can have is that your mobile devices will resync once again, but if you have your mobile states in the default location and don’t delete them as well you should be fine.

                      Regards Felix

                      Resources:
                      https://kopano.com/blog/how-to-get-kopano/
                      https://documentation.kopano.io/
                      https://kb.kopano.io/

                      Support overview:
                      https://kopano.com/support/

                      goudduif 1 Reply Last reply Reply Quote 0
                      • goudduif
                        goudduif @fbartels last edited by

                        @fbartels
                        When I uninstall the z-push, I can also save remove the /usr/share/z-push folder?
                        Before I reinstall it?

                        fbartels 1 Reply Last reply Reply Quote 0
                        • fbartels
                          fbartels Kopano @goudduif last edited by

                          @goudduif that states are by default stored in /var/lib/z-push, if that was you question. /usr/share only contains replaceable program code.

                          Regards Felix

                          Resources:
                          https://kopano.com/blog/how-to-get-kopano/
                          https://documentation.kopano.io/
                          https://kb.kopano.io/

                          Support overview:
                          https://kopano.com/support/

                          1 Reply Last reply Reply Quote 0
                          • goudduif
                            goudduif last edited by fbartels

                            fbartels
                            I uninstall z-push, reboot the server, remove z-push folders, and reinstall z-push
                            in the etc\z-push folder, I find the config.php. and the z-push.conf.php
                            Which one is the right one, or are both files required?
                            While when I review the log settings off the config.php,

                            /**********************************************************************************
                             *  Logging settings
                             *  Possible LOGLEVEL and LOGUSERLEVEL values are:
                             *  LOGLEVEL_OFF            - no logging
                             *  LOGLEVEL_FATAL          - log only critical errors
                             *  LOGLEVEL_ERROR          - logs events which might require corrective actions
                             *  LOGLEVEL_WARN           - might lead to an error or require corrective actions in the future
                             *  LOGLEVEL_INFO           - usually completed actions
                             *  LOGLEVEL_DEBUG          - debugging information, typically only meaningful to developers
                             *  LOGLEVEL_WBXML          - also prints the WBXML sent to/from the device
                             *  LOGLEVEL_DEVICEID       - also prints the device id for every log entry
                             *  LOGLEVEL_WBXMLSTACK     - also prints the contents of WBXML stack
                             *
                             *  The verbosity increases from top to bottom. More verbose levels include less verbose
                             *  ones, e.g. setting to LOGLEVEL_DEBUG will also output LOGLEVEL_FATAL, LOGLEVEL_ERROR,
                             *  LOGLEVEL_WARN and LOGLEVEL_INFO level entries.
                             */
                                define('LOGFILEDIR', '/var/log/z-push/');
                                define('LOGFILE', LOGFILEDIR . 'z-push.log');
                                define('LOGERRORFILE', LOGFILEDIR . 'z-push-error.log');
                                define('LOGLEVEL', LOGLEVEL_INFO);
                                define('LOGAUTHFAIL', false);
                                //define('LOGLEVEL', LOGLEVEL_WBXML);
                            
                                // To save e.g. WBXML data only for selected users, add the usernames to the array
                                // The data will be saved into a dedicated file per user in the LOGFILEDIR
                                // Users have to be encapusulated in quotes, several users are comma separated, like:
                                 //  $specialLogUsers = array('info@domain.com', 'myusername');
                                define('LOGUSERLEVEL', LOGLEVEL_DEVICEID);
                               $specialLogUsers = array('user1', 'User2');
                            

                            I still didn’t find the user1, abd user2 log file in the log folder.

                            fbartels 1 Reply Last reply Reply Quote 0
                            • fbartels
                              fbartels Kopano @goudduif last edited by

                              @goudduif when running ls -la /usr/share/z-push/config.php you see which config file is used by z-push.

                              For me it is:

                              # ls -la /usr/share/z-push/config.php                                                                                                                                               
                              lrwxrwxrwx 1 root root 27 Mär  3 15:11 /usr/share/z-push/config.php -> /etc/z-push/z-push.conf.php
                              

                              Regards Felix

                              Resources:
                              https://kopano.com/blog/how-to-get-kopano/
                              https://documentation.kopano.io/
                              https://kb.kopano.io/

                              Support overview:
                              https://kopano.com/support/

                              1 Reply Last reply Reply Quote 0
                              • goudduif
                                goudduif last edited by

                                While there where still some issues.
                                I remove z-push totally with " univention-remove z-push*"
                                But now when I reinstall Z-push from the appcenter, there’s nothing in the \etc\z-push folder
                                0_1490351935071_upload-d45e6ac1-bc54-4bf3-8fde-4f7f82e9937b

                                How can I solve this issue?

                                fbartels 1 Reply Last reply Reply Quote 0
                                • fbartels
                                  fbartels Kopano @goudduif last edited by fbartels

                                  @goudduif your screenshot does not show the univention appcenter. you should remove/reinstall z-push through the appcenter and not the package management.

                                  Regards Felix

                                  Resources:
                                  https://kopano.com/blog/how-to-get-kopano/
                                  https://documentation.kopano.io/
                                  https://kb.kopano.io/

                                  Support overview:
                                  https://kopano.com/support/

                                  1 Reply Last reply Reply Quote 0
                                  • goudduif
                                    goudduif last edited by

                                    That’s exactly what I did, I reinstall them from appcenter.
                                    But there I didn’t find anything on etc/z-push, I review what’s happens on Package management.

                                    1 Reply Last reply Reply Quote 0
                                    • goudduif
                                      goudduif last edited by

                                      I retore the config file from Backup, so Z-Push works, but there still some issue’s

                                      There are different users, with two different devices.
                                      One off thensync all mails without a issue, the other one, didn’t receive any data, until she reboot their one-plus-one phone.
                                      After the reboot, it sync the mail, and calendar. (no Contac’s) only one time.
                                      even when I enable the WBXML logging, it didn’t show anything regarding this device.
                                      The strange issue is, that everything works fine till we upgrade from Zarafa to Kopano.
                                      Below you find the WBXML logging from the device that sync only after a reboot.
                                      the WBXML file didn’t show any data off this device till the user reboot their device.

                                      If you like it I can send you the WBXML file.

                                      1 Reply Last reply Reply Quote 0
                                      • Sebastian
                                        Sebastian Kopano last edited by

                                        If there is nothing in the z-push log and also nothing in the webserver access & error logs then the device most probably isn’t connecting to the server.
                                        Did you also change the ssl configuration? Could it be that the mobile isn’t recognizing your (new) certificate?

                                        1 Reply Last reply Reply Quote 0
                                        • goudduif
                                          goudduif last edited by fbartels

                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] -------- Start
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] cmd='Sync' devType='Android' devId='boxerc543471768' getUser='user1' from='10.0.0.96' version='2.3.5+4' method='POST'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Used timezone 'Europe/Amsterdam'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetBackend(): trying autoload backend 'BackendKopano'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] BackendKopano using PHP-MAPI version: 8.1.1-10 - PHP version: 5.4.45-0.235.201611171529
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Request::ProcessHeaders() ASVersion: 14.0
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::CommandNeedsProvisioning(0): true
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->GetState() read '3431' bytes from file: '/var/lib/z-push/8/6/boxerc543471768-devicedata'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ASDevice data loaded for user: 'user1'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] TopCollector(): Initialized mutexid Resource id #57 and memid Resource id #58.
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection(): Initialized mutexid Resource id #62 and memid Resource id #63.
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::HierarchyCommand(0): false
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->ProvisioningRequired('603050742') saved device key '603050742': false
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->getPolicyName(): determined policy name: 'default'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::CommandNeedsAuthentication(0): true
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->Logon(): Trying to authenticate user 'user1'..
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->openMessageStore('user1'): Found 'DEFAULT' store: 'Resource id #78'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->Logon(): User 'user1' is authenticated
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Store supports properties containing Unicode characters.
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::CommandNeedsPlainInput(0): false
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I  <Synchronize>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I   <Folders>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I    <Folder>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     <SyncKey>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      {1a66cdee-6888-49e0-a6f3-cf1dbdc512f5}8
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     </SyncKey>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     <FolderId>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      Udd5bb
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     </FolderId>
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->GetState() read '945' bytes from file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-fd'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncParameters->UseCPO('DEFAULT')
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] DeviceManager->GetBackendIdForFolderId(): folderid Udd5bb => c8b30938bf774f6897130e721f8d3ed6590000000000
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncCollections->AddCollection(): Folder id 'Udd5bb' : ref. PolicyKey '603050742', ref. Lifetime '', last sync at '1490724488'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncCollections->AddCollection(): Updated reference PolicyKey '603050742', reference Lifetime '', Last sync at '1490724488'
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     <DeletesAsMoves/>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     <GetChanges/>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     <WindowSize>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      10
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     </WindowSize>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     <Options>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      <FilterType>
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncParameters->UseCPO('DEFAULT')
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I       4
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      </FilterType>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      <AirSyncBase:BodyPreference>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I       <AirSyncBase:Type>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I        1
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I       </AirSyncBase:Type>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I       <AirSyncBase:TruncationSize>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I        200000
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I       </AirSyncBase:TruncationSize>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I      </AirSyncBase:BodyPreference>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I     </Options>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I    </Folder>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I   </Folders>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] I  </Synchronize>
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] HandleSync(): Start Output
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] KopanoBackend->GetFolderStat() fetched status information of 40 folders for store 'user1'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] SyncParameters->IsExporterRunRequired(): true - current: 1459353174/114/0/4 - saved: false - expiring: not set
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Sync->loadStates(): loading states for folder 'Udd5bb'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->CleanStates(): Deleting file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-7'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->GetState() read '231' bytes from file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-8'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ExportChangesICS->Config(): synching inital data
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ExportChangesICS->Config() initialized with state: 0x943500000000000007000000fc4c030016000000c8b30938bf774f6897130e721f8d3ed6023801000000d350030016000000c8b30938bf774f6897130e721f8d3ed6cd3901000000d450030016000000c8b30938bf774f6897130e721f8d3ed6ce39010000001f66030016000000c8b30938bf774f6897130e721f8d3ed69c3d010000002066030016000000c8b30938bf774f6897130e721f8d3ed69d3d010000007f6a030016000000c8b30938bf774f6897130e721f8d3ed6514001000000806a030016000000c8b30938bf774f6897130e721f8d3ed6524001000000
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ExportChangesICS->InitializeExporter() successfully. 2 changes ready to sync for 'c8b30938bf774f6897130e721f8d3ed6590000000000'.
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->ProcessLoopDetectionAddStatus: 'Udd5bb' with status 1
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] Folder type: Calendar
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O  <Synchronize>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O   <Folders>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O    <Folder>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     <SyncKey>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     {1a66cdee-6888-49e0-a6f3-cf1dbdc512f5}9
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     </SyncKey>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     <FolderId>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     Udd5bb
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     </FolderId>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     <Status>
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     1
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     </Status>
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): folderid:'Udd5bb' uuid:'1a66cdee-6888-49e0-a6f3-cf1dbdc512f5' counter:'8' max:'10' queued:'2'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): case 1.2 detected
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): case 1.2.1 detected
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->Detect(): loop data: loopcount(1), maxCount(10), queued(2), ignored(false)
                                          28/03/2017 20:08:08 [21368] [WARN] [user1] [boxerc543471768] Mobile loop detected! Messages sent to the mobile will be restricted to 1 items in order to identify the conflict
                                          28/03/2017 20:08:08 [21368] [WBXML] [user1] [boxerc543471768] O     <MoreAvailable/>
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: 'c8b30938bf774f6897130e721f8d3ed6363501000000', parentsourcekey: 'c8b30938bf774f6897130e721f8d3ed6590000000000', entryid: '000000005a5734edde3d428fb3a1251b1f41c7120100000005000000b6cd09a7a3d9402e92ae6bebd203c5a700000000'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] ZPush::GetAdditionalSyncFolderStore('c8b30938bf774f6897130e721f8d3ed6590000000000'): 'false'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] BodyPreference types: 1
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] GetBodyPreferenceBestMatch: 1
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] bpo: truncation size:'200000', allornone:'0', preview:'0'
                                          28/03/2017 20:08:08 [21368] [DEBUG] [user1] [boxerc543471768] MAPIStreamWrapper::stream_open(): initialized mapistream:  streamlength: 0
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] TimezoneUtil::FillTZNames() filling up bias -60
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] MAPIProvider->GetStoreProps(): Getting store properties.
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->IgnoreNextMessage(): Loop mode, potential broken message id 'Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000'
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O     <Perform>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O      <Add>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O       <ServerEntryId>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O       Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O       </ServerEntryId>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O       <Data>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:Timezone>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        xP///ygARwBNAFQAKwAwADEAOgAwADAAKQAgAEIAZQBsAGcAcgBhAGQAZQAsACAAQgByAGEAdABpAHMAbABhAHYAYQAAAAEAAAABAAMAAAAAAAAAAAAAACgARwBNAFQAKwAwADEAOgAwADAAKQAgAEIAZQBsAGcAcgBhAGQAZQAsACAAQgByAGEAdABpAHMAbABhAHYAYQAAAAAAAAAAAAAAAAAAAAAAAAAAAA==
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:Timezone>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:DtStamp>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        20170228T200033Z
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:DtStamp>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:StartTime>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        20170509T173000Z
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:StartTime>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:Subject>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        shopping
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:Subject>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:UID>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        76E3FD9EED9446BAB899C975763E450A0
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:UID>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:OrganizerName>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        user2 morris
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:OrganizerName>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:OrganizerEmail>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        user2@domain.org
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:OrganizerEmail>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:EndTime>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        20170509T183000Z
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:EndTime>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:Sensitivity>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        0
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:Sensitivity>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:BusyStatus>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:BusyStatus>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:AllDayEvent>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        0
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:AllDayEvent>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:MeetingStatus>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        3
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:MeetingStatus>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:Attendees>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user7dune@business.org
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user7dune@business.org
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user5@domain.org
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user5 morris
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user1@domain.org
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user1 morris
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user4@domain.org
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Email>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          user4 morris
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Name>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          <POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O          </POOMCAL:Attendee_Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </POOMCAL:Attendee>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:Attendees>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <AirSyncBase:Body>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <AirSyncBase:Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </AirSyncBase:Type>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <AirSyncBase:EstimatedDataSize>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         0
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </AirSyncBase:EstimatedDataSize>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <AirSyncBase:Data>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         <<< written 0 of 0 bytes of plain data >>>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O         </AirSyncBase:Data>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </AirSyncBase:Body>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <AirSyncBase:NativeBodyType>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        1
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </AirSyncBase:NativeBodyType>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        <POOMCAL:ResponseType>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        5
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O        </POOMCAL:ResponseType>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O       </Data>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O      </Add>
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] PHPWrapper->ImportMessageChange(): change for: 'Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000'
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] HandleSync(): Exported maxItems of messages: 1 / 2
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O     </Perform>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O    </Folder>
                                          
                                          
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] FileStateMachine->SetState() written 945 bytes on file: '/var/lib/z-push/8/6/boxerc543471768-1a66cdee-6888-49e0-a6f3-cf1dbdc512f5-fd'
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O   </Folders>
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] O  </Synchronize>
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] WBXMLEncoder->endTag() WBXML output completed
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] WBXML-OUT: AwFqAEVcT0sDezFhNjZjZGVlLTY4ODgtNDllMC1hNmYzLWNmMWRiZGM1MTJmNX05AAFSA1VkZDViYgABTgMxAAEUVkdNA1VkZDViYjpjOGIzMDkzOGJmNzc0ZjY4OTcxMzBlNzIxZjhkM2VkNjM2MzUwMTAwMDAwMAABXQAERQN4UC8vL3lnQVJ3Qk5BRlFBS3dBd0FERUFPZ0F3QURBQUtRQWdBRUlBWlFCc0FHY0FjZ0JoQUdRQVpRQXNBQ0FBUWdCeUFHRUFkQUJwQUhNQWJBQmhBSFlBWVFBQUFBRUFBQUFCQUFNQUFBQUFBQUFBQUFBQUFDZ0FSd0JOQUZRQUt3QXdBREVBT2dBd0FEQUFLUUFnQUVJQVpRQnNBR2NBY2dCaEFHUUFaUUFzQUNBQVFnQnlBR0VBZEFCcEFITUFiQUJoQUhZQVlRQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQT09AAFRAzIwMTcwMjI4VDIwMDAzM1oAAWcDMjAxNzA1MDlUMTczMDAwWgABZgNLbmlwcGVuAAFoAzc2RTNGRDlFRUQ5NDQ2QkFCODk5Qzk3NTc2M0U0NTBBMAABWgNXaW0gR3VpanQAAVkDd2ltQHdncGMubmwAAVIDMjAxNzA1MDlUMTgzMDAwWgABZQMwAAFNAzEAAUYDMAABWAMzAAFHSEkDbGVvbmlldmFuZHVpam5AbGl2ZS5ubAABSgNsZW9uaWV2YW5kdWlqbkBsaXZlLm5sAAFqAzEAAQFISQNjb3JuZUB3Z3BjLm5sAAFKA0Nvcm5lIEd1aWp0AAFqAzEAAQFISQNsZXVudGplQHdncGMubmwAAUoDTGV1bnRqZSBHdWlqdAABagMxAAEBSEkDYXJpZUB3Z3BjLm5sAAFKA0FyaWUgR3VpanQAAWoDMQABAQEAEUpGAzEAAUwDMAABSwMAAQFWAzEAAQAEdgM1AAEBAQEBAQE=
                                          28/03/2017 20:08:09 [21368] [WBXML] [user1] [boxerc543471768] WBXML-IN : AwFqAEVcT0sDezFhNjZjZGVlLTY4ODgtNDllMC1hNmYzLWNmMWRiZGM1MTJmNX04AAFSA1VkZDViYgABHhNVAzEwAAFXWAM0AAEAEUVGAzEAAUcDMjAwMDAwAAEBAQEBAQ==
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] LoopDetection->ProcessLoopDetectionTerminate()
                                          28/03/2017 20:08:09 [21368] [ INFO] [user1] [boxerc543471768] cmd='Sync' memory='6.90 MiB/7.25 MiB' time='0.75s' devType='Android' devId='boxerc543471768' getUser='user1' from='10.0.0.96' version='2.3.5+4' method='POST' httpcode='200'
                                          28/03/2017 20:08:09 [21368] [DEBUG] [user1] [boxerc543471768] -------- End
                                          

                                          How can I solve this issue?

                                          1 Reply Last reply Reply Quote 0
                                          • goudduif
                                            goudduif last edited by fbartels

                                            The other device, of this user, show me also the following!

                                              Folder: Calendar             Sync: Synchronizing Status:  6% (7/114)
                                            Additional Folders:     none
                                            Status:                 OK
                                            WipeRequest on:         not set
                                            WipeRequest by:         not set
                                            Wiped on:               not set
                                            Policy name:            default
                                            Attention needed:       4 messages need attention because they could not be synchronized
                                                    Broken object:  'SyncMail' ignored on '2017-03-28 05:14'
                                                    Information:    Subject: 'Userday' - From: '"user2 Morris" <user2@domain.org>'
                                                    Reason:         Message was causing loop (2)
                                                    Item/Parent id: U16cff:c8b30938bf774f6897130e721f8d3ed6da3a01000000/U16cff
                                            
                                                    Broken object:  'SyncMail' ignored on '2017-03-28 19:27'
                                                    Information:    Subject: 'shopping' - From: '"user2 Morris" <user2@domain.org>'
                                                    Reason:         Message was causing loop (2)
                                                    Item/Parent id: U16cff:c8b30938bf774f6897130e721f8d3ed6373501000000/U16cff
                                            
                                                    Broken object:  'SyncAppointment' ignored on '2017-03-28 20:46'
                                                    Information:    Subject: 'shopping' - On: '2017-05-09 19:30'
                                                    Reason:         Message was causing loop (2)
                                                    Item/Parent id: Udd5bb:c8b30938bf774f6897130e721f8d3ed6363501000000/Udd5bb
                                            
                                                    Broken object:  'SyncAppointment' ignored on '2017-03-28 20:46'
                                                    Information:    Subject: 'shopping' - On: '2017-04-05 19:30'
                                                    Reason:         Message was causing loop (2)
                                                    Item/Parent id: Udd5bb:c8b30938bf774f6897130e721f8d3ed62a3501000000/Udd5bb
                                            

                                            I’ve already resync the users devices, run a clearloop, , and a fixstatus.
                                            I’m afraid that this is the reason why the contacts are not synced.Synchronization progress:

                                            1 Reply Last reply Reply Quote 0
                                            • First post
                                              Last post