Uploaded image for project: 'Z-Push'
  1. Z-Push
  2. ZP-1201

Settings command triggered by KOE always overwrites device data

    Details

    • Type: Bug
    • Status: QA
    • Priority: High
    • Resolution: Fixed
    • Affects Version/s: 2.3.5
    • Fix Version/s: 2.3.7 beta1, 2.3.7
    • Component/s: None
    • Labels:
      None

      Description

      The Settings command seems to always overwrite the device data, even if nothing changed.

      On busy systems that favors race conditions involving the devicedata state.

      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] -------- Start
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] cmd='Settings' devType='WindowsOutlook' devId='29..c3' getUser='user' from='xxx.xxx.51.95' version='2.3.5+4' method='POST'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] Used timezone 'Europe/Amsterdam'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] ZPush::GetBackend(): trying autoload backend 'BackendKopano'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] BackendKopano using PHP-MAPI version: 8.1.1.11-11 - PHP version: 5.6.25
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] Request::ProcessHeaders() ASVersion: 14.0
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] ZPush::CommandNeedsProvisioning(17): true
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] FileStateMachine->GetState() read '303149' bytes from file: '/var/lib/z-push/3/c/29..c3-devicedata'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] ASDevice data loaded for user: 'user'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] TopCollector(): Initialized mutexid Resource id #47 and memid Resource id #48.
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] LoopDetection(): Initialized mutexid Resource id #50 and memid Resource id #51.
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] ZPush::HierarchyCommand(17): false
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] KOE: 1.3.160 / 856c8038870200dce91dd7e250c7097a2e0673f5 / 2017-04-12 11:55
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] KOE Capabilities: receiveflags,sendflags,oof,ooftime,sharedfolders,webapp,freebusy,gab,notes,secondarycontacts,sendas,signatures,debugsupport 
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] DeviceManager->ProvisioningRequired('0') saved device key '-1': false
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] DeviceManager->getPolicyName(): determined policy name: 'default'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] ZPush::CommandNeedsAuthentication(17): true
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] KopanoBackend->Logon(): Trying to authenticate user 'user'..
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] KopanoBackend->openMessageStore('user'): Found 'DEFAULT' store: 'Resource id #58'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] KopanoBackend->Logon(): User 'user' is authenticated
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] Store supports properties containing Unicode characters.
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] ZPush::CommandNeedsPlainInput(17): false
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I  <Settings:Settings>
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] KopanoBackend->GetKoeSignatures(): Found 2 signatures - new '' - reply/fw: '' - hash: ee94..4d0da
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I   <Settings:Oof>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I    <Settings:Get>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I     <Settings:BodyType>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I      
          TEXT
         
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I     </Settings:BodyType>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I    </Settings:Get>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I   </Settings:Oof>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] I  </Settings:Settings>
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O  <Settings:Settings>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O   <Settings:Status>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O   1
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O   </Settings:Status>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O   <Settings:Oof>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O    <Settings:Status>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O    1
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O    </Settings:Status>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O    <Settings:Get>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O     <Settings:OofState>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O     0
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O     </Settings:OofState>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O     <Settings:OofMessage>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      <Settings:AppliesToInternal/>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      <Settings:Enabled>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      0
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      </Settings:Enabled>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      <Settings:ReplyMessage>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      some oof message
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      </Settings:ReplyMessage>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      <Settings:BodyType>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      
          TEXT
         
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O      </Settings:BodyType>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O     </Settings:OofMessage>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O    </Settings:Get>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O   </Settings:Oof>
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] O  </Settings:Settings>
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] WBXMLEncoder->endTag() WBXML output completed
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] WBXML-OUT: ...
      24/04/2017 14:40:49 [ 1393] [WBXML] [user] [29..c3] WBXML-IN : ...
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] DeviceManager->Save(): Device data changed
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] FileStateMachine->SetState() written 303149 bytes on file: '/var/lib/z-push/3/c/29..c3-devicedata'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] DeviceManager->Save(): Device data saved
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] LoopDetection->ProcessLoopDetectionTerminate()
      24/04/2017 14:40:49 [ 1393] [ INFO] [user] [29..c3] cmd='Settings' memory='8.70 MiB/9.75 MiB' time='0.32s' devType='WindowsOutlook' devId='29..c3' getUser='user' from='xxx.xxx.51.95' version='2.3.5+4' method='POST' httpcode='200'
      24/04/2017 14:40:49 [ 1393] [DEBUG] [user] [29..c3] -------- End
      

        Gliffy Diagrams

          Attachments

            Issue Links

              Activity

                People

                • Assignee:
                  Sebastian Sebastian Kummer
                  Reporter:
                  Sebastian Sebastian Kummer
                • Votes:
                  0 Vote for this issue
                  Watchers:
                  2 Start watching this issue

                  Dates

                  • Created:
                    Updated:
                    Resolved: