search

firebase / firebase-ios-sdk

Firebase SDK for Apple App Development
https://firebase.google.com
Apache License 2.0
5.68k stars 1.49k forks source link

[InAppMessaging] Not receiving test message on one device (while another device working) #6776

Closed iflyingboots closed 4 years ago

iflyingboots commented 4 years ago

Step 0: Are you in the right place?

[REQUIRED] Step 1: Describe your environment

[REQUIRED] Step 2: Describe the problem

Steps to reproduce:

  1. I have two iOS physical devices, one is iPhone 8 Plus and another one is iPhone 11.
  2. I was able to get the two installation IDs of the two devices, and iPhone 8 Plus could receive the test messages from Firebase In-app Message Web Console.
  3. However, when I tested on another device (iPhone 11), it couldn't receive any test messages with exact the same settings and code.

Logs on the non-working device:

2020-10-19 21:59:22.496580+0200 [APP_NAME][10533:8512235] 6.24.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-19 21:59:22.532262+0200 [APP_NAME][10533:8512233] 6.24.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-19 21:59:22.532328+0200 [APP_NAME][10533:8512233] 6.24.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-19 21:59:22.532743+0200 [APP_NAME][10533:8512202] [Firebase/Crashlytics] Version 4.1.1
2020-10-19 21:59:22.533091+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/Crashlytics][I-CLS000000] [Crashlytics] Running on iPhone12,1, 14.0.1 (18A393)
2020-10-19 21:59:22.533996+0200 [APP_NAME][10533:8512234] 6.24.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-19 21:59:22.534069+0200 [APP_NAME][10533:8512234] 6.24.0 - [Firebase/Analytics][I-ACS025028] AdMob and Firebase are in use in this app, but are not linked. For analytics data consistency and integrity, it is highly recommended that you link your AdMob app to the corresponding Firebase app and re-download the GoogleServices-Info.plist file. See https://support.google.com/admob/answer/6383165 for more information.
2020-10-19 21:59:22.545107+0200 [APP_NAME][10533:8512233] 6.24.0 - [Firebase/Crashlytics][I-CLS000000] [Crashlytics:Crash:Reports:Event] Registered Firebase Analytics event listener
2020-10-19 21:59:22.545208+0200 [APP_NAME][10533:8512233] 6.24.0 - [Firebase/Crashlytics][I-CLS000000] Automatic data collection is enabled.
2020-10-19 21:59:22.545349+0200 [APP_NAME][10533:8512233] 6.24.0 - [Firebase/Crashlytics][I-CLS000000] Unsent reports will be uploaded at startup
2020-10-19 21:59:22.547340+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessagingDisplay][I-FID100010] Got notification for kFIRAppReadyToConfigureSDKNotification. Setting display component on headless SDK.
2020-10-19 21:59:22.547615+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM290001] Setting a non-nil message display component
2020-10-19 21:59:22.547667+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM170001] Got notification for kFIRAppReadyToConfigureSDKNotification
2020-10-19 21:59:22.547723+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM170002] Auto bootstrap Firebase in-app messaging SDK
2020-10-19 21:59:22.547842+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM170005] Not running in simulator. Use regular clearcut uploading strategy.
2020-10-19 21:59:22.548620+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM310001] Persistent file path for activity log data is /var/mobile/Containers/Data/Application/5234DEA7-0818-476E-820F-19922794F67E/Library/Caches/firebase-iam-activity-log-cache
2020-10-19 21:59:22.549138+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM230001] Persistent file path for clearcut log records is /var/mobile/Containers/Data/Application/5234DEA7-0818-476E-820F-19922794F67E/Library/Caches/firebase-iam-clearcut-retry-records
2020-10-19 21:59:22.550941+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM230002] Loaded 0 clearcut log records from file in 0.000178 seconds
2020-10-19 21:59:22.551827+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM230005] Returning 0 clearcut retry records from popStillValidRecords
2020-10-19 21:59:22.552331+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM260001] FIRIAMClearcutUploader created with strategy as min wait time in seconds:300;max wait time in seconds:43200;failure backoff time in seconds:3600;batch send size:50
2020-10-19 21:59:22.552851+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM290001] SDK is in mode of Regular and has seen 4 fetches.
2020-10-19 21:59:22.557684+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/InAppMessaging][I-IAM300010] Detected 0 custom URL schemes from environment
2020-10-19 21:59:22.564503+0200 [APP_NAME][10533:8512235] 6.24.0 - [Firebase/Analytics][I-ACS031025] Analytics screen reporting is enabled. Call +[FIRAnalytics setScreenName:setScreenClass:] to set the screen name or override the default screen class name. To disable screen reporting, set the flag FirebaseScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-19 21:59:22.607049+0200 [APP_NAME][10533:8512240] 6.24.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-19 21:59:22.608370+0200 [APP_NAME][10533:8512242] 6.24.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60500000 started
2020-10-19 21:59:22.608962+0200 [APP_NAME][10533:8512242] 6.24.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-19 21:59:22.609273+0200 [APP_NAME][10533:8512242] 6.24.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-19 21:59:22.609329+0200 [APP_NAME][10533:8512242] 6.24.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-19 21:59:22.639384+0200 [APP_NAME][10533:8512235] 6.24.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-19 21:59:22.642891+0200 [APP_NAME][10533:8512202] <Google> To get test ads on this device, set: GADMobileAds.sharedInstance.requestConfiguration.testDeviceIdentifiers = @[ @"b492485b3b9a90aa60cdfe60352af332" ];
2020-10-19 21:59:22.643476+0200 [APP_NAME][10533:8512202] <Google> Note that test device identifiers may be reset when uninstalling and reinstalling the application.
2020-10-19 21:59:22.728093+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM260010] App foregrounded, FIRIAMClearcutUploader will seed next send
2020-10-19 21:59:22.728275+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM260006] Next upload attempt scheduled in 0 seconds
2020-10-19 21:59:22.728424+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM230005] Returning 0 clearcut retry records from popStillValidRecords
2020-10-19 21:59:22.728661+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM260007] No clearcut records to be uploaded
2020-10-19 21:59:22.728805+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM260010] App foregrounded, FIRIAMClearcutUploader will seed next send
2020-10-19 21:59:22.728873+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM260006] Next upload attempt scheduled in 0 seconds
2020-10-19 21:59:22.729085+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM230005] Returning 0 clearcut retry records from popStillValidRecords
2020-10-19 21:59:22.729139+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM260007] No clearcut records to be uploaded
2020-10-19 21:59:22.729179+0200 [APP_NAME][10533:8512246] 6.24.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-19 21:59:22.729340+0200 [APP_NAME][10533:8512246] 6.24.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-19 21:59:22.729386+0200 [APP_NAME][10533:8512246] 6.24.0 - [GoogleUtilities/AppDelegateSwizzler][I-SWZ001008] Successfully created App Delegate Proxy automatically. To disable the proxy, set the flag GoogleUtilitiesAppDelegateProxyEnabled to NO (Boolean) in the Info.plist
2020-10-19 21:59:22.729540+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM180002] Firebase In-App Messaging SDK version 0.20.2 finished startup in 0.116005 seconds with these settings: APIServer:firebaseinappmessaging.googleapis.com;ProjectNumber:811824953249; API_Key:AIzaSyBJxbY2AtUMt98gRq2GcdhPB0GVbkXz1ek;Clearcut Server:play.googleapis.com; Fetch Minimal Interval:86400 seconds; Activity Logger Max:100; Foreground Display Trigger Minimal Interval:86400 seconds;
Clearcut strategy:min wait time in seconds:300;max wait time in seconds:43200;failure backoff time in seconds:3600;batch send size:50;Global Firebase auto data collection enabled
2020-10-19 21:59:22.729574+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM150004] Persistent file path for fetch response data is /var/mobile/Containers/Data/Application/5234DEA7-0818-476E-820F-19922794F67E/Library/Caches/firebase-iam-messages-cache
2020-10-19 21:59:22.729611+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM150004] Persistent file path for fetch response data is /var/mobile/Containers/Data/Application/5234DEA7-0818-476E-820F-19922794F67E/Library/Caches/firebase-iam-messages-cache
2020-10-19 21:59:22.729652+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM150001] Loaded response from fetch storage successfully.
2020-10-19 21:59:22.729769+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM900014] No fetch epoch time detected in API response.
2020-10-19 21:59:22.729817+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM900002] 0 message definitions were parsed out successfully and 0 messages are discarded
2020-10-19 21:59:22.729952+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM160011] No analytics event trigger based messages, disable listening
2020-10-19 21:59:22.730006+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM140003] Stop observing Firebase Analytics events for display check.
2020-10-19 21:59:22.730100+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM160001] There are 0 test messages and 0 regular messages and 0 Firebase Analytics events to watch after resetting the message cache
2020-10-19 21:59:22.730399+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM180001] Message loading from fetch storage was done.
2020-10-19 21:59:22.731293+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM180014] No auto data collection enable setting entry detected.So no FIAM programmatic setting from the app.
2020-10-19 21:59:22.731365+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM180018] No programmatic or plist setting at FIAM level. Fallback to global Firebase level setting.
2020-10-19 21:59:22.731405+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM180008] Start SDK runtime components.
2020-10-19 21:59:22.731585+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM600002] Start observing app foreground notifications for message fetching.
2020-10-19 21:59:22.731748+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM140002] Start observing Firebase Analytics events for rendering messages.
2020-10-19 21:59:22.731859+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM180020] Start regular display flow for non-testing instance mode
2020-10-19 21:59:22.732348+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM500002] Start observing app foreground notifications for rendering messages.
2020-10-19 21:59:22.732615+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM400005] Interval time from last display is 1603137562.663377 seconds
2020-10-19 21:59:22.732666+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM400001] No appropriate in-app message detected for display.
2020-10-19 21:59:22.732907+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM700005] Interval from last time fetch is 696.019727 seconds
2020-10-19 21:59:22.732959+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM700008] Interval from last time fetch is 696.019727 seconds, smaller than fetch wait time 86400.000000
2020-10-19 21:59:22.733060+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/InAppMessaging][I-IAM700003] Only 696.019727 seconds from last fetch time. No action.
2020-10-19 21:59:22.733102+0200 [APP_NAME][10533:8512246] 6.24.0 - [Firebase/Analytics][I-ACS025026] Deferring to Firebase for event data collection
2020-10-19 21:59:22.918658+0200 [APP_NAME][10533:8512202] WF: === Starting WebFilter logging for process [APP_NAME]
2020-10-19 21:59:22.918694+0200 [APP_NAME][10533:8512202] WF: _userSettingsForUser mobile: {
    filterBlacklist =     (
    );
    filterWhitelist =     (
    );
    restrictWeb = 1;
    useContentFilter = 0;
    useContentFilterOverrides = 0;
    whitelistEnabled = 0;
}
2020-10-19 21:59:22.918724+0200 [APP_NAME][10533:8512202] WF: _WebFilterIsActive returning: NO
2020-10-19 21:59:22.978317+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-19 21:59:23.013472+0200 [APP_NAME][10533:8512233] 6.24.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 13179.73830699921
2020-10-19 21:59:23.030794+0200 [APP_NAME][10533:8512251] 6.24.0 - [Firebase/InAppMessaging][I-IAM190007] Successfully generated FIS token
2020-10-19 21:59:23.030883+0200 [APP_NAME][10533:8512251] 6.24.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-19 21:59:23.031257+0200 [APP_NAME][10533:8512251] 6.24.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-19 21:59:23.031399+0200 [APP_NAME][10533:8512251] 6.24.0 - [Firebase/InAppMessaging][I-IAM190009] Successfully in fetching both FID as eOh5cJBkvEDUru1XQeXkci and FIS token as eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJmaWQiOiJlT2g1Y0pCa3ZFRFVydTFYUWVYa2NpIiwicHJvamVjdE51bWJlciI6ODExODI0OTUzMjQ5LCJleHAiOjE2MDM3NDExMzMsImFwcElkIjoiMTo4MTE4MjQ5NTMyNDk6aW9zOjA5ODAwMmRlOThiZjZlYTY0ZmJkZTAifQ.AB2LPV8wRQIgB-8bC9_Hezn0ZqKm1Yqlrdi9s_MkeZT34tlc_KM21BYCIQC3U9Awkl2w_bil7vGQNn0tWQnBLhnXBUnPKz-hlsL3tg
2020-10-19 21:59:23.032148+0200 [APP_NAME][10533:8512251] 6.24.0 - [Firebase/InAppMessaging][I-IAM180017] Starting InAppMessaging runtime with Firebase Installation ID eOh5cJBkvEDUru1XQeXkci
2020-10-19 21:59:23.032527+0200 [APP_NAME][10533:8512251] 6.24.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, 2162594224745714558
2020-10-19 21:59:23.145877+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-19 21:59:23.146350+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-19 21:59:23.233327+0200 [APP_NAME][10533:8512240] 6.24.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-19 21:59:23.458952+0200 [APP_NAME][10533:8512243] 6.24.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-19 21:59:23.460519+0200 [APP_NAME][10533:8512243] 6.24.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-19 21:59:23.460560+0200 [APP_NAME][10533:8512243] 6.24.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-19 21:59:23.462177+0200 [APP_NAME][10533:8512243] 6.24.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, screen_view (_vs), {
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:23.462754+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: screen_view (_vs), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:23.716809+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: screen_view (_vs), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:23.747914+0200 [APP_NAME][10533:8512202] WF: _userSettingsForUser mobile: {
    filterBlacklist =     (
    );
    filterWhitelist =     (
    );
    restrictWeb = 1;
    useContentFilter = 0;
    useContentFilterOverrides = 0;
    whitelistEnabled = 0;
}
2020-10-19 21:59:23.747954+0200 [APP_NAME][10533:8512202] WF: _WebFilterIsActive returning: NO
2020-10-19 21:59:23.875003+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.1528949737548828
2020-10-19 21:59:23.875697+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: am, ad_query (_aq), {
    ad_event_id (_aeid) = -4179783338795622666;
    ga_event_origin (_o) = am;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:23.876436+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: ad_query (_aq), {
    ad_event_id (_aeid) = -4179783338795622666;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = am;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:24.049429+0200 [APP_NAME][10533:8512241] 6.24.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: ad_query (_aq), {
    ad_event_id (_aeid) = -4179783338795622666;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = am;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:24.227050+0200 [APP_NAME][10533:8512245] 6.24.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-19 21:59:24.227102+0200 [APP_NAME][10533:8512245] 6.24.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-19 21:59:24.281242+0200 [APP_NAME][10533:8512241] 6.24.0 - [Firebase/Analytics][I-ACS023105] Event is not subject to real-time event count daily limit. Marking an event as real-time. Event name, parameters: screen_view (_vs), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:24.285269+0200 [APP_NAME][10533:8512241] 6.24.0 - [Firebase/Analytics][I-ACS023105] Event is not subject to real-time event count daily limit. Marking an event as real-time. Event name, parameters: ad_query (_aq), {
    ad_event_id (_aeid) = -4179783338795622666;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = am;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:24.286700+0200 [APP_NAME][10533:8512245] 6.24.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 488
2020-10-19 21:59:24.286771+0200 [APP_NAME][10533:8512245] 6.24.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 20, 1603137563813
2020-10-19 21:59:24.475615+0200 [APP_NAME][10533:8512241] 6.24.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1603137564475, <APMPBMeasurementBatch: 0x281a2c7a0>
2020-10-19 21:59:24.478481+0200 [APP_NAME][10533:8512245] 6.24.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-19 21:59:24.530551+0200 [APP_NAME][10533:8512241] 6.24.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-19 21:59:24.569468+0200 [APP_NAME][10533:8512245] 6.24.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-19 21:59:24.574864+0200 [APP_NAME][10533:8512241] 6.24.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.2385399341583252
2020-10-19 21:59:24.768782+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-19 21:59:41.654310+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/InAppMessaging][I-IAM310004] App will become inactive, save activity logs
2020-10-19 21:59:41.654355+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/InAppMessaging][I-IAM310004] App will become inactive, save activity logs
2020-10-19 21:59:41.661617+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/InAppMessaging][I-IAM230003] Saving 0 clearcut log records into file is successful
2020-10-19 21:59:41.661818+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/InAppMessaging][I-IAM230003] Saving 0 clearcut log records into file is successful
2020-10-19 21:59:41.662053+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-19 21:59:41.662303+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-19 21:59:41.662337+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-19 21:59:41.730872+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 18363;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.730959+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: user_engagement (_e), {
    engagement_time_msec (_et) = 18363;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.843313+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 18363;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.845702+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.7523399591445923
2020-10-19 21:59:41.846345+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, app_background (_ab), {
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.847057+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: app_background (_ab), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.873454+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: app_background (_ab), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.894994+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023105] Event is not subject to real-time event count daily limit. Marking an event as real-time. Event name, parameters: user_engagement (_e), {
    engagement_time_msec (_et) = 18363;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.896963+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023105] Event is not subject to real-time event count daily limit. Marking an event as real-time. Event name, parameters: app_background (_ab), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 2162594224745714558;
}
2020-10-19 21:59:41.898184+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 108061
2020-10-19 21:59:41.898359+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 108061
2020-10-19 21:59:41.899798+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 478
2020-10-19 21:59:41.899888+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 21, 1603137581598
2020-10-19 21:59:41.902337+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1603137581902, <APMPBMeasurementBatch: 0x281a247c0>
2020-10-19 21:59:41.905441+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-19 21:59:41.964515+0200 [APP_NAME][10533:8512522] 6.24.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-19 21:59:42.032043+0200 [APP_NAME][10533:8512239] 6.24.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-19 21:59:42.039661+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-19 21:59:42.039966+0200 [APP_NAME][10533:8512253] 6.24.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 9
christibbs commented 4 years ago

Looking into this now.

christibbs commented 4 years ago

Just tested this on an iPhone 11 and it's working as expected.

From the logs, it looks like the test message was never fetched. Can you try relaunching the app after setting up the message for testing and getting the "Campaign ready for testing" toast on the Firebase console?

iflyingboots commented 4 years ago

Yes I could see the "Campaign ready for testing" message. I sent the test message from the console to both devices, as shown below:

Screen Shot 2020-10-20 at 9 34 11 PM

But only got the test message on one device, and got this log on my iPhone 11, which was the weird part:

 There are 0 test messages and 0 regular messages and 0 Firebase Analytics events to watch after resetting the message cache
antonjn commented 4 years ago

I've had this problem as well. I needed to remove and reinstall the app for the test message to appear.

iflyingboots commented 4 years ago

@antonjn Thanks for your reply. I've tried the uninstall-and-install trick but it doesn't work for me.

christibbs commented 4 years ago

Perhaps the issue is testing on multiple devices at a time... will try to duplicate.

antonjn commented 4 years ago

You know what. This issue might be related to mine https://github.com/firebase/firebase-ios-sdk/issues/6803. If it's an iOS 13+ device the window might have been shown but gotten a zero frame, hence blocking all other messages to show until the app is restarted, as you can't interact to dismiss that window. Easy to check in the view debugger of an attached device. If you find a zero size window showing the FIRIAM message view that might be the problem. It's not that likely that this is your problem as it's related to the app's foreground/background state during the message window presentation eg. if you get a message during a drag to show home/app switcher gesture (drag up from bottom a little, hold the gesture and wait for message to "appear").

christibbs commented 4 years ago

Yeah, I can't seem to reproduce this. Tried testing with multiple devices at once and it still worked. Was using this flow:

  1. Trigger test on device via console.
  2. Kill and relaunch app.

@iflyingboots (nice GitHub handle!) are you still running into this?

iflyingboots commented 4 years ago

@christibbs Hi, I just performed another test, same build, same devices, and, it worked! No idea what happened, but it's indeed working now.

Thank for your help!

christibbs commented 4 years ago

Great to hear.