Hello Jazmine,
complete logs here (I changed app name for private reasons).
Loggins from three sessions:
1. Device without StoreKit.configuration
2. Device without StoreKit.configuration (offerings called with 3 seconds delay, after purchaserInfo call)
3. Simulator with StoreKit.configuration
1. Device without StoreKit.configuration
2021-09-22 10:51:09.543838+0300 MyApplicationo1223:211830] ]Purchases] - DEBUG: Debug logging enabled
2021-09-22 10:51:09.543895+0300 MyApplicationc1223:211830] 1Purchases] - DEBUG: SDK Version - 3.12.3
2021-09-22 10:51:09.543926+0300 MyApplicationp1223:211830] 3Purchases] - DEBUG: Initial App User ID - (null)
2021-09-22 10:51:09.544257+0300 MyApplicationM1223:211830] 1Purchases] - DEBUG: Identifying App User ID: $RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8
2021-09-22 10:51:09.545365+0300 MyApplication01223:211830] oPurchases] - DEBUG: Delegate set
2021-09-22 10:51:09.545901+0300 MyApplication11223:211830] cPurchases] - DEBUG: No cached Offerings, fetching from network
2021-09-22 10:51:10.296298+0300 MyApplication91223:211830] pPurchases] - DEBUG: PurchaserInfo cache is stale, updating from network in foreground.
2021-09-22 10:51:10.296938+0300 MyApplication11223:211830] Purchases] - DEBUG: PurchaserInfo updated from network.
2021-09-22 10:51:10.305355+0300 MyApplication51223:212056] 3Purchases] - DEBUG: There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0b4bce757716f4bf49aa041b2ab4acfb8
2021-09-22 10:51:10.305764+0300 MyApplication 1223:212056] 6Purchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8
2021-09-22 10:51:10.308736+0300 MyApplication91223:211830] 3Purchases] - DEBUG: applicationDidBecomeActive
2021-09-22 10:51:10.308861+0300 MyApplication21223:212056] :Purchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8/offerings
2021-09-22 10:51:10.309374+0300 MyApplicationg1223:211830] 0Purchases] - DEBUG: PurchaserInfo cache is stale, updating from network in foreground.
2021-09-22 10:51:10.309784+0300 MyApplicationt1223:211830] 2Purchases] - DEBUG: PurchaserInfo updated from network.
2021-09-22 10:51:11.153081+0300 MyApplication>1223:212052] 0Purchases] - DEBUG: API request completed with status: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8/offerings 200
2021-09-22 10:51:11.155897+0300 MyApplicationp1223:212052] 0Purchases] - DEBUG: Requesting products from the store with identifiers: {(
"plan.standard.6months",
"plan.standard.1month",
"plan.advanced.1month",
"plan.standard.1year",
"plan.advanced.1year",
"plan.advanced.6months"
)}
2021-09-22 10:51:11.156244+0300 MyApplicationn1223:212052] API request completed with status: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8 201
2021-09-22 10:51:11.159228+0300 MyApplicationa1223:212052] oPurchases] - DEBUG: Sending latest PurchaserInfo to delegate.
.:]
2021-09-22 10:51:11.161288+0300 MyApplicationp1223:212052] /Purchases] - DEBUG: Serial request done: GET /subscribers/$RCAnonymousID0X0P+0b4bce757716f4bf49aa041b2ab4acfb8, 0 requests left in the queue
2. Device without StoreKit.configuration (offerings called with 3 seconds delay, after purchaserInfo call)
App start I called ‘purchaserInfo’, and after 3 seconds I call ‘offerings’ method with asyncAfter):
2021-09-22 11:00:14.943739+0300 MyApplicationf1266:217694] hPurchases] - DEBUG: Debug logging enabled
2021-09-22 11:00:14.943808+0300 MyApplication<1266:217694] Purchases] - DEBUG: SDK Version - 3.12.3
2021-09-22 11:00:14.943841+0300 MyApplication71266:217694] SPurchases] - DEBUG: Initial App User ID - (null)
2021-09-22 11:00:14.945795+0300 MyApplication<1266:217694] aPurchases] - DEBUG: Sending latest PurchaserInfo to delegate.
2021-09-22 11:00:14.945941+0300 MyApplicationS1266:217694] Purchases] - DEBUG: Delegate set
2021-09-22 11:00:14.946491+0300 MyApplicationp1266:217694] =Purchases] - DEBUG: Vending PurchaserInfo from cache.
s:]
2021-09-22 11:00:15.418394+0300 MyApplicationc1266:217694] mPurchases] - DEBUG: applicationDidBecomeActive
2021-09-22 11:00:15.425297+0300 MyApplication31266:217923] "Purchases] - DEBUG: There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+0b4bce757716f4bf49aa041b2ab4acfb8
2021-09-22 11:00:15.425380+0300 MyApplicationb1266:217923] oPurchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8
2021-09-22 11:00:15.426290+0300 MyApplication11266:217923] $Purchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8/offerings
2021-09-22 11:00:16.240433+0300 MyApplicationc1266:217928] nPurchases] - DEBUG: API request completed with status: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8 304
2021-09-22 11:00:16.243452+0300 MyApplicationT1266:217928] ePurchases] - DEBUG: Serial request done: GET /subscribers/$RCAnonymousID0X0P+0b4bce757716f4bf49aa041b2ab4acfb8, 0 requests left in the queue
2021-09-22 11:00:16.243855+0300 MyApplicationo1266:217928] cPurchases] - DEBUG: API request completed with status: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8/offerings 304
2021-09-22 11:00:16.244107+0300 MyApplication/1266:217928] sPurchases] - DEBUG: Requesting products from the store with identifiers: {(
"plan.standard.6months",
"plan.standard.1month",
"plan.advanced.1month",
"plan.standard.1year",
"plan.advanced.1year",
"plan.advanced.6months"
)}
2021-09-22 11:00:18.206205+0300 MyApplicationv1266:217694] No cached Offerings, fetching from network
2021-09-22 11:00:18.210244+0300 MyApplicationr1266:217934] /Purchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8/offerings
2021-09-22 11:00:18.385131+0300 MyApplicationg1266:217934] Purchases] - DEBUG: API request completed with status: GET /v1/subscribers/$RCAnonymousID:b4bce757716f4bf49aa041b2ab4acfb8/offerings 304
2021-09-22 11:00:51.249818+0300 MyApplication 1266:217694] hBackgroundTask] Background Task 5 ("SKProductsRequest"), was created over 30 seconds ago. In applications running in the background, this creates a risk of termination. Remember to call UIApplication.endBackgroundTask(_:) for your task in a timely manner to avoid this.
3. Simulator with StoreKit.configuration (only this option is working)
Third session (and only this working) running only on Simulator with StoreKit.configuration selected for target:
2021-09-22 11:37:56.292155+0300 MyApplication<18699:162713] nPurchases] - DEBUG: Debug logging enabled
2021-09-22 11:37:56.292280+0300 MyApplicationl18699:162713] =Purchases] - DEBUG: SDK Version - 3.12.3
2021-09-22 11:37:56.292407+0300 MyApplicationi18699:162713] "Purchases] - DEBUG: Initial App User ID - (null)
2021-09-22 11:37:56.295825+0300 MyApplicationa18699:162713] "Purchases] - DEBUG: Sending latest PurchaserInfo to delegate.
2021-09-22 11:37:56.296162+0300 MyApplication�18699:162713] /Purchases] - DEBUG: Delegate set
2021-09-22 11:37:56.297418+0300 MyApplication-18699:162713] nPurchases] - DEBUG: Vending PurchaserInfo from cache.
n"Advanced": <RCEntitlementInfo: identifier=Advanced,
isActive=0,
willRenew=1,
periodType=0,
latestPurchaseDate=2021-09-21 12:46:40 +0000,
originalPurchaseDate=2021-09-21 08:11:56 +0000,
expirationDate=2021-09-21 12:47:10 +0000,
store=0,
productIdentifier=plan.advanced.1month,
isSandbox=1,
unsubscribeDetectedAt=(null),
billingIssueDetectedAt=(null),
ownershipType=0,
>, "Standard": <RCEntitlementInfo: identifier=Standard,
isActive=0,
willRenew=1,
periodType=0,
latestPurchaseDate=2021-09-21 13:02:24 +0000,
originalPurchaseDate=2021-09-21 08:11:56 +0000,
expirationDate=2021-09-21 13:02:54 +0000,
store=0,
productIdentifier=plan.standard.1month,
isSandbox=1,
unsubscribeDetectedAt=(null),
billingIssueDetectedAt=(null),
ownershipType=0,
>]
2021-09-22 11:37:56.531422+0300 MyApplication=18699:162713] gPurchases] - DEBUG: applicationDidBecomeActive
2021-09-22 11:37:56.775216+0300 MyApplicationG18699:162713] gPurchases] - DEBUG: Offerings cache is stale, updating caches
2021-09-22 11:37:56.780389+0300 MyApplication>18699:163172] �Purchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:4eed6543ccef43368c5d190402988754/offerings
2021-09-22 11:37:56.781400+0300 MyApplicationi18699:163172] /Purchases] - DEBUG: There are no requests currently running, starting request GET /subscribers/$RCAnonymousID0X0P+04eed6543ccef43368c5d190402988754
2021-09-22 11:37:56.781489+0300 MyApplicationv18699:163172] oPurchases] - DEBUG: API request started: GET /v1/subscribers/$RCAnonymousID:4eed6543ccef43368c5d190402988754
2021-09-22 11:37:57.535855+0300 MyApplication.18699:163172] mPurchases] - DEBUG: API request completed with status: GET /v1/subscribers/$RCAnonymousID:4eed6543ccef43368c5d190402988754/offerings 304
2021-09-22 11:37:57.537104+0300 MyApplications18699:163172] pPurchases] - DEBUG: Requesting products from the store with identifiers: {(
"plan.standard.6months",
"plan.standard.1month",
"plan.advanced.1month",
"plan.standard.1year",
"plan.advanced.1year",
"plan.advanced.6months"
)}
2021-09-22 11:37:57.551464+0300 MyApplication>18699:163153] �Purchases] - DEBUG: Products request finished.
2021-09-22 11:37:57.551606+0300 MyApplicationi18699:163153] 6Purchases] - DEBUG: Retrieved SKProducts:
2021-09-22 11:37:57.551784+0300 MyApplication018699:163153] [Purchases] - DEBUG: plan.standard.1month - <SKProduct: 0x60000369f200>
2021-09-22 11:37:57.551946+0300 MyApplicationP18699:163153] BPurchases] - DEBUG: plan.advanced.1month - <SKProduct: 0x60000369f180>
2021-09-22 11:37:57.552088+0300 MyApplication]18699:163153] Purchases] - DEBUG: plan.standard.6months - <SKProduct: 0x60000369f120>
2021-09-22 11:37:57.552244+0300 MyApplication518699:163153] Purchases] - DEBUG: plan.advanced.6months - <SKProduct: 0x60000369f1a0>
2021-09-22 11:37:57.552354+0300 MyApplication618699:163153] ePurchases] - DEBUG: standard.1year - <SKProduct: 0x60000369f7f0>
2021-09-22 11:37:57.552468+0300 MyApplicationo18699:163153] ]Purchases] - DEBUG: advanced.1year - <SKProduct: 0x60000369f820>
2021-09-22 11:37:57.552617+0300 MyApplicationa18699:163153] 1Purchases] - DEBUG: 2 completion handlers waiting on products
2021-09-22 11:37:57.590789+0300 MyApplication318699:163153] oPurchases] - DEBUG: API request completed with status: GET /v1/subscribers/$RCAnonymousID:4eed6543ccef43368c5d190402988754 304
2021-09-22 11:37:57.593864+0300 MyApplication>18699:163153] �Purchases] - DEBUG: Serial request done: GET /subscribers/$RCAnonymousID0X0P+04eed6543ccef43368c5d190402988754, 0 requests left in the queue
2021-09-22 11:37:59.298466+0300 MyApplication"18699:162713] tPurchases] - DEBUG: Vending Offerings from cache