Solved

iOS 15/Xcode 13 [[RCPurchases sharedPurchases] offeringsWithCompletionBlock:] is never called

3 years ago
September 24, 2021
33 replies
2276 views

smudge.io
Helper
8 replies

iPadOS 15 running app from Xcode fails to return any products. Restore Purchases also fails to return.

Rebooting iPad allows it to work first time only.

This issue is specifically relating to auto-renewing subscriptions.

Built using Xcode Version 13.0 (13A233)

No issue with same code on iOS 14.

Success and failure logs follow.

Success:

2021-09-24 16:17:50.773181+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ Debug logging enabled
2021-09-24 16:17:50.773293+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ SDK Version - 3.12.4
2021-09-24 16:17:50.773336+0800 ZeroTime[345:4478] [Purchases] - DEBUG: 👤 Initial App User ID - (null)
2021-09-24 16:17:50.777445+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ Sending latest PurchaserInfo to delegate.
2021-09-24 16:17:50.778858+0800 ZeroTime[345:4478] didReceiveUpdatedPurchaserInfo: <PurchaserInfo
 originalApplicationVersion: (null),
 latestExpirationDate: (null)
 activeEntitlements: {
},
 activeSubscriptions: {
},
 nonConsumablePurchases: {(
)},
 requestDate: 2021-09-24 08:12:16 +0000
 firstSeen: 2021-09-24 08:12:16 +0000,
originalAppUserId: $RCAnonymousID:cada631d501147efa13dd3dd44741b9c,
entitlements: {
},
> {
    originalAppUserId = "$RCAnonymousID:cada631d501147efa13dd3dd44741b9c";
    willRenew = No;
}
2021-09-24 16:17:50.778946+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ Delegate set
2021-09-24 16:17:51.070276+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ Vending PurchaserInfo from cache.
2021-09-24 16:17:51.070546+0800 ZeroTime[345:4478] purchaserInfo: <PurchaserInfo
 originalApplicationVersion: (null),
 latestExpirationDate: (null)
 activeEntitlements: {
},
 activeSubscriptions: {
},
 nonConsumablePurchases: {(
)},
 requestDate: 2021-09-24 08:12:16 +0000
 firstSeen: 2021-09-24 08:12:16 +0000,
originalAppUserId: $RCAnonymousID:cada631d501147efa13dd3dd44741b9c,
entitlements: {
},
>
2021-09-24 16:17:51.070670+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ applicationDidBecomeActive
2021-09-24 16:17:51.079287+0800 ZeroTime[345:4655] [Purchases] - DEBUG: ℹ️ There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c
2021-09-24 16:17:51.079500+0800 ZeroTime[345:4655] [Purchases] - DEBUG: ℹ️ API request started: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c
2021-09-24 16:17:51.081741+0800 ZeroTime[345:4655] [Purchases] - DEBUG: ℹ️ There's a request currently running and 0 requests left in the queue, queueing GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:17:52.576457+0800 ZeroTime[345:4653] [Purchases] - DEBUG: ℹ️ API request completed with status: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c 304
2021-09-24 16:17:52.580989+0800 ZeroTime[345:4653] [Purchases] - DEBUG: ℹ️ Serial request done: GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c, 1 requests left in the queue
2021-09-24 16:17:52.581240+0800 ZeroTime[345:4653] [Purchases] - DEBUG: ℹ️ Starting the next request in the queue, <RCHTTPRequest: httpMethod=GET
path=/subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
requestBody=(null)
headers={
    Authorization = "Bearer vXpx...";
}
retried=0
>
2021-09-24 16:17:52.582667+0800 ZeroTime[345:4653] [Purchases] - DEBUG: ℹ️ There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:17:52.582820+0800 ZeroTime[345:4653] [Purchases] - DEBUG: ℹ️ API request started: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:17:52.993356+0800 ZeroTime[345:4652] [Purchases] - DEBUG: ℹ️ API request completed with status: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c/offerings 304
2021-09-24 16:17:52.994226+0800 ZeroTime[345:4652] [Purchases] - DEBUG: ℹ️ Requesting products from the store with identifiers: {(
    "io.smudge.ZeroTime.SUB.Yearly",
    "io.smudge.ZeroTime.SUB.Monthly"
)}
2021-09-24 16:17:52.998017+0800 ZeroTime[345:4652] [Purchases] - DEBUG: ℹ️ Serial request done: GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings, 0 requests left in the queue
2021-09-24 16:17:53.062085+0800 ZeroTime[345:4652] [Purchases] - DEBUG: ℹ️ Products request finished.
2021-09-24 16:17:53.062174+0800 ZeroTime[345:4652] [Purchases] - DEBUG: 💰 Retrieved SKProducts: 
2021-09-24 16:17:53.062242+0800 ZeroTime[345:4652] [Purchases] - DEBUG: 💰 io.smudge.ZeroTime.SUB.Monthly - <SKProduct: 0x283cbdc10>
2021-09-24 16:17:53.062297+0800 ZeroTime[345:4652] [Purchases] - DEBUG: 💰 io.smudge.ZeroTime.SUB.Yearly - <SKProduct: 0x283cbd820>
2021-09-24 16:17:53.062345+0800 ZeroTime[345:4652] [Purchases] - DEBUG: ℹ️ 1 completion handlers waiting on products
2021-09-24 16:17:58.074549+0800 ZeroTime[345:4478] [Purchases] - DEBUG: ℹ️ Vending Offerings from cache

Failure:

2021-09-24 16:13:05.996820+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ Debug logging enabled
2021-09-24 16:13:05.996935+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ SDK Version - 3.12.4
2021-09-24 16:13:05.996978+0800 ZeroTime[503:10344] [Purchases] - DEBUG: 👤 Initial App User ID - (null)
2021-09-24 16:13:06.000895+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ Sending latest PurchaserInfo to delegate.
2021-09-24 16:13:06.001788+0800 ZeroTime[503:10344] didReceiveUpdatedPurchaserInfo: <PurchaserInfo
 originalApplicationVersion: (null),
 latestExpirationDate: (null)
 activeEntitlements: {
},
 activeSubscriptions: {
},
 nonConsumablePurchases: {(
)},
 requestDate: 2021-09-24 08:12:16 +0000
 firstSeen: 2021-09-24 08:12:16 +0000,
originalAppUserId: $RCAnonymousID:cada631d501147efa13dd3dd44741b9c,
entitlements: {
},
> {
    originalAppUserId = "$RCAnonymousID:cada631d501147efa13dd3dd44741b9c";
    willRenew = No;
}
2021-09-24 16:13:06.001865+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ Delegate set
2021-09-24 16:13:06.280716+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ Vending PurchaserInfo from cache.
2021-09-24 16:13:06.280911+0800 ZeroTime[503:10344] purchaserInfo: <PurchaserInfo
 originalApplicationVersion: (null),
 latestExpirationDate: (null)
 activeEntitlements: {
},
 activeSubscriptions: {
},
 nonConsumablePurchases: {(
)},
 requestDate: 2021-09-24 08:12:16 +0000
 firstSeen: 2021-09-24 08:12:16 +0000,
originalAppUserId: $RCAnonymousID:cada631d501147efa13dd3dd44741b9c,
entitlements: {
},
>
2021-09-24 16:13:06.281064+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ applicationDidBecomeActive
2021-09-24 16:13:06.287826+0800 ZeroTime[503:10402] [Purchases] - DEBUG: ℹ️ There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c
2021-09-24 16:13:06.287919+0800 ZeroTime[503:10402] [Purchases] - DEBUG: ℹ️ API request started: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c
2021-09-24 16:13:06.290863+0800 ZeroTime[503:10402] [Purchases] - DEBUG: ℹ️ There's a request currently running and 0 requests left in the queue, queueing GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:13:07.702613+0800 ZeroTime[503:10399] [Purchases] - DEBUG: ℹ️ API request completed with status: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c 304
2021-09-24 16:13:07.705077+0800 ZeroTime[503:10399] [Purchases] - DEBUG: ℹ️ Serial request done: GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c, 1 requests left in the queue
2021-09-24 16:13:07.705201+0800 ZeroTime[503:10399] [Purchases] - DEBUG: ℹ️ Starting the next request in the queue, <RCHTTPRequest: httpMethod=GET
path=/subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
requestBody=(null)
headers={
    Authorization = "Bearer vXpx...";
}
retried=0
>
2021-09-24 16:13:07.706122+0800 ZeroTime[503:10399] [Purchases] - DEBUG: ℹ️ There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:13:07.706187+0800 ZeroTime[503:10399] [Purchases] - DEBUG: ℹ️ API request started: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:13:08.111173+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ API request completed with status: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c/offerings 304
2021-09-24 16:13:08.111475+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ Requesting products from the store with identifiers: {(
    "io.smudge.ZeroTime.SUB.Yearly",
    "io.smudge.ZeroTime.SUB.Monthly"
)}
2021-09-24 16:13:08.111900+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ Serial request done: GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings, 0 requests left in the queue
2021-09-24 16:13:12.926935+0800 ZeroTime[503:10344] [Purchases] - DEBUG: ℹ️ No cached Offerings, fetching from network
2021-09-24 16:13:12.963155+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:13:12.963257+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ API request started: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c/offerings
2021-09-24 16:13:13.334771+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ API request completed with status: GET /v1/subscribers/$RCAnonymousID:cada631d501147efa13dd3dd44741b9c/offerings 304
2021-09-24 16:13:13.335316+0800 ZeroTime[503:10422] [Purchases] - DEBUG: ℹ️ Serial request done: GET /subscribers/$RCAnonymousID0X0P+0cada631d501147efa13dd3dd44741b9c/offerings, 0 requests left in the queue

Best answer by smudge.io

This issue is resolved in iOS 15.1.

View original

Did this post help you find an answer to your question?

Show first post

Page 2 / 2

Sinecan Anhammer
11 replies
3 years ago
October 20, 2021

Hi @Andy@smudge.io@Andrew Edwards

Is this maybe the fix that it is now showing the error that is caused by the test environment of Apple?

I’d be happy to try it but could not make it clear to me how I should try it out in my flutter app. If necessary, I can surely try if you would be able to provide step by step instructions on how I should use this branch in the app.

Andy
RevenueCat Staff
237 replies
3 years ago
October 20, 2021

@smudge.io I believe I should have been more clear as to what the fix does: it only introduces a timeout to ensure that the method at least returns a value. The real fix needs to come from Apple and go into the OS itself, so this timeout (plus some logs) is the best we can do in the meantime.

From the notes in the summary post:

We worked on a patch to address this behavior. While we cannot fix the iOS bug ourselves, we’ve introduced a timeout to the SKProductsRequest, which should ensure that getOfferingsreturns a value (an error, in the case of this bug).

We’ve heard some people report that this issue is fixed in iOS 15.1, but I can’t verify that since I don’t have an account that reproduces the issue in the first place.

Thanks for testing!!

Andy

Andy
RevenueCat Staff
237 replies
3 years ago
October 20, 2021

@Sinecan Anhammer

Is this maybe the fix that it is now showing the error that is caused by the test environment of Apple?

Exactly right, the fix is to actually produce an error instead of waiting forever for Apple to return a value.

I’d be happy to try it but could not make it clear to me how I should try it out in my flutter app. If necessary, I can surely try if you would be able to provide step by step instructions on how I should use this branch in the app.

I’m working on propagating this fix to Flutter, I will keep you posted. Thanks for offering to test!

Andy

smudge.io
Author
Helper
8 replies
Answer
3 years ago
October 27, 2021

This issue is resolved in iOS 15.1.

Anonymous
0 replies
3 years ago
October 28, 2021

Thanks for letting us know!

mehrozdev
New Member
2 replies
3 years ago
January 19, 2022

how to get latest receipt info in webhook after verification done by revenuecat

Buddy
New Member
3 replies
3 years ago
February 2, 2022

This issue is however happening to all of my production users who upgraded to iOS 15 (15.2 at the time of writing). The Purchases.shared.offerings callback has always worked fine in iOS 14 and can run without issue on iOS 15 in the simulator, but when in production or testing on physical device the callback doesn’t fire (ie, not a sandbox account issue). I noticed this when I checked the monthly report and all iOS purchases had stopped. Upgraded from v3.11 to v3.14 but still had the same issue. Could really use some help here if anyone was in the same situation and was able to resolve this problem. Thanks in advance.

Buddy
New Member
3 replies
3 years ago
February 5, 2022

My issue was caused by this. The IOS upgrade turned out to be a red herring.

Page 2 / 2

Reply

Cookie policy

We use cookies to enhance and personalize your experience. If you accept you agree to our full cookie policy. Learn more about our cookies.

Cookie settings

We use 3 different kinds of cookies. You can choose which cookies you want to accept. We need basic cookies to make this site work, therefore these are the minimum you can select. Learn more about our cookies.

Basic
Functional

Normal
Functional + analytics

Complete
Functional + analytics + social media + embedded videos

Reply

Related topics

Label titlesicon

API Reference > Cards

Is it possible to crate a card with more than one label?icon

make inclusion of labels, without losing an existing label without cardicon

Add Label using APIicon

Most helpful members this week

Sign up

‏‏‎ ‎

Log in to the Community

‏‏‎ ‎

Scanning file for viruses.

This file cannot be downloaded

Cookie policy

Cookie settings