iOS in-house app upgrade install failed with strange cancel error

We met some feedback of upgrade installation failed, I also met the same issue. And I can make sure I didn't cancel the upgrade install, but the apple system log shows install failed reason due to the user canceled.

canceled by client 1 for reason Error Domain=IXUserPresentableErrorDomain Code=3 "This installation was canceled by the user." UserInfo={NSLocalizedFailureReason=User canceled., NSLocalizedDescription=This installation was canceled by the user.}).

By analyzing the system log, I guess when upgrading the in-house app, the iOS system may need to uninstall the old version app, but the uninstall operation may fail due to some unknown reason. And will lead the upgrade install failed(the real install failed reason).

I don't know well about the installation of the in-house app. I think the in-house app upgrade install has some bugs.

Does anyone meet the same issue when upgrading the in-house app? Please download detail install log uploaded in the attachment!

 2022-01-27 22:33:54.986228 +0800    symptomsd    FlowOriginLedger for com.***.yyy, rx 565474 tx 436052 (combined rx 565474 tx 436052) num closed 200 last access 2022-01-27 22:23:33.718 +0800
2022-01-27 22:34:04.563184 +0800    Spotlight   Requested application com.***.yyy has policy OK, associated categories:DH1009 associated sites:(null) equivalent bundle identifiers:com.***.yyy
2022-01-27 22:34:11.442146 +0800    searchd Requested application com.***.yyy has policy OK, associated categories:DH1009 associated sites:(null) equivalent bundle identifiers:com.***.yyy
2022-01-27 22:34:15.372576 +0800    appstored   [UPP599852B1/com.***.yyy] Running HEAD operation to get size for URL: https://***.yyyy.com/inhouse.ipa task: LocalDataTask <F3E1C478-D962-4312-8206-3B73CDF5CAAC>.<1>
2022-01-27 22:34:15.682576 +0800    appstored   [UPP599852B1/com.***.yyy] Found size: 74710311 for URL: https://***.yyyy.com/inhouse.ipa
2022-01-27 22:34:15.692636 +0800    appstored   [UPP599852B1/com.***.yyy]: Evaluating download, size = 74710311 automatic = 0
2022-01-27 22:34:15.701953 +0800    appstored   [UPP599852B1/com.***.yyy]: Implicitly allowed - cellular limit applies
2022-01-27 22:34:15.702011 +0800    appstored   [UPP599852B1/com.***.yyy]: Evaluating download, size = 74710311 automatic = 0
2022-01-27 22:34:15.702438 +0800    appstored   [UPP599852B1/com.***.yyy]: Implicitly denied low data - over threshold, or threshold unavailable
2022-01-27 22:34:15.703212 +0800    appstored   [UPP599852B1/com.***.yyy] Establishing coordinator for intent `Updating` with importance `UserWaiting`
2022-01-27 22:34:15.719999 +0800    installcoordinationd    -[IXSCoordinatorManager coordinatorForBundleID:creatingIfNotExisting:]_block_invoke: Registered coordinator: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:Background state:IXAppCoordinationStatePlaceholderPromiseNotSet promise-state:01234>
2022-01-27 22:34:15.720053 +0800    installcoordinationd    com.***.yyy:4:2:1:1:Created coordinator with intent IXCoordinatorIntentUpdating for client App Store
2022-01-27 22:34:15.721609 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setPriorityBoostCompletion:withSourceInfo:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:Background state:IXAppCoordinationStatePlaceholderPromiseNotSet promise-state:01234>: Retaining completion from appstored (215), QOS_CLASS_USER_INITIATED (0x19)
2022-01-27 22:34:15.721683 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setImportance:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:Background state:IXAppCoordinationStatePlaceholderPromiseNotSet promise-state:01234>: setting importance to UserWaiting
2022-01-27 22:34:15.722580 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setPriorityBoostCompletion:withSourceInfo:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStatePlaceholderPromiseNotSet promise-state:01234>: Disposing of completion from appstored (215), QOS_CLASS_USER_INITIATED (0x19)
2022-01-27 22:34:15.722615 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setPriorityBoostCompletion:withSourceInfo:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStatePlaceholderPromiseNotSet promise-state:01234>: Retaining completion from appstored (215), QOS_CLASS_USER_INITIATED (0x19)
2022-01-27 22:34:15.724585 +0800    appstored   [UPP599852B1/com.***.yyy] Skipping store metadata for non-store installation
2022-01-27 22:34:15.724673 +0800    installcoordinationd    -[IXSDataPromiseManager registerPromise:]: Registered promise <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9> for UUID 972307B4-DC5D-4A50-8421-4AE931560BA9
2022-01-27 22:34:15.726828 +0800    installcoordinationd    -[IXSPlaceholder setAttributes:]: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>: setting placeholder attributes to <IXPlaceholderAttributes<0x15fd0e3a0> : {
}>
2022-01-27 22:34:16.014767 +0800    installcoordinationd    -[IXSPlaceholder setIcon:]: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>: setting icon promise to <IXSPromisedTransferToPath(0x1613077f0) Name:artwork Creator:App Store UUID:7412570A-073E-4566-B3CB-121E1D438A3A PercentComplete:1.000000 IsComplete:Y DiskUsageBytes:0>
2022-01-27 22:34:16.019087 +0800    installcoordinationd    -[IXSDataPromiseManager promiseDidBegin:]: Unowned promise began: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:16.019119 +0800    installcoordinationd    -[IXSDataPromiseManager promise:didUpdateProgress:]: Unowned promise update progress to 0.000000 : <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:16.019218 +0800    installcoordinationd    -[IXSPlaceholder setConfigurationComplete:]: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>: setting configuration complete to Y
2022-01-27 22:34:16.021487 +0800    installcoordinationd    -[IXSPlaceholder _materialize]: Materializing placeholder <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9> at /private/var/containers/Shared/SystemGroup/systemgroup.com.apple.installcoordinationd/Library/InstallCoordination/PromiseStaging/972307B4-DC5D-4A50-8421-4AE931560BA9
2022-01-27 22:34:16.025052 +0800    installcoordinationd    -[IXSDataPromiseManager promiseDidComplete:]: Unowned promise completed: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:16.025312 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setPlaceholderPromise:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateReadyForPlaceholderInstall promise-state:P1234> : Placeholder Promise set: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:16.025356 +0800    installcoordinationd    -[IXSCoordinatedAppInstall promiseDidComplete:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateReadyForPlaceholderInstall promise-state:P1234> : Promise completed: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:16.030004 +0800    installcoordinationd    -[IXSCoordinatedAppInstall _onQueue_acquireAssertionAndInstallPlaceholder]: Acquiring termination assertion (if needed) to install placeholder for com.***.yyy
2022-01-27 22:34:16.030126 +0800    installcoordinationd    -[IXSPendingOperationsTracker beginPendingOperationForBundleID:]_block_invoke: Beginning pending operation for com.***.yyy
2022-01-27 22:34:16.031110 +0800    installcoordinationd    -[IXSCoordinatedAppInstall _onQueue_acquireAssertionIfNeededForPlaceholder:]: User is waiting for this placeholder install - not acquiring termination assertion to install com.***.yyy
2022-01-27 22:34:16.032604 +0800    installcoordinationd    -[IXSCoordinatedAppInstall _onQueue_installPlaceholder]: No termination assertion acquired for placeholder com.***.yyy; proceeding with install.
2022-01-27 22:34:16.033252 +0800    installcoordinationd    com.***.yyy:4:8:1:1:Placeholder Promise began. Coordinator State: IXAppCoordinationStateWaitingForPlaceholderInstall
2022-01-27 22:34:16.033479 +0800    installcoordinationd    com.***.yyy:4:8:2:1:Placeholder Promise completed. Coordinator State: IXAppCoordinationStateWaitingForPlaceholderInstall
2022-01-27 22:34:16.033735 +0800    appstored   -[IXAppInstallCoordinator _clientDelegate_promiseDidBeginFulfillmentWithIdentifier:]: <IXUpdatingAppInstallCoordinator<0x104e555f0> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>: Got promise did begin fulfillment for identifier 1
2022-01-27 22:34:16.033766 +0800    appstored   -[IXAppInstallCoordinator _clientDelegate_promiseDidBeginFulfillmentWithIdentifier:]_block_invoke: <IXUpdatingAppInstallCoordinator<0x104e555f0> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>: Ignoring delegate message; no observer set
2022-01-27 22:34:16.033832 +0800    appstored   [UPP599852B1/com.***.yyy] Importing new installation (order = 'none')
2022-01-27 22:34:16.034168 +0800    installcoordinationd    com.***.yyy:5:1:1:1:Beginning installation for file:///private/var/containers/Shared/SystemGroup/systemgroup.com.apple.installcoordinationd/Library/InstallCoordination/PromiseStaging/972307B4-DC5D-4A50-8421-4AE931560BA9/inhouse.app/ with options {
    CFBundleIdentifier = "com.***.yyy";
    IsUserInitiated = 1;
    LSInstallType = 1;
    PackageType = Placeholder;
    PerformAPFSClone = 1;
}
2022-01-27 22:34:16.036849 +0800    *** com.***.yyy:5:1:1:1:Kicking off ininstall: <LSMIPackageInstallCall @ 0x104718f00; b: com.***.yyy p: file:///private/var/containers/Shared/SystemGroup/systemgroup.com.apple.installcoordinationd/Library/InstallCoordination/PromiseStaging/972307B4-DC5D-4A50-8421-4AE931560BA9/inhouse.app/> type 2 with options {
    CFBundleIdentifier = "com.***.yyy";
    IsUserInitiated = 1;
    LSInstallType = 1;
    PackageType = Placeholder;
    PerformAPFSClone = 1;
}
2022-01-27 22:34:16.037081 +0800    appstored   [UPP599852B1/com.***.yyy]: Allowing start, all policy conditions were met
2022-01-27 22:34:16.038501 +0800    appstored   [UPP599852B1/com.***.yyy] Queuing preamble task
2022-01-27 22:34:16.038587 +0800    appstored   [UPP599852B1/com.***.yyy] Performing preamble checks
2022-01-27 22:34:16.038759 +0800    appstored   [UPP599852B1/com.***.yyy] Establishing coordinator for intent `Updating` with importance `UserWaiting`
2022-01-27 22:34:16.038803 +0800    appstored   Polling completed, started 1 installation(s): (
    "UPP599852B1/com.***.yyy"
)
2022-01-27 22:34:16.039087 +0800    *** com.***.yyy:5:1:1:1:Proceeding with install: <LSMIPackageInstallCall @ 0x104718f00; b: com.***.yyy p: file:///private/var/containers/Shared/SystemGroup/systemgroup.com.apple.installcoordinationd/Library/InstallCoordination/PromiseStaging/972307B4-DC5D-4A50-8421-4AE931560BA9/inhouse.app/> type 2 with options {
    CFBundleIdentifier = "com.***.yyy";
    IsUserInitiated = 1;
    LSInstallType = 1;
    PackageType = Placeholder;
    PerformAPFSClone = 1;
} on behalf of 112
2022-01-27 22:34:16.040035 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setPriorityBoostCompletion:withSourceInfo:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateWaitingForPlaceholderInstall promise-state:P1234>: Disposing of completion from appstored (215), QOS_CLASS_USER_INITIATED (0x19)
2022-01-27 22:34:16.040081 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setPriorityBoostCompletion:withSourceInfo:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateWaitingForPlaceholderInstall promise-state:P1234>: Retaining completion from appstored (215), QOS_CLASS_DEFAULT (0x15)
2022-01-27 22:34:16.041075 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setInitialODRAssetPromises:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateWaitingForPlaceholderInstall promise-state:P1234> : Initial ODR Asset Promises set: (
)
2022-01-27 22:34:16.045993 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setDeviceSecurityPromise:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateWaitingForPlaceholderInstall promise-state:P123s> : Device Security Promise set: <IXSPromisedOutOfBandTransfer(0x16100b410) Name:security-promise Creator:App Store UUID:28A766EE-BF47-480B-8F58-74721ACE812D PercentComplete:0.000000 IsComplete:N DiskUsageBytes:0>
2022-01-27 22:34:16.048140 +0800    appstored   Beginning progress for bundleID: com.***.yyy storeItemID: (null) reason: Restart
2022-01-27 22:34:16.048574 +0800    appstored   [UPP599852B1/com.***.yyy] Preflight suceeded with coordinator: <IXUpdatingAppInstallCoordinator<0x104f5c840> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>
2022-01-27 22:34:16.048707 +0800    appstored   [UPP599852B1/com.***.yyy] Beginning downloading
2022-01-27 22:34:16.049225 +0800    appstored   [UPP599852B1/com.***.yyy] Establishing coordinator based data consumer
2022-01-27 22:34:16.050369 +0800    installcoordinationd    -[IXSDataPromiseManager registerPromise:]: Registered promise <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.000000 IsComplete:N DiskUsageBytes:0> for UUID 36AE0914-4D2F-4C30-BE98-ED352ED22145
2022-01-27 22:34:16.052347 +0800    runningboardd   Received termination request from [daemon<com.apple.mobile.installd>:203] on <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">> with context <RBSTerminateContext| code:0xDEAD6502 explanation:FBSApplicationTerminationAssertion reportType:None maxTerminationResistance:Interactive attrs:[
    <RBSPreventLaunchLimitation| <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>>
    ]>
2022-01-27 22:34:16.053486 +0800    installcoordinationd    -[IXSCoordinatedAppInstall setAppAssetPromise:]: <IXSCoordinatedAppInstall<0x161008d40> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B intent:IXCoordinatorIntentUpdating creator/appDRI:App Store importance:UserWaiting state:IXAppCoordinationStateWaitingForPlaceholderInstall promise-state:Pa23s> : App Asset Promise set: <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.000000 IsComplete:N DiskUsageBytes:0>
2022-01-27 22:34:16.055538 +0800    appstored   [UPP599852B1/com.***.yyy]: Allowing access to expensive network (result = 3)
2022-01-27 22:34:16.055629 +0800    appstored   [UPP599852B1/com.***.yyy]: Denying access to low data network (result = 4)
2022-01-27 22:34:16.055720 +0800    appstored   [UPP599852B1/com.***.yyy]: Allowing access to cellular (mask = 3e)
2022-01-27 22:34:16.055811 +0800    appstored   [UPP599852B1/com.***.yyy] Will download package using request: <AssetRequest: 0x104c81f40> { URL: https://***.yyyy.com/inhouse.ipa }
2022-01-27 22:34:16.056929 +0800    installcoordinationd    -[IXSDataPromise(IXSDataPromiseIPCMethods) _remote_preflightWithCompletion:]_block_invoke: Promise <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.000000 IsComplete:N DiskUsageBytes:0>: 0 bytes needed, 1570856960 bytes available.
2022-01-27 22:34:16.063165 +0800    appstored   [UPP599852B1/com.***.yyy/7B07B6A7] Reusing existing session with identifier: (null) (com.apple.appstored.itunesstored.ats.nolowdata)
2022-01-27 22:34:16.063610 +0800    appstored   [UPP599852B1/com.***.yyy/7B07B6A7] No reporting as request reason is unspecified
2022-01-27 22:34:16.063658 +0800    appstored   [UPP599852B1/com.***.yyy/7B07B6A7] Created task: LocalDataTask <4DEC6628-0E27-4745-ABD5-56E47BD2D039>.<5> in session: com.apple.appstored.itunesstored.ats.nolowdata for URL: https://***.yyyy.com/inhouse.ipa
2022-01-27 22:34:16.063920 +0800    appstored   [UPP599852B1/com.***.yyy/7B07B6A7] Started task: LocalDataTask <4DEC6628-0E27-4745-ABD5-56E47BD2D039>.<5>
2022-01-27 22:34:16.094445 +0800    runningboardd   Acquiring assertion targeting system from originator [daemon<com.apple.mobile.installd>:203] with description <RBSAssertionDescriptor| "FBSApplicationTerminationAssertion" ID:32-203-1445 target:system attributes:[
    <RBSPreventLaunchLimitation| <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>>
    ]>
2022-01-27 22:34:16.094888 +0800    runningboardd   Executing termination request for: <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>
2022-01-27 22:34:16.095792 +0800    runningboardd   [application<com.***.yyy>:426] Terminating with context: <RBSTerminateContext| code:0xDEAD6502 explanation:FBSApplicationTerminationAssertion reportType:None maxTerminationResistance:Interactive attrs:[
    <RBSPreventLaunchLimitation| <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>>
    ]>
2022-01-27 22:34:16.096291 +0800    runningboardd   [application<com.***.yyy>:426] terminate_with_reason() success
2022-01-27 22:34:16.096529 +0800    SpringBoard termination assertion acquired for com.***.yyy
2022-01-27 22:34:16.098788 +0800    SpringBoard [application<com.***.yyy>:426] Workspace connection invalidated.
2022-01-27 22:34:16.098815 +0800    SpringBoard [application<com.***.yyy>:426] Now flagged as pending exit for reason: workspace client connection invalidated
2022-01-27 22:34:16.099145 +0800    backboardd  Connection removed: IOHIDEventSystemConnection uuid:684E3A30-685A-4B6E-B59D-4A51B15AFEE6 pid:426 process:inhouse type:Passive entitlements:0x0 caller:BackBoardServices: <redacted> + 340 attributes:{
    HighFrequency = 1;
    bundleID = "com.***.yyy";
    pid = 426;
} state:0x1 events:466 mask:0x800
2022-01-27 22:34:16.099829 +0800    backboardd  Removing client connection <BKHIDClientConnection: 0x102c7c380; IOHIDEventSystemConnectionRef: 0x1060c9ea0; vpid: 426(v4B0); taskPort: 0x38E63; bundleID: com.***.yyy> for client: IOHIDEventSystemConnection uuid:684E3A30-685A-4B6E-B59D-4A51B15AFEE6 pid:426 process:inhouse type:Passive entitlements:0x0 caller:BackBoardServices: <redacted> + 340 attributes:{
    HighFrequency = 1;
    bundleID = "com.***.yyy";
    pid = 426;
} state:0x1 events:466 mask:0x800 source:HID
2022-01-27 22:34:16.122699 +0800    installd    0x16ceff000 -[MIInstaller performInstallationWithError:]: Installing <MIInstallableParallelPlaceholder ID=com.***.yyy; Version=(null), ShortVersion=(null)>
2022-01-27 22:34:16.122808 +0800    installd    com.***.yyy:7:2:1:1:Start : Install (Parallel Placeholder)
2022-01-27 22:34:16.198261 +0800    runningboardd   [xpcservice<com.***.yyy.notificationExt([daemon<com.apple.SpringBoard>:65])>:446] termination reported by launchd (2, 9, 9)
2022-01-27 22:34:16.198460 +0800    runningboardd   Removing process: [xpcservice<com.***.yyy.notificationExt([daemon<com.apple.SpringBoard>:65])>:446]
2022-01-27 22:34:16.198766 +0800    runningboardd   Removing assertions for terminated process: [xpcservice<com.***.yyy.notificationExt([daemon<com.apple.SpringBoard>:65])>:446]
2022-01-27 22:34:16.199381 +0800    runningboardd   Calculated state for xpcservice<com.***.yyy.notificationExt([daemon<com.apple.SpringBoard>:65])>: none (role: None)
2022-01-27 22:34:16.206118 +0800    runningboardd   XPC connection invalidated: [xpcservice<com.***.yyy.notificationExt([daemon<com.apple.SpringBoard>:65])>:446]
2022-01-27 22:34:16.207226 +0800    mediaserverd    -CMSessionMgr- CMSessionMgrHandleApplicationStateChange: Client com.***.yyy.notificationExt with pid '446' is now Terminated. Background entitlement: NO ActiveLongFormVideoSession: NO WhitelistedLongFormVideoApp NO
2022-01-27 22:34:16.211978 +0800    runningboardd   XPC connection invalidated: [application<com.***.yyy>:426]
2022-01-27 22:34:16.233376 +0800    runningboardd   [application<com.***.yyy>:426] termination reported by launchd (15, 3735905538, 9)
2022-01-27 22:34:16.233581 +0800    runningboardd   Removing process: [application<com.***.yyy>:426]
2022-01-27 22:34:16.233995 +0800    runningboardd   Removing launch job for: [application<com.***.yyy>:426]
2022-01-27 22:34:16.235094 +0800    runningboardd   Removed job for [application<com.***.yyy>:426]
2022-01-27 22:34:16.235312 +0800    runningboardd   Removing assertions for terminated process: [application<com.***.yyy>:426]
2022-01-27 22:34:16.250569 +0800    runningboardd   Calculated state for application<com.***.yyy>: none (role: None)
2022-01-27 22:34:16.251280 +0800    runningboardd   XPC connection invalidated: [application<com.***.yyy>:426]
2022-01-27 22:34:16.253099 +0800    runningboardd   Calculated state for application<com.***.yyy>: none (role: None)
2022-01-27 22:34:16.255882 +0800    SpringBoard [application<com.***.yyy>:426] Process exited: <RBSProcessExitContext| specific, status:<RBSProcessExitStatus| domain:runningboard(15) code:0xdead6502>>.
2022-01-27 22:34:16.255951 +0800    SpringBoard [application<com.***.yyy>:426] Setting process task state to: Not Running
2022-01-27 22:34:16.256028 +0800    SpringBoard [application<com.***.yyy>:426] Setting process visibility to: Unknown
2022-01-27 22:34:16.256149 +0800    SpringBoard [application<com.***.yyy>:426] Invalidating workspace.
2022-01-27 22:34:16.256206 +0800    SpringBoard Removing workspace registration for processHandle: [application<com.***.yyy>:426]
2022-01-27 22:34:16.256242 +0800    SpringBoard Removing: <FBApplicationProcess: 0x12e3e1310; application<com.***.yyy>:426(v4B0)>
2022-01-27 22:34:16.256274 +0800    SpringBoard Process exited: <FBApplicationProcess: 0x12e3e1310; application<com.***.yyy>:426(v4B0)> -> <RBSProcessExitContext| specific, status:<RBSProcessExitStatus| domain:runningboard(15) code:0xdead6502>>
2022-01-27 22:34:16.257350 +0800    mediaserverd    -CMSessionMgr- CMSessionMgrHandleApplicationStateChange: Client com.***.yyy with pid '426' is now Terminated. Background entitlement: YES ActiveLongFormVideoSession: NO WhitelistedLongFormVideoApp NO
2022-01-27 22:34:16.257894 +0800    SpringBoard Application process state changed for com.***.yyy: (null)
2022-01-27 22:34:16.263077 +0800    SpringBoard Client provider invalidated: <FBWorkspace: 0x281ff77b0; application<com.***.yyy>>
2022-01-27 22:34:16.263534 +0800    SpringBoard Invalidating scene: sceneID:com.***.yyy-default
2022-01-27 22:34:16.264992 +0800    SpringBoard [sceneID:com.***.yyy-default][1] Scene invalidated.
2022-01-27 22:34:16.512903 +0800    installd    com.***.yyy:7:2:2:1:Success (End) : Install (Parallel Placeholder)
2022-01-27 22:34:16.513099 +0800    installd    0x16ceff000 -[MIInstaller performInstallationWithError:]: Install Successful for (Placeholder:com.***.yyy); Staging: 0.00s; Waiting: 0.00s; Preflight/Patch: 0.00s, Verifying: 0.00s; Overall: 0.20s
2022-01-27 22:34:16.514734 +0800    appstored   [UPP599852B1/com.***.yyy/7B07B6A7] Using server trust for authentication challenge
2022-01-27 22:34:16.515176 +0800    runningboardd   RBSStateCapture remove item called for untracked item <RBConnectionClient| 426 name:application<com.***.yyy> entitlements:<RBEntitlements| [
            
            ]> inheritanceManager:<RBClientInheritanceManager| >>
2022-01-27 22:34:16.515206 +0800    SpringBoard termination assertion released for com.***.yyy
2022-01-27 22:34:16.515413 +0800    *** com.***.yyy:5:4:1:1:Registering bundles - start
2022-01-27 22:34:16.515561 +0800    *** com.***.yyy:5:4:1:1:Registering com.***.yyy at /private/var/containers/Bundle/Application/70D1ADCE-B225-4F55-84A1-CA834A516F45/com.apple.mobileinstallation.placeholder
2022-01-27 22:34:16.532236 +0800    appstored   [UPP599852B1/com.***.yyy/7B07B6A7] Task: LocalDataTask <4DEC6628-0E27-4745-ABD5-56E47BD2D039>.<5> will send request
2022-01-27 22:34:16.534785 +0800    *** - 45683955: Checking whether application is managed at file:///private/var/containers/Bundle/Application/70D1ADCE-B225-4F55-84A1-CA834A516F45/com.apple.mobileinstallation.placeholder//com.***.yyy
2022-01-27 22:34:16.535181 +0800    *** com.***.yyy:5:5:1:1:Building bundle record for app
2022-01-27 22:34:16.536933 +0800    *** com.***.yyy:5:5:2:1:Built bundle record for app
2022-01-27 22:34:16.642838 +0800    *** com.***.yyy:5:4:2:1:Registered bundle status = success
2022-01-27 22:34:16.642868 +0800    *** com.***.yyy:5:4:2:1:Registering com.***.yyy
2022-01-27 22:34:16.642983 +0800    *** com.***.yyy:5:4:2:1:Registering bundles - end
2022-01-27 22:34:16.670748 +0800    appstored   [LaunchServicesObserver]: Apps offloaded: [com.***.yyy]
2022-01-27 22:34:16.670818 +0800    appstored   [B756360B] Cleaning up following removal of apps: [com.***.yyy]
2022-01-27 22:34:16.672919 +0800    appstored   Clearing launch history for com.***.yyy
2022-01-27 22:34:16.675271 +0800    appstored   Handling application demotion: {bundleID = com.***.yyy; version = (null); shortVersion = (null); variantID = (null)}
2022-01-27 22:34:16.677746 +0800    appstored   [LaunchServicesObserver]: Apps offloaded: [com.***.yyy]
2022-01-27 22:34:16.677898 +0800    appstored   [863A9660] Cleaning up following removal of apps: [com.***.yyy]
2022-01-27 22:34:18.039103 +0800    appstored   -[IXAppInstallCoordinator _clientDelegate_didUpdateProgress:forPhase:overallProgress:]: <IXUpdatingAppInstallCoordinator<0x104f5c840> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>: Got did update progress delegate with percentComplete 5.053600 phase IXCoordinatorProgressPhaseLoading overallProgress 3.385912
2022-01-27 22:34:18.039266 +0800    appstored   -[IXAppInstallCoordinator _clientDelegate_didUpdateProgress:forPhase:overallProgress:]_block_invoke: <IXUpdatingAppInstallCoordinator<0x104f5c840> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>: Ignoring delegate message; no observer set
2022-01-27 22:34:18.518281 +0800    appstored   -[IXAppInstallCoordinator _clientDelegate_didUpdateProgress:forPhase:overallProgress:]: <IXUpdatingAppInstallCoordinator<0x104f5c840> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>: Got did update progress delegate with percentComplete 7.093069 phase IXCoordinatorProgressPhaseLoading overallProgress 4.752356
2022-01-27 22:34:18.518435 +0800    appstored   -[IXAppInstallCoordinator _clientDelegate_didUpdateProgress:forPhase:overallProgress:]_block_invoke: <IXUpdatingAppInstallCoordinator<0x104f5c840> identifier:com.***.yyy uuid:78ABF3BF-CF8A-4999-8A2A-DA5A624C174B creator:App Store>: Ignoring delegate message; no observer set
2022-01-27 22:34:19.225873 +0800    nehelper    Handling an apps installed notification with bundle IDs (
    "com.***.yyy"
)
2022-01-27 22:34:19.291323 +0800    runningboardd   Acquiring assertion targeting system from originator [daemon<com.apple.mobile.installd>:203] with description <RBSAssertionDescriptor| "FBSApplicationTerminationAssertion" ID:32-203-1450 target:system attributes:[
    <RBSPreventLaunchLimitation| <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>>
    ]>
2022-01-27 22:34:19.293129 +0800    runningboardd   Executing termination request for: <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>
2022-01-27 22:34:19.531831 +0800    installd    0x16cf8b000 -[MIUninstaller performUninstallationWithError:]: Uninstalling parallel placeholder for com.***.yyy
2022-01-27 22:34:19.533612 +0800    installd    com.***.yyy:7:9:2:1:Success (End) : Uninstall (Parallel Placeholder)
2022-01-27 22:34:19.537589 +0800    SpringBoard termination assertion released for com.***.yyy
2022-01-27 22:34:19.537889 +0800    *** com.***.yyy:5:6:1:1:Unregister bundles - Start
2022-01-27 22:34:19.674045 +0800    *** com.***.yyy:5:6:1:1:Beginning _LSUnregisterAppWithBundleID (placeholder only)
2022-01-27 22:34:19.756216 +0800    installcoordinationd    -[IXSDataPromise cancelForReason:client:]: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9> : canceled by client 1 for reason Error Domain=IXUserPresentableErrorDomain Code=3 "This installation was canceled by the user." UserInfo={NSLocalizedFailureReason=User canceled., NSLocalizedDescription=This installation was canceled by the user.}
2022-01-27 22:34:19.756742 +0800    installcoordinationd    -[IXSDataPromise decommission]: Decommissioning <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:19.757803 +0800    installcoordinationd    -[IXSDataPromise cancelForReason:client:]: <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.108321 IsComplete:N DiskUsageBytes:0> : canceled by client 1 for reason Error Domain=IXUserPresentableErrorDomain Code=3 "This installation was canceled by the user." UserInfo={NSLocalizedFailureReason=User canceled., NSLocalizedDescription=This installation was canceled by the user.}
2022-01-27 22:34:19.759059 +0800    installcoordinationd    -[IXSDataPromise decommission]: Decommissioning <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.000000 IsComplete:N DiskUsageBytes:0>
2022-01-27 22:34:19.784715 +0800    appstored   Placeholder for application {bundleID = com.***.yyy; version = 2201122041; shortVersion = 1.9.7; variantID = (null)} removed, re-verifying installed application
2022-01-27 22:34:19.786051 +0800    appstored   Handling application installation: {bundleID = com.***.yyy; version = 2201122041; shortVersion = 1.9.7; variantID = (null)}
2022-01-27 22:34:19.786089 +0800    appstored   Ignoring installation for {bundleID = com.***.yyy; version = 2201122041; shortVersion = 1.9.7; variantID = (null)} as it does not appear to support ODR
2022-01-27 22:34:19.787542 +0800    appstored   Canceling progress for bundleID: com.***.yyy
2022-01-27 22:34:19.807920 +0800    navd    com.***.yyy:5:8:3:1:applicationsDidInstall <LSApplicationProxy: 0x103859f80> com.***.yyy file:///private/var/containers/Bundle/Application/70D1ADCE-B225-4F55-84A1-CA834A516F45/inhouse.app/ <com.***.yyy <installed >:0>
2022-01-27 22:34:21.645313 +0800    tccd    Handling Application Uninstalled event for {
    bundleIDs =     (
        "com.***.yyy"
    );
    isPlaceholder = 1;
}.
2022-01-27 22:34:22.107373 +0800    tccd    LS says com.***.yyy was uninstalled but now we have found it is still installed.
2022-01-27 22:34:28.331871 +0800    nehelper    Handling an apps uninstalled notification with bundle IDs (
    "com.***.yyy"
)
2022-01-27 22:34:28.754040 +0800    nehelper    Handling an apps installed notification with bundle IDs (
    "com.***.yyy"
)

2022-01-27 22:34:19.225873 +0800    nehelper    Handling an apps installed notification with bundle IDs (
    "com.***.yyy"
)
2022-01-27 22:34:19.291323 +0800    runningboardd   Acquiring assertion targeting system from originator [daemon<com.apple.mobile.installd>:203] with description <RBSAssertionDescriptor| "FBSApplicationTerminationAssertion" ID:32-203-1450 target:system attributes:[
    <RBSPreventLaunchLimitation| <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>>
    ]>
2022-01-27 22:34:19.293129 +0800    runningboardd   Executing termination request for: <RBSProcessPredicate <RBSProcessBundleIdentifierPredicate "com.***.yyy">>
2022-01-27 22:34:19.531831 +0800    installd    0x16cf8b000 -[MIUninstaller performUninstallationWithError:]: Uninstalling parallel placeholder for com.***.yyy
2022-01-27 22:34:19.533612 +0800    installd    com.***.yyy:7:9:2:1:Success (End) : Uninstall (Parallel Placeholder)
2022-01-27 22:34:19.537589 +0800    SpringBoard termination assertion released for com.***.yyy
2022-01-27 22:34:19.537889 +0800    *** com.***.yyy:5:6:1:1:Unregister bundles - Start
2022-01-27 22:34:19.674045 +0800    *** com.***.yyy:5:6:1:1:Beginning _LSUnregisterAppWithBundleID (placeholder only)
2022-01-27 22:34:19.756216 +0800    installcoordinationd    -[IXSDataPromise cancelForReason:client:]: <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9> : canceled by client 1 for reason Error Domain=IXUserPresentableErrorDomain Code=3 "This installation was canceled by the user." UserInfo={NSLocalizedFailureReason=User canceled., NSLocalizedDescription=This installation was canceled by the user.}
2022-01-27 22:34:19.756742 +0800    installcoordinationd    -[IXSDataPromise decommission]: Decommissioning <IXSPlaceholder(0x15ff07b80) Name:App Placeholder: inhouse (com.***.yyy) Creator:App Store UUID:972307B4-DC5D-4A50-8421-4AE931560BA9>
2022-01-27 22:34:19.757803 +0800    installcoordinationd    -[IXSDataPromise cancelForReason:client:]: <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.108321 IsComplete:N DiskUsageBytes:0> : canceled by client 1 for reason Error Domain=IXUserPresentableErrorDomain Code=3 "This installation was canceled by the user." UserInfo={NSLocalizedFailureReason=User canceled., NSLocalizedDescription=This installation was canceled by the user.}
2022-01-27 22:34:19.759059 +0800    installcoordinationd    -[IXSDataPromise decommission]: Decommissioning <IXSPromisedStreamingZipTransfer(0x15fe0e460) Name:UPP599852B1/com.***.yyy Creator:App Store UUID:36AE0914-4D2F-4C30-BE98-ED352ED22145 PercentComplete:0.000000 IsComplete:N DiskUsageBytes:0>
2022-01-27 22:34:19.784715 +0800    appstored   Placeholder for application {bundleID = com.***.yyy; version = 2201122041; shortVersion = 1.8.1; variantID = (null)} removed, re-verifying installed application
2022-01-27 22:34:19.786051 +0800    appstored   Handling application installation: {bundleID = com.***.yyy; version = 2201122041; shortVersion = 1.8.1; variantID = (null)}
2022-01-27 22:34:19.786089 +0800    appstored   Ignoring installation for {bundleID = com.***.yyy; version = 2201122041; shortVersion = 1.8.1; variantID = (null)} as it does not appear to support ODR
2022-01-27 22:34:19.787542 +0800    appstored   Canceling progress for bundleID: com.***.yyy
2022-01-27 22:34:19.807920 +0800    navd    com.***.yyy:5:8:3:1:applicationsDidInstall <LSApplicationProxy: 0x103859f80> com.***.yyy file:///private/var/containers/Bundle/Application/70D1ADCE-B225-4F55-84A1-CA834A516F45/inhouse.app/ <com.***.yyy <installed >:0>
2022-01-27 22:34:21.645313 +0800    tccd    Handling Application Uninstalled event for {
    bundleIDs =     (
        "com.***.yyy"
    );
    isPlaceholder = 1;
}.
2022-01-27 22:34:22.107373 +0800    tccd    LS says com.***.yyy was uninstalled but now we have found it is still installed.
2022-01-27 22:34:28.331871 +0800    nehelper    Handling an apps uninstalled notification with bundle IDs (
    "com.***.yyy"
)
2022-01-27 22:34:28.754040 +0800    nehelper    Handling an apps installed notification with bundle IDs (
    "com.***.yyy"
)

2022-01-27 22:34:22.107373 +0800 tccd LS says com.***.yyy was uninstalled but now we have found it is still installed.

What does this mean?

Updating in-house apps does not require deleting the earlier version. The update should replace the existing .ipa if it contains the same bundle identifier. Please file a feedback including reproduction steps and a sysdiagnose for further investigation.

iOS in-house app upgrade install failed with strange cancel error
 
 
Q