Search Unity

iap purchase failure reason unknown[ bad response -1002 ]

Discussion in 'Unity IAP' started by kaiyum, Nov 8, 2018.

  1. kaiyum

    kaiyum

    Joined:
    Nov 25, 2012
    Posts:
    686
    I implemented IAP into the game.
    My unity version: Unity LTS 2017.4.9f1
    Platform: Android
    This is development build.
    On a particular phone, I can not purchase and get unknown error. Relevant adb log is:
    Code (CSharp):
    1. 11-08 15:13:27.027: I/PowerCenterProvider(3174): call METHOD_GET_POWER_SUPPLY_INFO, quick_charge:false
    2. 11-08 15:13:27.027: I/PowerCenterProvider(3174): call METHOD_GET_POWER_SUPPLY_INFO, quick_charge:false
    3. 11-08 15:13:27.027: I/PowerCenterProvider(3174): call METHOD_GET_POWER_SUPPLY_INFO, quick_charge:false
    4. 11-08 15:13:28.831: V/BoostFramework(17858): BoostFramework() : mPerf = com.qualcomm.qti.Performance@51d5eab
    5. 11-08 15:13:29.005: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU3 [P:0] to CPU6 [P:1] (banned)
    6. 11-08 15:13:29.005: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU3 [P:0] to CPU4 [P:1] (banned)
    7. 11-08 15:13:29.006: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU1 [P:0] to CPU7 [P:1] (banned)
    8. 11-08 15:13:29.006: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU1 [P:0] to CPU5 [P:1] (banned)
    9. 11-08 15:13:29.006: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU0 [P:0] to CPU6 [P:1] (banned)
    10. 11-08 15:13:29.006: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU0 [P:0] to CPU4 [P:1] (banned)
    11. 11-08 15:13:29.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU2 [P:0] to CPU7 [P:1] (banned)
    12. 11-08 15:13:29.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU2 [P:0] to CPU5 [P:1] (banned)
    13. 11-08 15:13:29.049: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    14. 11-08 15:13:29.054: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    15. 11-08 15:13:29.637: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    16. 11-08 15:13:29.638: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    17. 11-08 15:13:29.638: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    18. 11-08 15:13:29.639: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    19. 11-08 15:13:31.500: I/Finsky(17965): [9702] com.google.android.finsky.bn.an.run(6): Stats for Executor: BlockingExecutor com.google.android.finsky.bn.ao@17804a5[Running, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 6]
    20. 11-08 15:13:31.501: I/Finsky(17965): [9702] com.google.android.finsky.bn.an.run(6): Stats for Executor: LightweightExecutor com.google.android.finsky.bn.ao@e22707a[Running, pool size = 4, active threads = 0, queued tasks = 0, completed tasks = 44]
    21. 11-08 15:13:31.729: I/Finsky(17965): [9702] com.google.android.finsky.bn.an.run(6): Stats for Executor: bgExecutor com.google.android.finsky.bn.ao@b4b402b[Running, pool size = 4, active threads = 0, queued tasks = 0, completed tasks = 7]
    22. 11-08 15:13:31.905: I/Finsky(17965): [9702] com.google.android.finsky.bn.an.run(6): Stats for Executor: StorageAwareDownloadServiceManagerSpaceChecker com.google.android.finsky.bn.ao@8d99c88[Running, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
    23. 11-08 15:13:32.060: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    24. 11-08 15:13:32.065: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    25. 11-08 15:13:32.065: D/WifiStateMachine(1544): updateCapabilities for config:Portblissfalse,false
    26. 11-08 15:13:32.639: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    27. 11-08 15:13:32.639: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    28. 11-08 15:13:32.640: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    29. 11-08 15:13:32.640: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    30. 11-08 15:13:34.006: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU3 [P:0] to CPU6 [P:1] (banned)
    31. 11-08 15:13:34.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU3 [P:0] to CPU4 [P:1] (banned)
    32. 11-08 15:13:34.008: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU1 [P:0] to CPU7 [P:1] (banned)
    33. 11-08 15:13:34.008: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU1 [P:0] to CPU5 [P:1] (banned)
    34. 11-08 15:13:34.008: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU0 [P:0] to CPU6 [P:1] (banned)
    35. 11-08 15:13:34.008: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU0 [P:0] to CPU4 [P:1] (banned)
    36. 11-08 15:13:34.008: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU2 [P:0] to CPU7 [P:1] (banned)
    37. 11-08 15:13:34.009: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU2 [P:0] to CPU5 [P:1] (banned)
    38. 11-08 15:13:35.070: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    39. 11-08 15:13:35.081: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    40. 11-08 15:13:35.081: D/WifiStateMachine(1544): updateCapabilities for config:Portblissfalse,false
    41. 11-08 15:13:35.640: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    42. 11-08 15:13:35.640: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    43. 11-08 15:13:35.641: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    44. 11-08 15:13:35.642: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    45. 11-08 15:13:38.089: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    46. 11-08 15:13:38.096: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    47. 11-08 15:13:38.638: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    48. 11-08 15:13:38.639: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    49. 11-08 15:13:38.639: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    50. 11-08 15:13:38.642: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    51. 11-08 15:13:39.006: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU3 [P:0] to CPU6 [P:1] (banned)
    52. 11-08 15:13:39.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU3 [P:0] to CPU4 [P:1] (banned)
    53. 11-08 15:13:39.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU1 [P:0] to CPU7 [P:1] (banned)
    54. 11-08 15:13:39.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU1 [P:0] to CPU5 [P:1] (banned)
    55. 11-08 15:13:39.008: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU0 [P:0] to CPU6 [P:1] (banned)
    56. 11-08 15:13:39.008: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU0 [P:0] to CPU4 [P:1] (banned)
    57. 11-08 15:13:39.008: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU2 [P:0] to CPU7 [P:1] (banned)
    58. 11-08 15:13:39.008: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU2 [P:0] to CPU5 [P:1] (banned)
    59. 11-08 15:13:41.103: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    60. 11-08 15:13:41.125: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    61. 11-08 15:13:41.126: D/WifiStateMachine(1544): updateCapabilities for config:Portblissfalse,false
    62. 11-08 15:13:41.640: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    63. 11-08 15:13:41.640: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    64. 11-08 15:13:41.640: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    65. 11-08 15:13:41.641: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    66. 11-08 15:13:42.088: I/DatabaseUtil(1858): writeApplyOperatorDetailInfo
    67. 11-08 15:13:44.005: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU3 [P:0] to CPU6 [P:1] (banned)
    68. 11-08 15:13:44.006: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU3 [P:0] to CPU4 [P:1] (banned)
    69. 11-08 15:13:44.006: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU1 [P:0] to CPU7 [P:1] (banned)
    70. 11-08 15:13:44.006: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU1 [P:0] to CPU5 [P:1] (banned)
    71. 11-08 15:13:44.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU0 [P:0] to CPU6 [P:1] (banned)
    72. 11-08 15:13:44.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU0 [P:0] to CPU4 [P:1] (banned)
    73. 11-08 15:13:44.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU2 [P:0] to CPU7 [P:1] (banned)
    74. 11-08 15:13:44.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU2 [P:0] to CPU5 [P:1] (banned)
    75. 11-08 15:13:44.133: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    76. 11-08 15:13:44.151: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    77. 11-08 15:13:44.152: D/WifiStateMachine(1544): updateCapabilities for config:Portblissfalse,false
    78. 11-08 15:13:44.640: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    79. 11-08 15:13:44.640: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    80. 11-08 15:13:44.642: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    81. 11-08 15:13:44.642: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    82. 11-08 15:13:46.077: I/Unity(17858): will try to buy 60 heart!
    83. 11-08 15:13:46.077: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    84. 11-08 15:13:46.077: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    85. 11-08 15:13:46.077: I/Unity(17858): UnityEngine.Logger:Log(LogType, Object)
    86. 11-08 15:13:46.077: I/Unity(17858): UnityEngine.Debug:Log(Object)
    87. 11-08 15:13:46.077: I/Unity(17858): SaveMePanelUI:<Start>m__D() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:247)
    88. 11-08 15:13:46.077: I/Unity(17858): <Waiter>c__Iterator0:MoveNext() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:264)
    89. 11-08 15:13:46.077: I/Unity(17858): UnityEngine.SetupCoroutine:InvokeMoveNext(IEnumerator, IntPtr) (at /Users/builduser/buildslave/unity/build/Runtime/Export/Coroutines.cs:17)
    90. 11-08 15:13:46.077: I/Unity(17858):
    91. 11-08 15:13:46.077: I/Unity(17858): (Filename: D Line: 0)
    92. 11-08 15:13:46.085: I/Unity(17858): buy call for productID: heart_60_prod
    93. 11-08 15:13:46.085: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    94. 11-08 15:13:46.085: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    95. 11-08 15:13:46.085: I/Unity(17858): UnityEngine.Logger:Log(LogType, Object)
    96. 11-08 15:13:46.085: I/Unity(17858): UnityEngine.Debug:Log(Object)
    97. 11-08 15:13:46.085: I/Unity(17858): SaveMePanelUI:BuyProduct(String) (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:322)
    98. 11-08 15:13:46.085: I/Unity(17858): SaveMePanelUI:<Start>m__D() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:249)
    99. 11-08 15:13:46.085: I/Unity(17858): <Waiter>c__Iterator0:MoveNext() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:264)
    100. 11-08 15:13:46.085: I/Unity(17858): UnityEngine.SetupCoroutine:InvokeMoveNext(IEnumerator, IntPtr) (at /Users/builduser/buildslave/unity/build/Runtime/Export/Coroutines.cs:17)
    101. 11-08 15:13:46.085: I/Unity(17858):
    102. 11-08 15:13:46.085: I/Unity(17858): (Filename: D Line: 0)
    103. 11-08 15:13:46.089: I/Unity(17858): Purchasing product asychronously: 'heart_60_prod'
    104. 11-08 15:13:46.089: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    105. 11-08 15:13:46.089: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    106. 11-08 15:13:46.089: I/Unity(17858): UnityEngine.Logger:Log(LogType, Object)
    107. 11-08 15:13:46.089: I/Unity(17858): UnityEngine.Debug:Log(Object)
    108. 11-08 15:13:46.089: I/Unity(17858): Portbliss.NativePlugins.InternalDoNotMessup.InAppPurchaseModule:BuyProduct(String, OnPurchase) (at D:\BMAD\Assets\GameAssets\PortblissNativePlugins\Scripts\Modules\InAppPurchaseModule.cs:166)
    109. 11-08 15:13:46.089: I/Unity(17858): SaveMePanelUI:BuyProduct(String) (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:323)
    110. 11-08 15:13:46.089: I/Unity(17858): SaveMePanelUI:<Start>m__D() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:249)
    111. 11-08 15:13:46.089: I/Unity(17858): <Waiter>c__Iterator0:MoveNext() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:264)
    112. 11-08 15:13:46.089: I/Unity(17858): UnityEngine.SetupCoroutine:InvokeMoveNext(IEnumerator, IntPtr) (at /Users/builduser/buildslave/unity/build/Runtime/Export/Coroutines.cs:17)
    113. 11-08 15:13:46.089: I/Unity(17858):
    114. 11-08 15:13:46.089: I/Unity(17858): (Filename: D Line: 0)
    115. 11-08 15:13:46.106: I/UnityIAP(17858): isUnityVrEnabled = false
    116. 11-08 15:13:46.109: I/UnityIAP(17858): onPurchaseProduct: heart_60_prod
    117. 11-08 15:13:46.109: I/UnityIAP(17858): ITEM TYPE:inapp
    118. 11-08 15:13:46.135: I/Timeline(17858): Timeline: Activity_launch_request time:106011883 intent:Intent { cmp=com.portbliss.breakingmad/com.unity.purchasing.googleplay.PurchaseActivity (has extras) }
    119. 11-08 15:13:46.158: I/ActivityManager(1544): START u0 {cmp=com.portbliss.breakingmad/com.unity.purchasing.googleplay.PurchaseActivity (has extras)} from uid 10467 on display 0
    120. 11-08 15:13:46.197: I/Unity(17858): windowFocusChanged: false
    121. 11-08 15:13:46.198: D/ActivityTrigger(1544): ActivityTrigger activityPauseTrigger
    122. 11-08 15:13:46.206: I/Unity(17858): onPause
    123. 11-08 15:13:46.247: I/Unity(17858): purchase({0}): heart_60_prod
    124. 11-08 15:13:46.247: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    125. 11-08 15:13:46.247: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    126. 11-08 15:13:46.247: I/Unity(17858): UnityEngine.Logger:Log(String, Object)
    127. 11-08 15:13:46.247: I/Unity(17858): UnityEngine.Purchasing.PurchasingManager:InitiatePurchase(Product, String) (at C:\buildslave\unity\build\Extensions\UnityPurchasing\Runtime\Purchasing\PurchasingManager.cs:61)
    128. 11-08 15:13:46.247: I/Unity(17858): UnityEngine.Purchasing.PurchasingManager:InitiatePurchase(Product) (at C:\buildslave\unity\build\Extensions\UnityPurchasing\Runtime\Purchasing\PurchasingManager.cs:38)
    129. 11-08 15:13:46.247: I/Unity(17858): Portbliss.NativePlugins.InternalDoNotMessup.InAppPurchaseModule:BuyProduct(String, OnPurchase) (at D:\BMAD\Assets\GameAssets\PortblissNativePlugins\Scripts\Modules\InAppPurchaseModule.cs:170)
    130. 11-08 15:13:46.247: I/Unity(17858): SaveMePanelUI:BuyProduct(String) (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:323)
    131. 11-08 15:13:46.247: I/Unity(17858): SaveMePanelUI:<Start>m__D() (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:249)
    132. 11-08 15:13:46.247: I/Unity(17858): <Waiter>c__Iterator0:MoveNext() (at D:\BMAD\Assets\GameAssets\Scripts\Manag
    133. 11-08 15:13:46.328: E/AudioFlinger(729): open /proc/17858/cmdline error
    134. 11-08 15:13:46.328: I/AudioFlinger(729): setAppName(), name=[], active=[0]
    135. 11-08 15:13:46.334: D/Unity(17858): Sensor :        Accelerometer ( 1) ; 0.002396 / 0.00s ; BMI120 Accelerometer / BOSCH
    136. 11-08 15:13:46.342: I/Timeline(1544): Timeline: App_transition_ready time:106012090
    137. 11-08 15:13:46.353: I/UnityIAP(17858): Creating purchase activity
    138. 11-08 15:13:46.354: I/UnityIAP(17858): oldSkuMetadata is null
    139. 11-08 15:13:46.354: D/ViewContentFactory(17858): initViewContentFetcherClass
    140. 11-08 15:13:46.354: I/ContentCatcher(17858): ViewContentFetcher : ViewContentFetcher
    141. 11-08 15:13:46.354: D/ViewContentFactory(17858): createInterceptor took 0ms
    142. 11-08 15:13:46.355: I/UnityIAP(17858): invoking callback
    143. 11-08 15:13:46.361: I/UnityIAP(17858): Constructing buy intent for heart_60_prod, item type: inapp
    144. 11-08 15:13:46.374: I/ContentCatcher(17858): Interceptor : Catcher list invalid for com.portbliss.breakingmad@com.unity.purchasing.googleplay.PurchaseActivity@47205853
    145. 11-08 15:13:46.374: I/ContentCatcher(17858): Interceptor : Get featureInfo from config pick_mode
    146. 11-08 15:13:46.398: D/GestureStubView(1751): resetRenderProperty: showGestureStub
    147. 11-08 15:13:46.398: D/GestureStubView(1751): showGestureStub
    148. 11-08 15:13:46.398: D/GestureStubView(1751): resetRenderProperty: showGestureStub
    149. 11-08 15:13:46.399: D/GestureStubView(1751): showGestureStub
    150. 11-08 15:13:46.400: I/Finsky(17965): [9751] com.google.android.finsky.billing.iab.ao.b(45): com.portbliss.breakingmad: Account from first account - [ClytJBVieR1OIxaEAmBC5ClNkEk]
    151. 11-08 15:13:46.413: I/Timeline(1544): Timeline: App_transition_ready time:106012161
    152. 11-08 15:13:46.416: I/Timeline(1544): Timeline: App_transition_ready time:106012164
    153. 11-08 15:13:46.418: I/Timeline(1544): Timeline: App_transition_ready time:106012166
    154. 11-08 15:13:46.419: I/OpenGLRenderer(17858): Initialized EGL, version 1.4
    155. 11-08 15:13:46.419: D/OpenGLRenderer(17858): Swap behavior 1
    156. 11-08 15:13:46.423: D/GestureStubView(1751): gatherTransparentRegion: need render w:54  h:1296
    157. 11-08 15:13:46.426: I/Timeline(1544): Timeline: App_transition_ready time:106012174
    158. 11-08 15:13:46.431: D/GestureStubView(1751): gatherTransparentRegion: need render w:54  h:1296
    159. 11-08 15:13:46.451: W/ResourceType(1751): No package identifier when getting name for resource number 0x00000000
    160. 11-08 15:13:46.453: I/art(1544): Background partial concurrent mark sweep GC freed 128904(6MB) AllocSpace objects, 49(1624KB) LOS objects, 26% free, 43MB/59MB, paused 3.392ms total 211.837ms
    161. 11-08 15:13:46.471: I/Timeline(1544): Timeline: App_transition_ready time:106012219
    162. 11-08 15:13:46.471: I/Timeline(1544): Timeline: App_transition_stopped time:106012219
    163. 11-08 15:13:46.474: I/ActivityManager(1544): Displayed com.portbliss.breakingmad/com.unity.purchasing.googleplay.PurchaseActivity: +137ms
    164. 11-08 15:13:46.474: I/Timeline(1544): Timeline: Activity_windows_visible id: ActivityRecord{95d28aa u0 com.portbliss.breakingmad/com.unity.purchasing.googleplay.PurchaseActivity t418} time:106012222
    165. 11-08 15:13:46.475: D/PowerKeeper.Event(3806): notifyActivityLaunchTime: com.portbliss.breakingmad/com.unity.purchasing.googleplay.PurchaseActivity totalTime: 137
    166. 11-08 15:13:46.476: D/EventBus(1751): [1751, u0] send(AppTransitionFinishedEvent)
    167. 11-08 15:13:46.476: D/EventBus(1751): [1751, u0]  -> ForcedResizableInfoActivityController [0xb41c692, P1] onBusEvent(AppTransitionFinishedEvent)
    168. 11-08 15:13:46.476: D/EventBus(1751): [1751, u0] onBusEvent(AppTransitionFinishedEvent) duration: 35 microseconds, avg: 1881
    169. 11-08 15:13:46.486: I/UnityIAP(17858): Launching buy intent for heart_60_prod. Request code: 999
    170. 11-08 15:13:46.486: I/WindowManagerService(1544): MIUILOG- Show when locked PermissionDenied pkg : com.portbliss.breakingmad uid : 10467
    171. 11-08 15:13:46.489: I/ActivityManager(1544): START u0 {act=com.google.android.finsky.phoenix.ACQUIRE dat=iabData:inapp:com.portbliss.breakingmad:heart_60_prod pkg=com.android.vending cmp=com.android.vending/com.google.android.finsky.billing.acquire.PhoenixAcquireActivity (has extras)} from uid 10145 on display 0
    172. 11-08 15:13:46.491: I/UnityIAP(17858): onActivityResult
    173. 11-08 15:13:46.492: E/IabHelper(17858): In-app billing error: Null data in IAB activity result.
    174. 11-08 15:13:46.493: I/UnityIAP(17858): onIabPurchaseFinished: false
    175. 11-08 15:13:46.493: I/UnityIAP(17858): Null data in IAB result (response: -1002:Bad response received)
    176. 11-08 15:13:46.493: I/UnityIAP(17858): Purchase response code:-1002
    177. 11-08 15:13:46.494: I/UnityIAP(17858): Received bad response, polling for successful purchases to investigate failure more deeply
    178. 11-08 15:13:46.496: I/UnityIAP(17858): invoking callback
    179. 11-08 15:13:46.496: I/UnityIAP(17858): Querying owned items, item type: inapp
    180. 11-08 15:13:46.496: I/UnityIAP(17858): Package name: com.portbliss.breakingmad
    181. 11-08 15:13:46.496: I/UnityIAP(17858): Calling getPurchases with continuation token: null
    182. 11-08 15:13:46.499: D/ActivityTrigger(1544): ActivityTrigger activityPauseTrigger
    183. 11-08 15:13:46.500: I/Finsky(17965): [9751] com.google.android.finsky.billing.iab.ao.b(45): com.portbliss.breakingmad: Account from first account - [ClytJBVieR1OIxaEAmBC5ClNkEk]
    184. 11-08 15:13:46.504: I/UnityIAP(17858): Owned items response: 0
    185. 11-08 15:13:46.504: I/UnityIAP(17858): Continuation token: null
    186. 11-08 15:13:46.507: V/AudioPolicyEffects(729): releaseOutputSessionEffects: no output processing was attached to this stream
    187. 11-08 15:13:46.535: I/AppActiveChecker(3806): uid:10467change to inactive
    188. 11-08 15:13:46.545: I/Timeline(1544): Timeline: App_transition_ready time:106012292
    189. 11-08 15:13:46.549: I/Unity(17858): onResume
    190. 11-08 15:13:46.551: D/GestureStubView(1751): resetRenderProperty: showGestureStub
    191. 11-08 15:13:46.551: D/GestureStubView(1751): showGestureStub
    192. 11-08 15:13:46.551: D/GestureStubView(1751): resetRenderProperty: showGestureStub
    193. 11-08 15:13:46.551: D/GestureStubView(1751): showGestureStub
    194. 11-08 15:13:46.557: I/Unity(17858): windowFocusChanged: true
    195. 11-08 15:13:46.566: I/WindowManagerService(1544): MIUILOG- Show when locked PermissionDenied pkg : com.portbliss.breakingmad uid : 10467
    196. 11-08 15:13:46.567: D/GestureStubView(1751): gatherTransparentRegion: need render w:54  h:1296
    197. 11-08 15:13:46.572: D/GestureStubView(1751): gatherTransparentRegion: need render w:54  h:1296
    198. 11-08 15:13:46.590: D/Unity(17858): Sensor :        Accelerometer ( 1) ; 0.002396 / 0.00s ; BMI120 Accelerometer / BOSCH
    199. 11-08 15:13:46.593: D/Unity(17858): Choreographer available: Enabling VSYNC timing
    200. 11-08 15:13:46.593: I/AudioPolicyManagerCustom(729): setting force DEEP buffer now
    201. 11-08 15:13:46.593: I/AudioPolicyManagerCustom(729): setting force DEEP buffer now
    202. 11-08 15:13:46.593: I/AudioPolicyManagerCustom(729): setting force DEEP buffer now
    203. 11-08 15:13:46.595: I/AudioPolicyManagerCustom(729): Direct PCM is disabled for this request
    204. 11-08 15:13:46.596: I/AudioPolicyManagerCustom(729): setting force DEEP buffer now
    205. 11-08 15:13:46.596: D/compress_voip(729): voice_extn_compress_voip_out_get_parameters: enter
    206. 11-08 15:13:46.597: D/AudioTrack(17858): Client defaulted notificationFrames to 706 for frameCount 2120
    207. 11-08 15:13:46.598: V/AudioPolicyEffects(729): addOutputSessionEffects(): no output processing needed for this stream
    208. 11-08 15:13:46.598: E/AudioFlinger(729): open /proc/17858/cmdline error
    209. 11-08 15:13:46.598: I/AudioFlinger(729): setAppName(), name=[], active=[1]
    210. 11-08 15:13:46.603: I/AppActiveChecker(3806): uid:10467 change to active
    211. 11-08 15:13:46.660: W/AD-PLUGIN-AppInstallActivateManager(17408): unRegisterSplashPackageUpdatedListener
    212. 11-08 15:13:46.670: I/Unity(17858): onPurchaseFailedEvent({0}): heart_60_prod
    213. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    214. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    215. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.Logger:Log(String, Object)
    216. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.Purchasing.PurchasingManager:OnPurchaseFailed(PurchaseFailureDescription) (at C:\buildslave\unity\build\Extensions\UnityPurchasing\Runtime\Purchasing\PurchasingManager.cs:137)
    217. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.Purchasing.JSONStore:OnPurchaseFailed(PurchaseFailureDescription, String)
    218. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.Purchasing.JSONStore:OnPurchaseFailed(String)
    219. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.Purchasing.<>c__DisplayClass6_0:<OnPurchaseFailed>b__0()
    220. 11-08 15:13:46.670: I/Unity(17858): UnityEngine.Purchasing.Extension.UnityUtil:Update()
    221. 11-08 15:13:46.670: I/Unity(17858):
    222. 11-08 15:13:46.670: I/Unity(17858): (Filename: C Line: 0)
    223. 11-08 15:13:46.688: I/Unity(17858): OnPurchaseFailed: FAIL. Product: 'heart_60_prod', PurchaseFailureReason: Unknown
    224. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    225. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    226. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.Logger:Log(LogType, Object)
    227. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.Debug:Log(Object)
    228. 11-08 15:13:46.688: I/Unity(17858): Portbliss.NativePlugins.InternalDoNotMessup.InAppPurchaseModule:UnityEngine.Purchasing.IStoreListener.OnPurchaseFailed(Product, PurchaseFailureReason) (at D:\BMAD\Assets\GameAssets\PortblissNativePlugins\Scripts\Modules\InAppPurchaseModule.cs:296)
    229. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.Purchasing.StoreListenerProxy:OnPurchaseFailed(Product, PurchaseFailureReason) (at C:\buildslave\unity\build\Extensions\UnityPurchasing\Runtime\Purchasing\StoreListenerProxy.cs:40)
    230. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.Purchasing.PurchasingManager:OnPurchaseFailed(PurchaseFailureDescription) (at C:\buildslave\unity\build\Extensions\UnityPurchasing\Runtime\Purchasing\PurchasingManager.cs:138)
    231. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.Purchasing.JSONStore:OnPurchaseFailed(PurchaseFailureDescription, String)
    232. 11-08 15:13:46.688: I/Unity(17858): UnityEngine.Purcha
    233. 11-08 15:13:46.694: I/Unity(17858): purchase failure!
    234. 11-08 15:13:46.694: I/Unity(17858): UnityEngine.DebugLogHandler:Internal_Log(LogType, String, Object)
    235. 11-08 15:13:46.694: I/Unity(17858): UnityEngine.DebugLogHandler:LogFormat(LogType, Object, String, Object[])
    236. 11-08 15:13:46.694: I/Unity(17858): UnityEngine.Logger:Log(LogType, Object)
    237. 11-08 15:13:46.694: I/Unity(17858): UnityEngine.Debug:Log(Object)
    238. 11-08 15:13:46.694: I/Unity(17858): <BuyProduct>c__AnonStorey2:<>m__0(IAPresult) (at D:\BMAD\Assets\GameAssets\Scripts\Managers\UI\SaveMePanelUI.cs:386)
    239. 11-08 15:13:46.694: I/Unity(17858): Portbliss.NativePlugins.InternalDoNotMessup.InAppPurchaseModule:FireEventAndCleanup(IAPresult) (at D:\BMAD\Assets\GameAssets\PortblissNativePlugins\Scripts\Modules\InAppPurchaseModule.cs:314)
    240. 11-08 15:13:46.694: I/Unity(17858): Portbliss.NativePlugins.InternalDoNotMessup.InAppPurchaseModule:UnityEngine.Purchasing.IStoreListener.OnPurchaseFailed(Product, PurchaseFailureReason) (at D:\BMAD\Assets\GameAssets\PortblissNativePlugins\Scripts\Modules\InAppPurchaseModule.cs:299)
    241. 11-08 15:13:46.694: I/Unity(17858): UnityEngine.Purchasing.StoreListenerProxy:OnPurchaseFailed(Product, PurchaseFailureReason) (at C:\buildslave\unity\build\Extensions\UnityPurchasing\Runtime\Purchasing\StoreListenerProxy.cs:40)
    242. 11-08 15:13:46.694: I/Unity(17858): UnityEngine.Purchasing.PurchasingManager:OnPur
    243. 11-08 15:13:46.725: I/art(1544): Background partial concurrent mark sweep GC freed 8673(407KB) AllocSpace objects, 0(0B) LOS objects, 26% free, 43MB/59MB, paused 3.146ms total 193.620ms
    244. 11-08 15:13:46.816: I/Timeline(1544): Timeline: App_transition_stopped time:106012564
    245. 11-08 15:13:46.821: D/EventBus(1751): [1751, u0] send(AppTransitionFinishedEvent)
    246. 11-08 15:13:46.821: D/EventBus(1751): [1751, u0]  -> ForcedResizableInfoActivityController [0xb41c692, P1] onBusEvent(AppTransitionFinishedEvent)
    247. 11-08 15:13:46.821: D/EventBus(1751): [1751, u0] onBusEvent(AppTransitionFinishedEvent) duration: 28 microseconds, avg: 1881
    248. 11-08 15:13:46.821: D/EventBus(1751): [1751, u0] send(AppTransitionFinishedEvent)
    249. 11-08 15:13:46.821: D/EventBus(1751): [1751, u0]  -> ForcedResizableInfoActivityController [0xb41c692, P1] onBusEvent(AppTransitionFinishedEvent)
    250. 11-08 15:13:46.821: D/EventBus(1751): [1751, u0] onBusEvent(AppTransitionFinishedEvent) duration: 17 microseconds, avg: 1881
    251. 11-08 15:13:46.989: W/AD-PLUGIN-AppInstallActivateManager(17408): unRegisterSplashPackageUpdatedListener
    252. 11-08 15:13:47.157: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    253. 11-08 15:13:47.162: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    254. 11-08 15:13:47.640: D/KeyguardUpdateMonitor(1751): received broadcast android.intent.action.BATTERY_CHANGED
    255. 11-08 15:13:47.641: D/KeyguardUpdateMonitor(1751): handleBatteryUpdate
    256. 11-08 15:13:47.641: I/BatteryInfoReceiver(3174): ACTION_BATTERY_CHANGED
    257. 11-08 15:13:47.642: W/QCNEJ(1958): |CORE| CNE received unexpected action: android.intent.action.BATTERY_CHANGED
    258. 11-08 15:13:49.005: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU3 [P:0] to CPU6 [P:1] (banned)
    259. 11-08 15:13:49.006: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU3 [P:0] to CPU4 [P:1] (banned)
    260. 11-08 15:13:49.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU1 [P:0] to CPU7 [P:1] (banned)
    261. 11-08 15:13:49.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU1 [P:0] to CPU5 [P:1] (banned)
    262. 11-08 15:13:49.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU0 [P:0] to CPU6 [P:1] (banned)
    263. 11-08 15:13:49.007: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU0 [P:0] to CPU4 [P:1] (banned)
    264. 11-08 15:13:49.007: I/MSM-irqbalance(848): Decided to move IRQ3 from CPU2 [P:0] to CPU7 [P:1] (banned)
    265. 11-08 15:13:49.008: I/MSM-irqbalance(848): Decided to move IRQ5 from CPU2 [P:0] to CPU5 [P:1] (banned)
    266. 11-08 15:13:50.168: D/wpa_supplicant(10970): wlan0: Control interface command 'SIGNAL_POLL'
    267. 11-08 15:13:50.172: D/wpa_supplicant(10970): CTRL-DEBUG: global_ctrl_sock-sendto: sock=8 sndbuf=212992 outq=0 send_len=48
    268. 11-08 15:13:50.173: D/WifiStateMachine(1544): updateCapabilities for config:Portblissfalse,false
    269. 01-01 00:00:00.000: E/(-1): Device disconnected
    270.  
    What am I missing at this particular phone? The user has been added to license testing.
     
    kaiyum, Nov 8, 2018
    #1

  2. JeffDUnity3D

    JeffDUnity3D

    Joined:
    May 2, 2017
    Posts:
    14,446
    On a particular phone, which phone? It works on other devices? We do not have phone model specific code to suggest.
     
    JeffDUnity3D, Nov 8, 2018
    #2

  3. kaiyum

    kaiyum

    Joined:
    Nov 25, 2012
    Posts:
    686
    Xiomi Mi Max 2
    Android Version: 7.1.1 NMF26F
    Model name: MDE40
    MIUI version: MIUI Global 9.5 | Stable
    (9.5.5.0(NDDMIFA))
    Kernel Version: 3.18.31-perf-g1088948
    Baseband Version: 2.3.c1-00100.1-0510_1834_b98fc3e
     
    kaiyum, Nov 9, 2018
    #3

  4. JeffDUnity3D

    JeffDUnity3D

    Joined:
    May 2, 2017
    Posts:
    14,446
    JeffDUnity3D, Nov 9, 2018
    #4
    kaiyum likes this.

  5. unityjingyao

    unityjingyao

    Unity Technologies

    Joined:
    Feb 20, 2017
    Posts:
    220
    Hi @kaiyum ,
    Please make sure that you've granted "Start in background" permission to Google Play Store.
    MIUI has strict rules on this permission.
     
    unityjingyao, Nov 14, 2018
    #5
    kaiyum likes this.

  6. kaiyum

    kaiyum

    Joined:
    Nov 25, 2012
    Posts:
    686
    Sorry for late response guys. I was hospitalized during these days. Anyway, thanks for the response and I will be trying the solutions. :):)
     
    kaiyum, Nov 19, 2018
    #6