Hello @John_Corbett !
I did logging in OnInitialized method through all items. First item is consumable - and metadata is Ok. Second item is - subscription and metadata is wrong (and also on 2022.09.14 18:26:07.825 it marked as an unavailable product).
While logging subscription - it falls from “try” to “catch” on this line: item.metadata.GetGoogleProductMetadata().introductoryPrice
For now i don’t understand - what can be the reason of such behaviour. Is it some Google restrictions which make product unavailable? Or is it code issue?
This time I used internal testing to speed up the things, but i had same errors in closed testing. ( It took over a week to pass the closed testing for this tiny game). Nevertheless I plan to release it there anyway.
Yes, it happens in any case.
Log
2022.09.14 18:26:07.631 3799 5509 Warn Unity <color=white><size=14> 09.14 18:26:07 #MyLog UGS initialized;
2022.09.14 18:26:07.631 3799 5509 Warn Unity Global:MyLog(String)
2022.09.14 18:26:07.631 3799 5509 Warn Unity <UGS_InitializationAsync>d__9:MoveNext()
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.ExecutionContext:RunInternal(ExecutionContext, ContextCallback, Object, Boolean)
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Runtime.CompilerServices.MoveNextRunner:Run()
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.Tasks.AwaitTaskContinuation:RunCallback(ContextCallback, Object, Task&)
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.Tasks.Task:FinishContinuations()
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.Tasks.Task1:TrySetResult(TResult) 2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Runtime.CompilerServices.AsyncTaskMethodBuilder1:SetResult(TResult)
2022.09.14 18:26:07.631 3799 5509 Warn Unity Unity.Services.Core.d__11:MoveNext()
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.ExecutionContext:RunInternal(ExecutionContext, ContextCallback, Object, Boolean)
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Runtime.CompilerServices.MoveNextRunner:Run()
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.Tasks.AwaitTaskContinuation:RunCallback(ContextCallback, Object, Task&)
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.Tasks.Task:FinishContinuations()
2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Threading.Tasks.Task1:TrySetResult(TResult) 2022.09.14 18:26:07.631 3799 5509 Warn Unity System.Runtime.CompilerServices.AsyncTaskMethodBuilder1:SetResu
2022.09.14 18:26:07.688 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog Purchasing initialized
2022.09.14 18:26:07.688 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.688 3799 5509 Warn Unity <UGS_InitializationAsync>d__9:MoveNext()
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.ExecutionContext:RunInternal(ExecutionContext, ContextCallback, Object, Boolean)
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Runtime.CompilerServices.MoveNextRunner:Run()
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.Tasks.AwaitTaskContinuation:RunCallback(ContextCallback, Object, Task&)
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.Tasks.Task:FinishContinuations()
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.Tasks.Task1:TrySetResult(TResult) 2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Runtime.CompilerServices.AsyncTaskMethodBuilder1:SetResult(TResult)
2022.09.14 18:26:07.688 3799 5509 Warn Unity Unity.Services.Core.d__11:MoveNext()
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.ExecutionContext:RunInternal(ExecutionContext, ContextCallback, Object, Boolean)
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Runtime.CompilerServices.MoveNextRunner:Run()
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.Tasks.AwaitTaskContinuation:RunCallback(ContextCallback, Object, Task&)
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.Tasks.Task:FinishContinuations()
2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Threading.Tasks.Task1:TrySetResult(TResult) 2022.09.14 18:26:07.688 3799 5509 Warn Unity System.Runtime.CompilerServices.AsyncTaskMethodBuilder 2022.09.14 18:26:07.825 3799 5509 Warn Unity Unavailable product com.companyname.gamename.sub3-com.companyname.gamename.sub3 2022.09.14 18:26:07.825 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:HasAvailableProductsToPurchase(Boolean) 2022.09.14 18:26:07.825 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.825 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.825 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.825 3799 5509 Warn Unity
2022.09.14 18:26:07.826 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog StoreController Initialized
2022.09.14 18:26:07.826 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.826 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.826 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.826 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1) 2022.09.14 18:26:07.826 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update() 2022.09.14 18:26:07.826 3799 5509 Warn Unity 2022.09.14 18:26:07.827 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog item 0: UnityEngine.Purchasing.Product </color></size> 2022.09.14 18:26:07.827 3799 5509 Warn Unity Global:MyGreenLog(String) 2022.09.14 18:26:07.827 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider) 2022.09.14 18:26:07.827 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.827 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.827 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.827 3799 5509 Warn Unity
2022.09.14 18:26:07.829 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata price: 1 || definition com.companyname.gamename.test || available to purchase: True || type: NonConsumable
2022.09.14 18:26:07.829 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.829 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.829 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.829 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1) 2022.09.14 18:26:07.829 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update() 2022.09.14 18:26:07.829 3799 5509 Warn Unity 2022.09.14 18:26:07.830 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata: UnityEngine.Purchasing.GoogleProductMetadata </color></size> 2022.09.14 18:26:07.830 3799 5509 Warn Unity Global:MyGreenLog(String) 2022.09.14 18:26:07.830 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider) 2022.09.14 18:26:07.830 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.830 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.830 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.830 3799 5509 Warn Unity
2022.09.14 18:26:07.832 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata isoCurrencyCode USD
2022.09.14 18:26:07.832 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.832 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.832 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.832 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1) 2022.09.14 18:26:07.832 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update() 2022.09.14 18:26:07.832 3799 5509 Warn Unity 2022.09.14 18:26:07.833 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata price string: 1,00 $ </color></size> 2022.09.14 18:26:07.833 3799 5509 Warn Unity Global:MyGreenLog(String) 2022.09.14 18:26:07.833 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider) 2022.09.14 18:26:07.833 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.833 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.833 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.833 3799 5509 Warn Unity
2022.09.14 18:26:07.834 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog google metadata:
2022.09.14 18:26:07.834 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.834 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.834 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.834 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1) 2022.09.14 18:26:07.834 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update() 2022.09.14 18:26:07.834 3799 5509 Warn Unity 2022.09.14 18:26:07.835 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog item 1: UnityEngine.Purchasing.Product </color></size> 2022.09.14 18:26:07.835 3799 5509 Warn Unity Global:MyGreenLog(String) 2022.09.14 18:26:07.835 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider) 2022.09.14 18:26:07.835 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.835 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.835 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.835 3799 5509 Warn Unity
2022.09.14 18:26:07.836 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata price: 0 || definition com.companyname.gamename.sub3 || available to purchase: False || type: Subscription
2022.09.14 18:26:07.836 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.836 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.836 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.836 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1) 2022.09.14 18:26:07.836 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update() 2022.09.14 18:26:07.836 3799 5509 Warn Unity 2022.09.14 18:26:07.838 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata: UnityEngine.Purchasing.ProductMetadata </color></size> 2022.09.14 18:26:07.838 3799 5509 Warn Unity Global:MyGreenLog(String) 2022.09.14 18:26:07.838 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider) 2022.09.14 18:26:07.838 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.838 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.838 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.838 3799 5509 Warn Unity
2022.09.14 18:26:07.839 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata isoCurrencyCode
2022.09.14 18:26:07.839 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.839 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.839 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.839 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1) 2022.09.14 18:26:07.839 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update() 2022.09.14 18:26:07.839 3799 5509 Warn Unity 2022.09.14 18:26:07.840 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog metadata price string: </color></size> 2022.09.14 18:26:07.840 3799 5509 Warn Unity Global:MyGreenLog(String) 2022.09.14 18:26:07.840 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider) 2022.09.14 18:26:07.840 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization() 2022.09.14 18:26:07.840 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List1)
2022.09.14 18:26:07.840 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()
2022.09.14 18:26:07.840 3799 5509 Warn Unity
2022.09.14 18:26:07.844 3799 5509 Warn Unity <size=14><color=lime> 09.14 18:26:07 #MyLog catch
2022.09.14 18:26:07.844 3799 5509 Warn Unity Global:MyGreenLog(String)
2022.09.14 18:26:07.844 3799 5509 Warn Unity Shop:UnityEngine.Purchasing.IStoreListener.OnInitialized(IStoreController, IExtensionProvider)
2022.09.14 18:26:07.844 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:CheckForInitialization()
2022.09.14 18:26:07.844 3799 5509 Warn Unity UnityEngine.Purchasing.PurchasingManager:OnProductsRetrieved(List`1)
2022.09.14 18:26:07.844 3799 5509 Warn Unity UnityEngine.Purchasing.Extension.UnityUtil:Update()