Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Background notifications not received on first open. Have to hard close and re-open app. #6553

Closed
tomkelsey opened this issue Sep 24, 2020 · 41 comments · Fixed by #6669
Closed
Assignees

Comments

@tomkelsey
Copy link

[REQUIRED] Step 1: Describe your environment

  • Xcode version: 11.7
  • Firebase SDK version: 6.33.0
  • Firebase Component: Messaging
  • Component version: 4.7.0
  • Installation method: CocoaPods
  • iOS: 13.7

[REQUIRED] Step 2: Describe the problem

Background notifications not received on first app open. You have to hard close and re-open the app before they start working.

Steps to reproduce:

Install and deploy the iOS Messaging Quickstart app to TestFlight.

https://github.com/firebase/quickstart-ios/tree/master/messaging

The only change to the code I made was to copy the FCM token to the clipboard on tapping "Log Token"

This was my process:

  • Built and deployed the iOS quick start app to TestFlight.
  • Deleted any existing version of the iOS quick start app on my device.
  • Restarted device
  • Installed the app from TestFlight
  • Allowed notifications and tapped "Log Token" to copy the FCM Token to my clipboard
  • Backgrounded the app
  • Sent a test message (with just title and notification text set to "Test") to the FCM Token via Firebase console
  • No notification received.
  • Force quit the app.
  • Re-open the app.
  • Background the app.
  • Send another message via Firebase console to same FCM token
  • Notification received

When testing the app without TestFlight and just running from Xcode to my device I experienced inconsistent results. Sometimes the notification is received on first install/open and other times I have to quit the app and re-open it. However, via TestFlight it seems to be consistently not working following the steps above (I've repeated them 10 times without seeing any difference).

Happy to help with any extra information I can. As a heads up, I'm afraid I know very little about native development! I came across from this issue on the react-native-firebase repo - invertase/react-native-firebase#4299

@tomkelsey
Copy link
Author

Just some further information that may be relevant:

I tried updating to iOS 14 to see if it would resolve the issue.

I updated to iOS 14, uninstalled the existing quick start messaging app. Restarted my device and reinstalled from TestFlight and followed the above steps. This time the notification came through on first app open.

I thought the iOS 14 update may have fixed it. However, on repeating the above steps it it failed to work again.

So I guess either:

  • It's inconsistent (perhaps signifies some sort of race condition?)
  • The first install after updating to iOS14 had some impact (perhaps things are cached/kept around even after deleting/restarting the device that is interfering? No idea if that's a bonkers suggestion or not!).
@tomkelsey
Copy link
Author

tomkelsey commented Sep 24, 2020

Following a comment here: invertase/react-native-firebase#4299 (comment)

I tried setting the podfile versions as follows:

pod 'Firebase/Analytics', '~> 6.27.0'
pod 'Firebase/Messaging', '~> 6.27.0'

Unfortunately made no difference.

Another potentially relevant bit of extra information: when I was testing this in my own app using react-native-firebase using the APNs token direct always worked on first open. It was only sending via FCM that didn't. Perhaps this suggests it's an issue linking the APNs token to the FCM token?

@tomkelsey
Copy link
Author

I've also tried disabling swizzling (adding FirebaseAppDelegateProxyEnabled=NO into Info.plist) and updating AppDelegate.m didRegisterForRemoteNotificationsWithDeviceToken to include [FIRMessaging messaging].APNSToken = deviceToken; (following https://firebase.google.com/docs/cloud-messaging/ios/client)

Unfortunately still experiencing the same issue.

@ahmadworks
Copy link

Did you find any solution for this? I have the exact same issue.

@tomkelsey
Copy link
Author

Hi @ahmadworks - unfortunately not. I saw that you commented on invertase/react-native-firebase#4299 (comment) saying that downgrading react-native-firebase worked for you.

As far as I can see, the older version react-native-firebase uses version 6.27.0 of the iOS SDK. Above (#6553 (comment)) I commented to say I tried using that version and still experienced the same issue (directly with the ios SDK - not with react-native-firebase)

It'd be great if you could also try repeating the steps I outlined above with the quick start iOS app to see if you have the same issue (with both the latest version and the older version of the SDK). That way it'll hopefully confirm if it's a reproducible issue or some quirk with my specific setup.

@enasaz9
Copy link

enasaz9 commented Sep 29, 2020

same issue here, also I'm having issue receiving the normal push notification too,
the last working component was 4.6.2
so try this

pod 'Firebase/Messaging', '~> 6.32.2'

@ewfian
Copy link

ewfian commented Sep 29, 2020

I just tried the Version 6.33.0 with new api Messaging.token(completion:) and this issue was gone.

@ahmadworks
Copy link

I just tried the Version 6.33.0 with new api Messaging.token(completion:) and this issue was gone.

Are you sure? I tried it many times and it's not working, the newest version that worked for me is 6.29.0. I think you need to try it twice because it works sometimes.

@ewfian
Copy link

ewfian commented Sep 29, 2020

Are you sure? I tried it many times and it's not working, the newest version that worked for me is 6.29.0. I think you need to try it twice because it works sometimes.

Thank you for your reminder. I will try again many times today.

@ahmadworks
Copy link

Are you sure? I tried it many times and it's not working, the newest version that worked for me is 6.29.0. I think you need to try it twice because it works sometimes.

Thank you for your reminder. I will try again many times today.

I am building with 6.33.0 now to confirm it for you.
How did you try it? Just added $FirebaseSDKVersion = '6.33.0' to Podfile?

@ewfian
Copy link

ewfian commented Sep 29, 2020

How did you try it? Just added $FirebaseSDKVersion = '6.33.0' to Podfile?

You can refer this commit: leyserkids/cordova-plugin-firebase@124dd72

@ahmadworks
Copy link

So you edited RNFirebase files! I though you were able to update to 6.33.0 with completion just form Podfile.

@ewfian
Copy link

ewfian commented Sep 29, 2020

So you edited RNFirebase files! I though you were able to update to 6.33.0 with completion just form Podfile.

I am using Cordova. In addition to update pod package, I also did using the new api Messaging.token(completion:) to get fcm token.

@ahmadworks
Copy link

Sorry, I thought you are replying to my comment on Invertase Firebase plugin.

@morganchen12
Copy link
Contributor

Hey all, sorry for the slow response to this issue. Can you share some app logs on the first launch of your app (where silent notifications don't work) and subsequent launches (where notifications are received as expected)?

@ewfian
Copy link

ewfian commented Sep 30, 2020

@morganchen12 Here are some logs. With setLoggerLevel:FIRLoggerLevelMax
the difference with following two sample can refer this commit leyserkids/cordova-plugin-firebase@124dd72

btw. I has deleted some row not related to Firebase

0x01. Using Version 6.32.2 and use [FIRInstanceID instanceID] instanceIDWithHandler] to get token
The notification can not received

2020-09-30 20:18:31.922470+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: Starting Firebase plugin
2020-09-30 20:18:31.922529+0900 LeySerKids-P[2611:143418] [CDVTimer][firebaseplugin] 0.082016ms
2020-09-30 20:18:31.960228+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: GoogleService-Info.plist found, setup: [FIRApp configureWithOptions]
2020-09-30 20:18:31.966475+0900 LeySerKids-P[2611:143699] 6.32.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-09-30 20:18:32.014230+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-09-30 20:18:32.023645+0900 LeySerKids-P[2611:143691] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:32.026575+0900 LeySerKids-P[2611:143699] 6.32.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-09-30 20:18:32.030907+0900 LeySerKids-P[2611:143691] 6.32.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-09-30 20:18:32.097310+0900 LeySerKids-P[2611:143699] 6.32.0 - [Firebase/InstanceID][I-IID008009] Deleted checkin plist file.
2020-09-30 20:18:32.097485+0900 LeySerKids-P[2611:143699] 6.32.0 - [Firebase/InstanceID][I-IID007006] Checkin parameters: {
    checkin =     {
        iosbuild =         {
            model = "iPhone11,8";
            "os_version" = "IOS_14.0";
        };
        "last_checkin_msec" = 0;
        type = 2;
        "user_number" = 0;
    };
    digest = "";
    fragment = 0;
    id = 0;
    locale = "zh-Hans";
    "security_token" = 0;
    "time_zone" = "Asia/Tokyo";
    "user_serial_number" = 0;
    version = 2;
}
2020-09-30 20:18:32.097803+0900 LeySerKids-P[2611:143699] 6.32.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.6.2
2020-09-30 20:18:32.098283+0900 LeySerKids-P[2611:143699] 6.32.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-09-30 20:18:32.103013+0900 LeySerKids-P[2611:143775] 6.32.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-09-30 20:18:32.103802+0900 LeySerKids-P[2611:143775] 6.32.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
22020-09-30 20:18:32.144792+0900 LeySerKids-P[2611:143418] IAB.close() called but it was already closed.
2020-09-30 20:18:32.193109+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didReceiveRegistrationToken: dLtPcmPGAEN7t4f6MzIKwc:APA91bHGVBj-B3fAuRO-Jp2680Xodc1o1YNbsMa29Pq4GFWUZjJlz5fEN6DnK5bKSEi4Eh4ylpUPcPzUZRKOp2n4iwL-30iMSdCYvSR3d_M8-_YfgZdEVkQuRbtUnLDYgsQkrX7v-u_A
2020-09-30 20:18:32.251213+0900 LeySerKids-P[2611:143697] 6.32.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-09-30 20:18:32.251529+0900 LeySerKids-P[2611:143697] 6.32.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-09-30 20:18:32.251807+0900 LeySerKids-P[2611:143697] 6.32.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-09-30 20:18:32.251970+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID013002] Removed cached checkin preferences from Keychain because this is a fresh install.
2020-09-30 20:18:32.253081+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID013006] App reset detected. Will delete server registrations.
2020-09-30 20:18:32.253413+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:32.253678+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID014012] Invalidating cached token for 752538346208 (*) due to token is no longer fresh.
2020-09-30 20:18:32.253956+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID005004] Checkin is in progress
2020-09-30 20:18:32.286707+0900 LeySerKids-P[2611:143778] 6.32.0 - [Firebase/Installations][I-FIS001001] Sending request:  { URL: https://firebaseinstallations.googleapis.com/v1/projects/lk-kp-jp/installations/ }, body:{"appId":"*************","fid":"cO9j1h53hUQFkkIZz7NDDb","authVersion":"FIS_v2","sdkVersion":"i:1.7.0"}, headers: {
    "Content-Type" = "application/json";
    "X-Goog-Api-Key" = "*********************";
    "X-Ios-Bundle-Identifier" = "com.grapecity.*****************";
    "X-firebase-client" = "apple-platform/ios apple-sdk/18A390 fire-analytics/6.8.2 fire-fcm/4.6.2 fire-iid/4.7.0 fire-install/1.7.0 fire-ios/6.10.2 firebase-crashlytics/4.6.1 swift/true xcode/12A7209";
    "X-firebase-client-log-type" = 3;
}.
2020-09-30 20:18:32.435629+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:32.458652+0900 LeySerKids-P[2611:143418] FCM hasPermission: [object Object]
2020-09-30 20:18:32.666558+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:32.668327+0900 LeySerKids-P[2611:143689] 6.32.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-09-30 20:18:32.668414+0900 LeySerKids-P[2611:143689] 6.32.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-09-30 20:18:32.670629+0900 LeySerKids-P[2611:143778] 6.32.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 752538346208 (*) due to APNs token change.
2020-09-30 20:18:32.670963+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:32.671281+0900 LeySerKids-P[2611:143778] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:32.994214+0900 LeySerKids-P[2611:143771] 6.32.0 - [Firebase/InstanceID][I-IID007002] Invalid last checkin timestamp 2020-09-30 11:18:33 +0000 in future.
2020-09-30 20:18:32.995026+0900 LeySerKids-P[2611:143771] 6.32.0 - [Firebase/InstanceID][I-IID007003] Checkin successful with authId: 5279753475512558523, digest: 6GHjsCXLihTPikebR5vbBA==, lastCheckinTimestamp: 1601464712000
2020-09-30 20:18:32.995104+0900 LeySerKids-P[2611:143771] 6.32.0 - [Firebase/InstanceID][I-IID005004] Successfully got checkin credentials
2020-09-30 20:18:33.000230+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/InstanceID][I-IID008010] Checkin plist file is saved
2020-09-30 20:18:33.001574+0900 LeySerKids-P[2611:143771] 6.32.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "com.grapecity.*****************";
    class = genp;
    gena = "com.google.iid";
    svce = "com.google.iid.checkin";
}
2020-09-30 20:18:33.004951+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/InstanceID][I-IID005002] Checkin success.
2020-09-30 20:18:33.005020+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:33.005288+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 752538346208, scope: *
2020-09-30 20:18:33.191933+0900 LeySerKids-P[2611:143778] 6.32.0 - [Firebase/Installations][I-FIS001003] Request response received:  { URL: https://firebaseinstallations.googleapis.com/v1/projects/lk-kp-jp/installations/ }, error: (null), body: {
  "name": "projects/752538346208/installations/cO9j1h53hUQFkkIZz7NDDb",
  "fid": "cO9j1h53hUQFkkIZz7NDDb",
  "refreshToken": "2_5q2A2P6WGPCUWXmGg6Zp7xVu4jKRjvSDScCzJ0qRl6UNZc_ARx3k9nWXyCKovBIT",
  "authToken": {
    "token": "eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJmaWQiOiJjTzlqMWg1M2hVUUZra0laejdORERiIiwicHJvamVjdE51bWJlciI6NzUyNTM4MzQ2MjA4LCJleHAiOjE2MDIwNjk1MTMsImFwcElkIjoiMTo3NTI1MzgzNDYyMDg6aW9zOjJkZTU4ZjU5MTVlYWM1MGEifQ.AB2LPV8wRgIhAIaHOpKjccm66fuaDkUV2E_4Hl4ITKYuRAhEh9cuopx-AiEAjstqO9TVlCPZVpnlolxR5VnnEW_XxOPZHOP1IEcIXVg",
    "expiresIn": "604800s"
  }
}
.
2020-09-30 20:18:33.193088+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/Installations][I-FIS001005] Parsing server response for https://firebaseinstallations.googleapis.com/v1/projects/lk-kp-jp/installations/.
2020-09-30 20:18:33.195085+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/Installations][I-FIS001007] FIRInstallationsItem parsed successfully.
2020-09-30 20:18:33.214126+0900 LeySerKids-P[2611:143771] 6.32.0 - [Firebase/InstanceID][I-IID021000] Unregister request to https://fcmtoken.googleapis.com/register content: X-osv=14.0&device=4753012421744185450&plat=2&app=com.grapecity.*****************&app_ver=8.28.293&X-cliv=fiid-4.6.2&delete=true
2020-09-30 20:18:33.924020+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID014007] Successfully deleted GCM server registrations on app reset
2020-09-30 20:18:33.924754+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:33.926446+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:33.967997+0900 LeySerKids-P[2611:143778] 6.32.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0&device=5279753475512558523&X-scope=*&plat=2&app=com.grapecity.*****************&app_ver=8.28.293&X-cliv=fiid-4.6.2&sender=752538346208&X-subtype=752538346208&appid=cO9j1h53hUQFkkIZz7NDDb&gmp_app_id=************
2020-09-30 20:18:34.403362+0900 LeySerKids-P[2611:143771] 6.32.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "com.grapecity.*****************";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "752538346208:*";
}
2020-09-30 20:18:34.414845+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: cO9j1h53hUQFkkIZz7NDDb:APA91bFWe4RRka-e5tY5Izo2-JbnNGJXOtKA97CxXrGDnePsaV-755EVSnM8zrsdTUiA3ba0H9CEq3eQneswFHIRTKhFWsD8l0tYCQ4rKsjB8hD-o9TOt0MR-5Db7HJVXtiPL3VnpZ0P, authorizedEntity: 752538346208, scope:*
2020-09-30 20:18:34.414872+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didReceiveRegistrationToken: cO9j1h53hUQFkkIZz7NDDb:APA91bFWe4RRka-e5tY5Izo2-JbnNGJXOtKA97CxXrGDnePsaV-755EVSnM8zrsdTUiA3ba0H9CEq3eQneswFHIRTKhFWsD8l0tYCQ4rKsjB8hD-o9TOt0MR-5Db7HJVXtiPL3VnpZ0P
2020-09-30 20:18:34.417461+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID003016] Received APNS token while fetching default token. Refetching default token.
2020-09-30 20:18:34.419999+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:34.420400+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: didReceiveRegistrationToken: cO9j1h53hUQFkkIZz7NDDb:APA91bFWe4RRka-e5tY5Izo2-JbnNGJXOtKA97CxXrGDnePsaV-755EVSnM8zrsdTUiA3ba0H9CEq3eQneswFHIRTKhFWsD8l0tYCQ4rKsjB8hD-o9TOt0MR-5Db7HJVXtiPL3VnpZ0P
2020-09-30 20:18:34.421828+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:34.424055+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID003010] Successfully fetched default token.
2020-09-30 20:18:34.424297+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/InstanceID][I-IID003008] Got default token cO9j1h53hUQFkkIZz7NDDb:APA91bFWe4RRka-e5tY5Izo2-JbnNGJXOtKA97CxXrGDnePsaV-755EVSnM8zrsdTUiA3ba0H9CEq3eQneswFHIRTKhFWsD8l0tYCQ4rKsjB8hD-o9TOt0MR-5Db7HJVXtiPL3VnpZ0P
2020-09-30 20:18:38.459050+0900 LeySerKids-P[2611:143778] FirebasePlugin[native] LOG: requestAuthorizationWithOptions: granted=YES
2020-09-30 20:18:38.459688+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:38.462262+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: requestAuthorizationWithOptions: granted=YES
2020-09-30 20:18:38.462774+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:56.219851+0900 LeySerKids-P[2611:143697] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:56.219852+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:56.220506+0900 LeySerKids-P[2611:143690] 6.32.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:56.221420+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:59.096997+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:59.098523+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:18:59.098520+0900 LeySerKids-P[2611:143783] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:18:59.099411+0900 LeySerKids-P[2611:143783] 6.32.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:19:02.333267+0900 LeySerKids-P[2611:143783] 6.32.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:19:02.333317+0900 LeySerKids-P[2611:143418] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:19:02.334216+0900 LeySerKids-P[2611:143418] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0xb1c55e32 7231dc2b 65b7ca0e 3147d138 ... dec5c386 bad9c511 }
2020-09-30 20:19:02.334422+0900 LeySerKids-P[2611:143783] 6.32.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT

0x02. Using the Version 6.33.0 and to get token by [FIRMessaging messaging] tokenWithCompletion
Works well

2020-09-30 20:23:02.390520+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: Starting Firebase plugin
2020-09-30 20:23:02.390578+0900 LeySerKids-P[2641:145484] [CDVTimer][firebaseplugin] 0.077963ms
2020-09-30 20:23:02.430735+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: GoogleService-Info.plist found, setup: [FIRApp configureWithOptions]
2020-09-30 20:23:02.438372+0900 LeySerKids-P[2641:145746] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-09-30 20:23:02.438940+0900 LeySerKids-P[2641:145746] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-09-30 20:23:02.494738+0900 LeySerKids-P[2641:145818] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:02.494874+0900 LeySerKids-P[2641:145818] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-09-30 20:23:02.496907+0900 LeySerKids-P[2641:145818] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
22020-09-30 20:23:02.510345+0900 LeySerKids-P[2641:145743] 6.33.0 - [Firebase/InstanceID][I-IID008009] Deleted checkin plist file.
2020-09-30 20:23:02.581348+0900 LeySerKids-P[2641:145820] 6.33.0 - [Firebase/InstanceID][I-IID007006] Checkin parameters: {
    checkin =     {
        iosbuild =         {
            model = "iPhone11,8";
            "os_version" = "IOS_14.0";
        };
        "last_checkin_msec" = 0;
        type = 2;
        "user_number" = 0;
    };
    digest = "";
    fragment = 0;
    id = 0;
    locale = "zh-Hans";
    "security_token" = 0;
    "time_zone" = "Asia/Tokyo";
    "user_serial_number" = 0;
    version = 2;
}
2020-09-30 20:23:02.582371+0900 LeySerKids-P[2641:145820] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-09-30 20:23:02.582536+0900 LeySerKids-P[2641:145820] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-09-30 20:23:02.592931+0900 LeySerKids-P[2641:145820] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-09-30 20:23:02.593839+0900 LeySerKids-P[2641:145820] 6.33.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-09-30 20:23:02.632343+0900 LeySerKids-P[2641:145484] IAB.close() called but it was already closed.
2020-09-30 20:23:02.727884+0900 LeySerKids-P[2641:145745] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-09-30 20:23:02.728456+0900 LeySerKids-P[2641:145745] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-09-30 20:23:02.728504+0900 LeySerKids-P[2641:145745] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-09-30 20:23:02.728554+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/InstanceID][I-IID013002] Removed cached checkin preferences from Keychain because this is a fresh install.
2020-09-30 20:23:02.728659+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/InstanceID][I-IID013006] App reset detected. Will delete server registrations.
2020-09-30 20:23:02.728824+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:02.733912+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didReceiveRegistrationToken: cO9j1h53hUQFkkIZz7NDDb:APA91bFWe4RRka-e5tY5Izo2-JbnNGJXOtKA97CxXrGDnePsaV-755EVSnM8zrsdTUiA3ba0H9CEq3eQneswFHIRTKhFWsD8l0tYCQ4rKsjB8hD-o9TOt0MR-5Db7HJVXtiPL3VnpZ0P
2020-09-30 20:23:02.735173+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/InstanceID][I-IID014012] Invalidating cached token for 752538346208 (*) due to token is no longer fresh.
2020-09-30 20:23:02.736360+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/InstanceID][I-IID005004] Checkin is in progress
2020-09-30 20:23:02.767539+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/Installations][I-FIS001001] Sending request:  { URL: https://firebaseinstallations.googleapis.com/v1/projects/lk-kp-jp/installations/ }, body:{"appId":"************","fid":"cfH2l2SteUofs-JNqbptjx","authVersion":"FIS_v2","sdkVersion":"i:1.7.0"}, headers: {
    "Content-Type" = "application/json";
    "X-Goog-Api-Key" = "****************";
    "X-Ios-Bundle-Identifier" = "com.grapecity.*****************";
    "X-firebase-client" = "apple-platform/ios apple-sdk/18A390 fire-analytics/6.8.3 fire-fcm/4.7.0 fire-iid/4.7.0 fire-install/1.7.0 fire-ios/6.10.3 firebase-crashlytics/4.6.1 swift/true xcode/12A7209";
    "X-firebase-client-log-type" = 3;
}.
2020-09-30 20:23:02.960784+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/Messaging][I-FCM002022] APNS device token not set before retrieving FCM Token for Sender ID '752538346208'. Notifications to this FCM Token will not be delivered over APNS.Be sure to re-retrieve the FCM token once the APNS device token is set.
2020-09-30 20:23:02.962764+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID005004] Checkin is in progress
2020-09-30 20:23:02.971390+0900 LeySerKids-P[2641:145484] FCM hasPermission: [object Object]
2020-09-30 20:23:03.191036+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:03.192659+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-09-30 20:23:03.192754+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-09-30 20:23:03.195040+0900 LeySerKids-P[2641:145821] 6.33.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 752538346208 (*) due to APNs token change.
2020-09-30 20:23:03.195230+0900 LeySerKids-P[2641:145821] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:03.195566+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:03.451921+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/InstanceID][I-IID007002] Invalid last checkin timestamp 2020-09-30 11:23:03 +0000 in future.
2020-09-30 20:23:03.451990+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/InstanceID][I-IID007003] Checkin successful with authId: 5539136355670603314, digest: w7fbvHmSalRovolKTP/3mA==, lastCheckinTimestamp: 1601464983000
2020-09-30 20:23:03.452655+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/InstanceID][I-IID005004] Successfully got checkin credentials
2020-09-30 20:23:03.457152+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/InstanceID][I-IID008010] Checkin plist file is saved
2020-09-30 20:23:03.458176+0900 LeySerKids-P[2641:145817] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "com.grapecity.*****************";
    class = genp;
    gena = "com.google.iid";
    svce = "com.google.iid.checkin";
}
2020-09-30 20:23:03.460157+0900 LeySerKids-P[2641:145817] 6.33.0 - [Firebase/InstanceID][I-IID005002] Checkin success.
2020-09-30 20:23:03.460280+0900 LeySerKids-P[2641:145817] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:03.460557+0900 LeySerKids-P[2641:145817] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 752538346208, scope: *
2020-09-30 20:23:03.460686+0900 LeySerKids-P[2641:145817] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 752538346208, scope: *
2020-09-30 20:23:03.591096+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/Installations][I-FIS001003] Request response received:  { URL: https://firebaseinstallations.googleapis.com/v1/projects/lk-kp-jp/installations/ }, error: (null), body: {
  "name": "projects/752538346208/installations/cfH2l2SteUofs-JNqbptjx",
  "fid": "cfH2l2SteUofs-JNqbptjx",
  "refreshToken": "2_XR68jA-uqH0QNeo_y_KITUbqj_JeOkVCtsXQI4tKnDSfcswtfC0WJfJOlYuwDtO_",
  "authToken": {
    "token": "eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJmaWQiOiJjZkgybDJTdGVVb2ZzLUpOcWJwdGp4IiwicHJvamVjdE51bWJlciI6NzUyNTM4MzQ2MjA4LCJleHAiOjE2MDIwNjk3ODMsImFwcElkIjoiMTo3NTI1MzgzNDYyMDg6aW9zOjJkZTU4ZjU5MTVlYWM1MGEifQ.AB2LPV8wRgIhAKUA_dN12ukDsgl9cGQr8oeeiM4ZRHxzZj0HTS8OtBu5AiEA3ky8ZsH0RMId4yvANTA6DEsZSkbt_MlNqqI6pOhtVT0",
    "expiresIn": "604800s"
  }
}
.
2020-09-30 20:23:03.592564+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/Installations][I-FIS001005] Parsing server response for https://firebaseinstallations.googleapis.com/v1/projects/lk-kp-jp/installations/.
2020-09-30 20:23:03.593042+0900 LeySerKids-P[2641:145822] 6.33.0 - [Firebase/Installations][I-FIS001007] FIRInstallationsItem parsed successfully.
2020-09-30 20:23:03.610032+0900 LeySerKids-P[2641:145821] 6.33.0 - [Firebase/InstanceID][I-IID021000] Unregister request to https://fcmtoken.googleapis.com/register content: X-osv=14.0&device=5279753475512558523&plat=2&app=com.grapecity.*****************&app_ver=8.28.293&X-cliv=fiid-4.7.0&delete=true
2020-09-30 20:23:04.989124+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/InstanceID][I-IID014007] Successfully deleted GCM server registrations on app reset
2020-09-30 20:23:04.991015+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:04.991624+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:05.027613+0900 LeySerKids-P[2641:145821] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0&device=5539136355670603314&X-scope=*&plat=2&app=com.grapecity.*****************&app_ver=8.28.293&X-cliv=fiid-4.7.0&sender=752538346208&X-subtype=752538346208&appid=cfH2l2SteUofs-JNqbptjx&gmp_app_id=************
2020-09-30 20:23:05.331914+0900 LeySerKids-P[2641:145821] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:05.331975+0900 LeySerKids-P[2641:145821] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:05.343096+0900 LeySerKids-P[2641:145484] API error: <_UIKBCompatInputView: 0x105948570; frame = (0 0; 0 0); layer = > returned 0 width, assuming UIViewNoIntrinsicMetric
2020-09-30 20:23:05.343238+0900 LeySerKids-P[2641:145484] API error: <_UIKBCompatInputView: 0x105948570; frame = (0 0; 0 0); layer = > returned 0 width, assuming UIViewNoIntrinsicMetric
2020-09-30 20:23:05.400384+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "com.grapecity.*****************";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "752538346208:*";
}
2020-09-30 20:23:05.412890+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didReceiveRegistrationToken: cfH2l2SteUofs-JNqbptjx:APA91bF01yx2NQSh3rX6_CSSfy8_CnAWdRqBuh78Kg115lo-tPFQ7a9SVVCBlYMQyUt9HMa_fIoQr6xw2ioKDOIGyCHw_1LaIO7z5XLWDbeex5bST0WQCLqzQFt7bZf253g5nw1wfvUX
2020-09-30 20:23:05.418993+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: cfH2l2SteUofs-JNqbptjx:APA91bF01yx2NQSh3rX6_CSSfy8_CnAWdRqBuh78Kg115lo-tPFQ7a9SVVCBlYMQyUt9HMa_fIoQr6xw2ioKDOIGyCHw_1LaIO7z5XLWDbeex5bST0WQCLqzQFt7bZf253g5nw1wfvUX, authorizedEntity: 752538346208, scope:*
2020-09-30 20:23:05.420212+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID003016] Received APNS token while fetching default token. Refetching default token.
2020-09-30 20:23:05.420856+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:05.425879+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0&device=5539136355670603314&X-scope=*&plat=2&app=com.grapecity.*****************&app_ver=8.28.293&X-cliv=fiid-4.7.0&sender=752538346208&X-subtype=752538346208&appid=cfH2l2SteUofs-JNqbptjx&gmp_app_id=************
2020-09-30 20:23:05.425958+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didReceiveRegistrationToken: cfH2l2SteUofs-JNqbptjx:APA91bF01yx2NQSh3rX6_CSSfy8_CnAWdRqBuh78Kg115lo-tPFQ7a9SVVCBlYMQyUt9HMa_fIoQr6xw2ioKDOIGyCHw_1LaIO7z5XLWDbeex5bST0WQCLqzQFt7bZf253g5nw1wfvUX
2020-09-30 20:23:05.426876+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:05.426948+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/InstanceID][I-IID003010] Successfully fetched default token.
2020-09-30 20:23:05.427352+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/InstanceID][I-IID003008] Got default token cfH2l2SteUofs-JNqbptjx:APA91bF01yx2NQSh3rX6_CSSfy8_CnAWdRqBuh78Kg115lo-tPFQ7a9SVVCBlYMQyUt9HMa_fIoQr6xw2ioKDOIGyCHw_1LaIO7z5XLWDbeex5bST0WQCLqzQFt7bZf253g5nw1wfvUX
2020-09-30 20:23:05.697415+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: cfH2l2SteUofs-JNqbptjx:APA91bF01yx2NQSh3rX6_CSSfy8_CnAWdRqBuh78Kg115lo-tPFQ7a9SVVCBlYMQyUt9HMa_fIoQr6xw2ioKDOIGyCHw_1LaIO7z5XLWDbeex5bST0WQCLqzQFt7bZf253g5nw1wfvUX, authorizedEntity: 752538346208, scope:*
2020-09-30 20:23:08.941736+0900 LeySerKids-P[2641:145817] FirebasePlugin[native] LOG: requestAuthorizationWithOptions: granted=YES
2020-09-30 20:23:08.942244+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:08.943822+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: requestAuthorizationWithOptions: granted=YES
2020-09-30 20:23:08.944151+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:21.366847+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:21.366863+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:21.367523+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:21.367972+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 752538346208, scope: *
2020-09-30 20:23:21.368010+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:21.381468+0900 LeySerKids-P[2641:145823] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0&device=5539136355670603314&X-scope=*&plat=2&app=com.grapecity.*****************&app_ver=8.28.293&X-cliv=fiid-4.7.0&sender=752538346208&X-subtype=752538346208&appid=cfH2l2SteUofs-JNqbptjx&apns_token=s_382e87450d9b49eb0245e42f42aa7002536464d35ae91bd3495a0a9c51552240&gmp_app_id=************
2020-09-30 20:23:21.857273+0900 LeySerKids-P[2641:145884] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: cfH2l2SteUofs-JNqbptjx:APA91bF01yx2NQSh3rX6_CSSfy8_CnAWdRqBuh78Kg115lo-tPFQ7a9SVVCBlYMQyUt9HMa_fIoQr6xw2ioKDOIGyCHw_1LaIO7z5XLWDbeex5bST0WQCLqzQFt7bZf253g5nw1wfvUX, authorizedEntity: 752538346208, scope:*
2020-09-30 20:23:24.257200+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:24.258403+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:24.258526+0900 LeySerKids-P[2641:145745] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:24.259592+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:27.453743+0900 LeySerKids-P[2641:145819] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:27.453809+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:27.454474+0900 LeySerKids-P[2641:145885] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-09-30 20:23:27.456891+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didRegisterForRemoteNotificationsWithDeviceToken: {length = 32, bytes = 0x382e8745 0d9b49eb 0245e42f 42aa7002 ... 495a0a9c 51552240 }
2020-09-30 20:23:28.770348+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: willPresentNotification: {
    aps =     {
        alert =         {
            body = LeySerKids;
            title = LeySerKids;
        };
        badge = 59;
        "content-available" = 1;
        sound = default;
    };
    badge = 59;
    extras = "{\"category\":\"test\",\"noIncreaseBadge\":true}";
    "gcm.message_id" = 1601465008736867;
    "google.c.a.e" = 1;
}
2020-09-30 20:23:28.775355+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: willPresentNotification: {    aps =     {        alert =         {            body = LeySerKids;            title = LeySerKids;        };        badge = 59;        "content-available" = 1;        sound = default;    };    badge = 59;    extras = "{"category":"test","noIncreaseBadge":true}";    "gcm.message_id" = 1601465008736867;    "google.c.a.e" = 1;}
2020-09-30 20:23:28.778757+0900 LeySerKids-P[2641:145484] FirebasePlugin.onNotificationOpen
2020-09-30 20:23:28.779356+0900 LeySerKids-P[2641:145484] {"extras":"{\"category\":\"test\",\"noIncreaseBadge\":true}","aps":{"content-available":1,"alert":{"title":"LeySerKids","body":"LeySerKids"},"badge":59,"sound":"default"},"google.c.a.e":"1","badge":"59","gcm.message_id":"1601465008736867"}
2020-09-30 20:23:28.791284+0900 LeySerKids-P[2641:145885] 6.33.0 - [Firebase/Messaging][I-FCM018001] Device has capacity 60986 MB with 37464 MB free.
2020-09-30 20:23:28.792062+0900 LeySerKids-P[2641:145885] 6.33.0 - [Firebase/Messaging][I-FCM006000] Received message missing local start time, dropped.
2020-09-30 20:23:28.792878+0900 LeySerKids-P[2641:145885] 6.33.0 - [Firebase/Messaging][I-FCM019005] FIRMessagingAnalytics: Sending event: _nf params: {
}
2020-09-30 20:23:28.794508+0900 LeySerKids-P[2641:145885] 6.33.0 - [Firebase/Messaging][I-FCM006000] Received message missing local start time, dropped.
2020-09-30 20:23:28.794988+0900 LeySerKids-P[2641:145885] 6.33.0 - [Firebase/Messaging][I-FCM016004] Added sync message to cache: 1601465008736867
2020-09-30 20:23:28.796038+0900 LeySerKids-P[2641:145484] FirebasePlugin[native] LOG: didReceiveRemoteNotification: {
    aps =     {
        alert =         {
            body = LeySerKids;
            title = LeySerKids;
        };
        badge = 59;
        "content-available" = 1;
        sound = default;
    };
    badge = 59;
    extras = "{\"category\":\"test\",\"noIncreaseBadge\":true}";
    "gcm.message_id" = 1601465008736867;
    "google.c.a.e" = 1;
}
2020-09-30 20:23:28.803846+0900 LeySerKids-P[2641:145484] FirebasePlugin[native]: didReceiveRemoteNotification: {    aps =     {        alert =         {            body = LeySerKids;            title = LeySerKids;        };        badge = 59;        "content-available" = 1;        sound = default;    };    badge = 59;    extras = "{"category":"test","noIncreaseBadge":true}";    "gcm.message_id" = 1601465008736867;    "google.c.a.e" = 1;}
2020-09-30 20:23:28.804020+0900 LeySerKids-P[2641:145484] FirebasePlugin.onNotificationOpen
2020-09-30 20:23:28.804085+0900 LeySerKids-P[2641:145484] {"extras":"{\"category\":\"test\",\"noIncreaseBadge\":true}","aps":{"content-available":1,"alert":{"title":"LeySerKids","body":"LeySerKids"},"badge":59,"sound":"default"},"google.c.a.e":"1","badge":"59","gcm.message_id":"1601465008736867"}
@morganchen12
Copy link
Contributor

Thanks for the update @ewfian!

@tomkelsey @ahmadworks @enasaz9 can you try updating to 6.33.0 and using the new Messaging API as well?

@tomkelsey
Copy link
Author

Hi @morganchen12 - I've tried using the quickstart with version 6.33.0 but don't believe I'm using the new API (I was just using the code from the quickstart repository).

I'm afraid I don't know objective c nor how everything pieces together. If you can let me know what changes I'd need to make to the quickstart example to use the new API I'm happy to test and see if it resolves the issue 👍

Also re: logs. Is there a way to get device logs from a testflight device? As that seems to be the environment where I can most reliably reproduce the issue.

Failing that I can try and recreate via a device connected directly to my machine but I'm not sure how to access the logs after hard closing the app and then re-opening?

@tomkelsey
Copy link
Author

tomkelsey commented Oct 1, 2020

Following on from my comment above, I saw this PR firebase/quickstart-ios#1053 on using the new messaging API and implemented the changes.

Unfortunately it didn't change the behaviour I outlined above. I still seemed to get the same results on testing multiple times via TestFlight.

@tomkelsey
Copy link
Author

I'm not sure I had the right level of debugging enabled, but I've included a bunch of app logs for it both working and not working running on a device attached to my computer (not via TestFlight) using the old API.

In each case I opened the app, tapped Log Token, then backgrounded the app and tried to send a push notification via the Firebase console directly to the FCM token.

Old API, worked:

2020-10-01 15:46:52.483777+0100 MessagingExample[1162:303171] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-01 15:46:53.020186+0100 MessagingExample[1162:303162] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-01 15:46:53.021383+0100 MessagingExample[1162:303162] 6.33.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-01 15:46:53.092820+0100 MessagingExample[1162:303063] FCM registration token: fQYKkUU0ukEVjonRu22uKL:APA91bHXwgcjGjvCU2guWKkMih1M4mdd9fN5fNMaCZdGYnUqWhOSHtep_UNoO1bhx1WPFhYEzIyaybpMcbVJHJmp1bK9cxOgN9Y1ihxtDdKH4qLYAl7d_SyhI6-QuD9FgiVCCC2IhPDn
2020-10-01 15:46:53.242087+0100 MessagingExample[1162:303167] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-01 15:46:53.389902+0100 MessagingExample[1162:303167] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-01 15:46:53.390671+0100 MessagingExample[1162:303167] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-01 15:47:05.940328+0100 MessagingExample[1162:303063] Local FCM registration token: fQYKkUU0ukEVjonRu22uKL:APA91bHXwgcjGjvCU2guWKkMih1M4mdd9fN5fNMaCZdGYnUqWhOSHtep_UNoO1bhx1WPFhYEzIyaybpMcbVJHJmp1bK9cxOgN9Y1ihxtDdKH4qLYAl7d_SyhI6-QuD9FgiVCCC2IhPDn
2020-10-01 15:47:05.961332+0100 MessagingExample[1162:303063] Returning local object of class NSString
2020-10-01 15:47:05.983336+0100 MessagingExample[1162:303063] Remote instance ID token: fQYKkUU0ukEVjonRu22uKL:APA91bHXwgcjGjvCU2guWKkMih1M4mdd9fN5fNMaCZdGYnUqWhOSHtep_UNoO1bhx1WPFhYEzIyaybpMcbVJHJmp1bK9cxOgN9Y1ihxtDdKH4qLYAl7d_SyhI6-QuD9FgiVCCC2IhPDn
2020-10-01 15:47:07.160309+0100 MessagingExample[1162:303178] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration
2020-10-01 15:47:07.292115+0100 MessagingExample[1162:303063] Local FCM registration token: fQYKkUU0ukEVjonRu22uKL:APA91bHXwgcjGjvCU2guWKkMih1M4mdd9fN5fNMaCZdGYnUqWhOSHtep_UNoO1bhx1WPFhYEzIyaybpMcbVJHJmp1bK9cxOgN9Y1ihxtDdKH4qLYAl7d_SyhI6-QuD9FgiVCCC2IhPDn
2020-10-01 15:47:07.302965+0100 MessagingExample[1162:303063] Returning local object of class NSString
2020-10-01 15:47:07.321058+0100 MessagingExample[1162:303173] PBItemCollectionServicer connection disconnected.
2020-10-01 15:47:07.324093+0100 MessagingExample[1162:303063] Remote instance ID token: fQYKkUU0ukEVjonRu22uKL:APA91bHXwgcjGjvCU2guWKkMih1M4mdd9fN5fNMaCZdGYnUqWhOSHtep_UNoO1bhx1WPFhYEzIyaybpMcbVJHJmp1bK9cxOgN9Y1ihxtDdKH4qLYAl7d_SyhI6-QuD9FgiVCCC2IhPDn
2020-10-01 15:47:20.621895+0100 MessagingExample[1162:303177] PBItemCollectionServicer connection disconnected.

Old API, worked:

2020-10-01 15:57:08.446849+0100 MessagingExample[365:8023] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-01 15:57:08.447245+0100 MessagingExample[365:8023] 6.33.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-01 15:57:08.449262+0100 MessagingExample[365:8027] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-01 15:57:08.880253+0100 MessagingExample[365:8029] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-01 15:57:09.023276+0100 MessagingExample[365:8029] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-01 15:57:09.024147+0100 MessagingExample[365:8029] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-01 15:57:09.448156+0100 MessagingExample[365:7807] FCM registration token: d-_MYE2Sjks1jtzVkUgL9N:APA91bES_tD6sRE5GChBznQg9c0DN4FOTEQaLIds2IX4kIv5KHLkrf3cHC0a30yazGn0SpG6YrwKtbfqZfCyW97vgp1eN4m4SNdvuCnQvY-lRARgwdNcGsKIq29sG1hI17kdrf25j4cN
2020-10-01 15:57:11.636569+0100 MessagingExample[365:7807] Local FCM registration token: d-_MYE2Sjks1jtzVkUgL9N:APA91bES_tD6sRE5GChBznQg9c0DN4FOTEQaLIds2IX4kIv5KHLkrf3cHC0a30yazGn0SpG6YrwKtbfqZfCyW97vgp1eN4m4SNdvuCnQvY-lRARgwdNcGsKIq29sG1hI17kdrf25j4cN
2020-10-01 15:57:11.655281+0100 MessagingExample[365:7807] Returning local object of class NSString
2020-10-01 15:57:11.678480+0100 MessagingExample[365:7807] Remote instance ID token: d-_MYE2Sjks1jtzVkUgL9N:APA91bES_tD6sRE5GChBznQg9c0DN4FOTEQaLIds2IX4kIv5KHLkrf3cHC0a30yazGn0SpG6YrwKtbfqZfCyW97vgp1eN4m4SNdvuCnQvY-lRARgwdNcGsKIq29sG1hI17kdrf25j4cN
2020-10-01 15:57:23.573220+0100 MessagingExample[365:8027] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration
2020-10-01 15:57:29.908614+0100 MessagingExample[365:8024] PBItemCollectionServicer connection disconnected.

Old API, worked:

2020-10-01 15:58:18.450529+0100 MessagingExample[371:8996] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-01 15:58:18.635829+0100 MessagingExample[371:8988] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-01 15:58:18.636401+0100 MessagingExample[371:8988] 6.33.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-01 15:58:19.002369+0100 MessagingExample[371:9004] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-01 15:58:19.186834+0100 MessagingExample[371:8914] FCM registration token: dkL-a1RRCEPEgB6JQULpEr:APA91bFpiLFUnfc9L09C-HHDwO8SLuTOMgTWX2hkdOfunoNNn7T6oal221c_iCCbsxuKgczrywVJmrQWBwxTxCcpPj9LPoTKtS56Nq1RkvR_VbuOBvNVskigvzwXShDnPNAEbJKyZooP
2020-10-01 15:58:19.244019+0100 MessagingExample[371:8996] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-01 15:58:19.244226+0100 MessagingExample[371:8996] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-01 15:58:20.702204+0100 MessagingExample[371:8914] Local FCM registration token: dkL-a1RRCEPEgB6JQULpEr:APA91bFpiLFUnfc9L09C-HHDwO8SLuTOMgTWX2hkdOfunoNNn7T6oal221c_iCCbsxuKgczrywVJmrQWBwxTxCcpPj9LPoTKtS56Nq1RkvR_VbuOBvNVskigvzwXShDnPNAEbJKyZooP
2020-10-01 15:58:20.718983+0100 MessagingExample[371:8914] Returning local object of class NSString
2020-10-01 15:58:20.737131+0100 MessagingExample[371:8914] Remote instance ID token: dkL-a1RRCEPEgB6JQULpEr:APA91bFpiLFUnfc9L09C-HHDwO8SLuTOMgTWX2hkdOfunoNNn7T6oal221c_iCCbsxuKgczrywVJmrQWBwxTxCcpPj9LPoTKtS56Nq1RkvR_VbuOBvNVskigvzwXShDnPNAEbJKyZooP
2020-10-01 15:58:28.704472+0100 MessagingExample[371:8996] PBItemCollectionServicer connection disconnected.
2020-10-01 15:58:31.195835+0100 MessagingExample[371:8998] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration

Old API, worked:

2020-10-01 16:10:55.432502+0100 MessagingExample[420:10869] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-01 16:10:55.432761+0100 MessagingExample[420:10869] 6.33.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-01 16:10:55.466006+0100 MessagingExample[420:10870] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-01 16:10:55.493875+0100 MessagingExample[420:10864] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-01 16:10:55.528330+0100 MessagingExample[420:10868] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-01 16:10:55.529007+0100 MessagingExample[420:10868] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-01 16:10:55.534096+0100 MessagingExample[420:10680] FCM registration token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn
2020-10-01 16:10:57.922505+0100 MessagingExample[420:10680] Local FCM registration token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn
2020-10-01 16:10:57.936213+0100 MessagingExample[420:10680] Returning local object of class NSString
2020-10-01 16:10:57.949996+0100 MessagingExample[420:10680] Remote instance ID token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn

Old API, didn't work:

2020-10-01 15:52:45.552748+0100 MessagingExample[331:4866] FCM registration token: dJz2eKbPBUWMn429NtweDL:APA91bFjBo2E9CNfKotBASJQWCyXb3R3_2KL8mDbVpDqHoK8iixRErRKIv0970flw_Sunf_w0iabiJ3oiFiH1DoPARM-9FcfhKAiUubMokQjhyiuxZV3BtJBEeC3ao-kEl36p_b823Zq
2020-10-01 15:52:45.587370+0100 MessagingExample[331:5034] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-01 15:52:45.813305+0100 MessagingExample[331:5028] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-01 15:52:45.813826+0100 MessagingExample[331:5028] 6.33.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-01 15:52:47.274554+0100 MessagingExample[331:4866] Local FCM registration token: dJz2eKbPBUWMn429NtweDL:APA91bFjBo2E9CNfKotBASJQWCyXb3R3_2KL8mDbVpDqHoK8iixRErRKIv0970flw_Sunf_w0iabiJ3oiFiH1DoPARM-9FcfhKAiUubMokQjhyiuxZV3BtJBEeC3ao-kEl36p_b823Zq
2020-10-01 15:52:47.304823+0100 MessagingExample[331:4866] Returning local object of class NSString
2020-10-01 15:52:47.531339+0100 MessagingExample[331:4866] FCM registration token: fLCfTrmlG0-epUV9t8BSrE:APA91bHot-bWWDoa56BNaiXQ_PPsnbdQnPs_PHcl0FDTdEsE5UJHt6VjZrZrQi5PGYgvZvr67Mb_t7xmaYA7TJ4n-a9_ApQ2cIwQFBNOM8AAatwTb8XB5nw4S6mDP1SOaa31qLunvCN2
2020-10-01 15:52:47.538971+0100 MessagingExample[331:4866] Remote instance ID token: fLCfTrmlG0-epUV9t8BSrE:APA91bHot-bWWDoa56BNaiXQ_PPsnbdQnPs_PHcl0FDTdEsE5UJHt6VjZrZrQi5PGYgvZvr67Mb_t7xmaYA7TJ4n-a9_ApQ2cIwQFBNOM8AAatwTb8XB5nw4S6mDP1SOaa31qLunvCN2
2020-10-01 15:52:48.463305+0100 MessagingExample[331:5028] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-01 15:52:49.472035+0100 MessagingExample[331:5028] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-01 15:52:49.473357+0100 MessagingExample[331:5028] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-01 15:52:54.283745+0100 MessagingExample[331:5030] PBItemCollectionServicer connection disconnected.
2020-10-01 15:52:55.306713+0100 MessagingExample[331:5029] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration

Old API, didn't work:

2020-10-01 16:00:43.368448+0100 MessagingExample[315:4714] FCM registration token: dkL-a1RRCEPEgB6JQULpEr:APA91bFpiLFUnfc9L09C-HHDwO8SLuTOMgTWX2hkdOfunoNNn7T6oal221c_iCCbsxuKgczrywVJmrQWBwxTxCcpPj9LPoTKtS56Nq1RkvR_VbuOBvNVskigvzwXShDnPNAEbJKyZooP
2020-10-01 16:00:44.209308+0100 MessagingExample[315:4849] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-01 16:00:44.460464+0100 MessagingExample[315:4714] FCM registration token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn
2020-10-01 16:00:44.549027+0100 MessagingExample[315:4838] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-01 16:00:44.555876+0100 MessagingExample[315:4838] 6.33.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-01 16:00:47.747281+0100 MessagingExample[315:4849] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-01 16:00:48.289502+0100 MessagingExample[315:4849] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-01 16:00:48.289644+0100 MessagingExample[315:4849] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-01 16:00:58.254313+0100 MessagingExample[315:4883] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration
2020-10-01 16:07:38.182633+0100 MessagingExample[315:4714] Local FCM registration token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn
2020-10-01 16:07:38.191772+0100 MessagingExample[315:4714] Returning local object of class NSString
2020-10-01 16:07:38.210450+0100 MessagingExample[315:4714] Remote instance ID token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn
@morganchen12
Copy link
Contributor

@chliangGoogle can you take a look at these logs?

@tomkelsey
Copy link
Author

Something I just noticed - in the two examples that didn't work, the FCM registration token is logged before the "swizzling" message.

So perhaps this is a race condition if the FCM token is registered prior to swizzling? And then the APNs Token isn't properly associated?

Sorry if this makes no sense - I'm hypothesising without a full understanding of how it all works!

To reiterate a point I believe I mentioned previously - if I log the APNs token directly and send via that rather than Firebase it always works. So it seems to suggest to me that it's an issue with the FCM token and APNs being linked up properly?

@charlotteliang
Copy link
Contributor

@tomkelsey So is this just some device work, some doesn't? Could be a delivery performance issue, for the devices that didn't work, can you try again to see if they do?

And are they just keep not working, or they start working as described as the title?

If you can find a device that is consistently not working, can you send out debugLogs:
To enable debug logging set the following application argument: -FIRDebugEnabled (see http://goo.gl/RfcP7r)

Can you send the logs for at first open and reopen?

@morganchen12
Copy link
Contributor

@chliangGoogle the logs are above in this comment: #6553 (comment)

@charlotteliang
Copy link
Contributor

@morganchen12 Yeah I saw the logs. My question is based on after seeing the logs. And I try to first understand the behavior and then get the real debug logs that the comment is missing.

@morganchen12
Copy link
Contributor

Got it, thanks for clarifying 😅

@tomkelsey can you provide the logs with -FIRDebugEnabled?

@tomkelsey
Copy link
Author

@chliangGoogle Hey :) Thanks for your time.

The logs are all from the same device. I've just been deleting the app and running it again from xcode each time.

On TestFlight, it seems to be consistent in that:

  • It doesn't receive background notifications on first open the vast majority of the time.
  • Hard closing, re-opening, then backgrounding the app again fixes it. It's fixed it every time for me having tested it now probably 50+ times.

I can't as reliably reproduce this when running in debug mode via xcode directly on my device. During the examples above, sometimes hard closing and re-opening the app appeared to fix it whilst other times it did not.

I'll try and re-create again with the -FIRDebugEnabled flag.

I can't however see how to get the logs for when I re-open the app. If I run from xcode, then hard close the app and re-open it, I believe I lose the connection to xcode and the logs?

@charlotteliang
Copy link
Contributor

@tomkelsey
So is it accurate to say it only happens on TestFlight?
if you can reproduce, you can get the debugLog on first open the app, copy paste them in a text file. Then close the app and reopen them on xcode, and copy past more debugLog to the text file.

@tomkelsey
Copy link
Author

tomkelsey commented Oct 2, 2020

Hi @chliangGoogle - no, it also happens when testing on my device and running from xcode. It just seems to be more difficult to reproduce.

That said, I reliably recreated the issue three times in a row just now.

  1. Uninstall the quickstart app
  2. Restart the device
  3. Run the quickstart app from xcode
  4. App opens, accept notifications
  5. Tap log fcm token
  6. Background app
  7. Send Test message via Firebase console to FCM token
  8. No notification received.
  9. Hard close app (swipe it away)
  10. Run it from xcode again
  11. Background app
  12. Send Test message via Firebase console to FCM token (the same token as above)
  13. Notification received.

Please find logs below with the -FIRDebugEnabled flag:

First open from xcode, not working:

2020-10-02 07:42:12.536020+0100 MessagingExample[326:5020] FCM registration token: cFb9HzozFUQFjxqekqeAu_:APA91bGRbe8MS5trRBToyGESkHkRkg8h2ZSM2p_cagubgXd63mfEhyQK8dGPi-fJQWn22Zb_ftV2mqfMmk7wTceGvfjOVgZ5rUi2YNDK1snDMi0mE0Nr2sGtttlfqfL3286DjRjZLRxn
2020-10-02 07:42:12.886467+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-02 07:42:12.886568+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-10-02 07:42:12.886611+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:12.886672+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID008009] Deleted checkin plist file.
2020-10-02 07:42:12.887765+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID007006] Checkin parameters: {
    checkin =     {
        iosbuild =         {
            model = "iPhone10,6";
            "os_version" = "IOS_14.0.1";
        };
        "last_checkin_msec" = 0;
        type = 2;
        "user_number" = 0;
    };
    digest = "";
    fragment = 0;
    id = 0;
    locale = "en_GB";
    "security_token" = 0;
    "time_zone" = "Europe/London";
    "user_serial_number" = 0;
    version = 2;
}
2020-10-02 07:42:12.887864+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-10-02 07:42:12.887926+0100 MessagingExample[326:5173] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 07:42:12.887986+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-02 07:42:12.889439+0100 MessagingExample[326:5173] 6.33.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-02 07:42:12.889949+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID013002] Removed cached checkin preferences from Keychain because this is a fresh install.
2020-10-02 07:42:12.890500+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID013006] App reset detected. Will delete server registrations.
2020-10-02 07:42:12.890558+0100 MessagingExample[326:5173] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 07:42:12.890607+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:12.890801+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID014012] Invalidating cached token for 86648024660 (*) due to token is no longer fresh.
2020-10-02 07:42:12.891031+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID005004] Checkin is in progress
2020-10-02 07:42:12.891354+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Installations][I-FIS001001] Sending request: <NSMutableURLRequest: 0x2815f8870> { URL: https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/ }, body:{"appId":"1:86648024660:ios:0f5bfc6cf5109b79aa47c1","fid":"eobKNqDbi07FjWqKpx39U1","authVersion":"FIS_v2","sdkVersion":"i:1.7.0"}, headers: {
    "Content-Type" = "application/json";
    "X-Goog-Api-Key" = "[REMOVED]";
    "X-Ios-Bundle-Identifier" = "[REMOVED]";
    "X-firebase-client" = "apple-platform/ios apple-sdk/18A390 fire-analytics/6.8.3 fire-fcm/4.7.0 fire-iid/4.7.0 fire-install/1.7.0 fire-ios/6.10.3 swift/false xcode/12A7300";
    "X-firebase-client-log-type" = 3;
}.
2020-10-02 07:42:12.892263+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID007002] Invalid last checkin timestamp 2020-10-02 06:42:12 +0000 in future.
2020-10-02 07:42:12.892511+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID007003] Checkin successful with authId: 4680868791145190947, digest: iX5KdFOzaB6YxCOoqzv+DQ==, lastCheckinTimestamp: 1601620932000
2020-10-02 07:42:12.892874+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID005004] Successfully got checkin credentials
2020-10-02 07:42:12.893186+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID008010] Checkin plist file is saved
2020-10-02 07:42:12.893998+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid";
    svce = "com.google.iid.checkin";
}
2020-10-02 07:42:12.894157+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID005002] Checkin success.
2020-10-02 07:42:12.894227+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:12.894455+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-02 07:42:12.896254+0100 MessagingExample[326:5198] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 07:42:13.072086+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-10-02 07:42:13.072995+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-10-02 07:42:13.073693+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 86648024660 (*) due to APNs token change.
2020-10-02 07:42:13.074205+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:13.074670+0100 MessagingExample[326:5173] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 07:42:13.074958+0100 MessagingExample[326:5173] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-02 07:42:13.075375+0100 MessagingExample[326:5173] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 07:42:13.091291+0100 MessagingExample[326:5173] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 07:42:13.224358+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS001003] Request response received: <NSMutableURLRequest: 0x2815f8870> { URL: https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/ }, error: (null), body: {
  "name": "projects/86648024660/installations/eobKNqDbi07FjWqKpx39U1",
  "fid": "eobKNqDbi07FjWqKpx39U1",
  "refreshToken": "[REMOVED]",
  "authToken": {
    "token": "[REMOVED]",
    "expiresIn": "604800s"
  }
}
.
2020-10-02 07:42:13.225422+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS001005] Parsing server response for https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/.
2020-10-02 07:42:13.225529+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS001007] FIRInstallationsItem parsed successfully.
2020-10-02 07:42:13.225595+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/InstanceID][I-IID021000] Unregister request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5451616377477184223&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&delete=true
2020-10-02 07:42:13.331135+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/InstanceID][I-IID014007] Successfully deleted GCM server registrations on app reset
2020-10-02 07:42:13.331905+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:13.332092+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:13.368751+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-02 07:42:13.368815+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS005000] The AdSupport Framework is not currently linked. Some features will not function properly. Learn more at http://goo.gl/9vSsPb
2020-10-02 07:42:13.368898+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, -5053978636418340834
2020-10-02 07:42:13.369559+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, -5053978636418340834
2020-10-02 07:42:13.369679+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-02 07:42:13.369922+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-02 07:42:13.370523+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-02 07:42:13.370650+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-02 07:42:13.515540+0100 MessagingExample[326:5174] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=4680868791145190947&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=eobKNqDbi07FjWqKpx39U1&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-02 07:42:13.709766+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "86648024660:*";
}
2020-10-02 07:42:13.752919+0100 MessagingExample[326:5020] FCM registration token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:42:13.756651+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC, authorizedEntity: 86648024660, scope:*
2020-10-02 07:42:13.757525+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/InstanceID][I-IID003016] Received APNS token while fetching default token. Refetching default token.
2020-10-02 07:42:13.763749+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:13.763912+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:13.763981+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/InstanceID][I-IID003010] Successfully fetched default token.
2020-10-02 07:42:13.764049+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/InstanceID][I-IID003008] Got default token eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:42:13.806328+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 9.999982833862305
error in connection_block_invoke_2: Connection interrupted
2020-10-02 07:42:17.252911+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, -5053978636418340834
2020-10-02 07:42:17.278749+0100 MessagingExample[326:5020] Local FCM registration token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:42:17.348024+0100 MessagingExample[326:5020] Returning local object of class NSString
2020-10-02 07:42:17.488248+0100 MessagingExample[326:5020] Remote instance ID token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:42:17.499498+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:17.499676+0100 MessagingExample[326:5168] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:42:20.472667+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-02 07:42:20.473191+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-02 07:42:20.474480+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-02 07:42:20.956026+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: first_open_after_install (_fi), 1
2020-10-02 07:42:21.160784+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: first_open_time (_fot), 1601622000000
2020-10-02 07:42:21.190370+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, first_open (_f), {
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
    previous_first_open_count (_pfo) = 24;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 07:42:21.201988+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
    previous_first_open_count (_pfo) = 24;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 07:42:21.901522+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
    previous_first_open_count (_pfo) = 24;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 07:42:21.982659+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -9.107299089431763
2020-10-02 07:42:22.209884+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: ga_session_id (_sid), 1601620931
2020-10-02 07:42:22.297053+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: ga_session_number (_sno), 1
2020-10-02 07:42:22.297115+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, session_start (_s), {
    ga_event_origin (_o) = auto;
    session_id (_sid) = 1601620931;
    session_number (_sno) = 1;
}
2020-10-02 07:42:22.299285+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601620931;
    session_number (_sno) = 1;
}
2020-10-02 07:42:22.340361+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601620931;
    session_number (_sno) = 1;
}
2020-10-02 07:42:22.468233+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:22.468308+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -9.592982053756714
2020-10-02 07:42:22.470745+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 1;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:22.470808+0100 MessagingExample[326:5169] 6.33.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) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:22.515605+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:22.751401+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:22.752053+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -9.873995065689087
2020-10-02 07:42:22.818801+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-02 07:42:22.819759+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:22.819823+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -9.944580078125
2020-10-02 07:42:22.820256+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-02 07:42:22.820306+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-02 07:42:22.820700+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-02 07:42:22.821966+0100 MessagingExample[326:5198] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:22.822480+0100 MessagingExample[326:5170] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:22.830695+0100 MessagingExample[326:5170] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:23.011176+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-02 07:42:23.011607+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:23.069428+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS900001] Downloading data. Host: https://app-measurement.com/config/app/1:86648024660:ios:0f5bfc6cf5109b79aa47c1?platform=ios&app_instance_id=229805EFA07546D096225F7EA6260B58&gmp_version=60803
2020-10-02 07:42:23.155670+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/config/app/1:86648024660:ios:0f5bfc6cf5109b79aa47c1?platform=ios&app_instance_id=229805EFA07546D096225F7EA6260B58&gmp_version=60803
2020-10-02 07:42:23.277144+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration
2020-10-02 07:42:23.554896+0100 MessagingExample[326:5170] 6.33.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: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
    previous_first_open_count (_pfo) = 24;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 07:42:23.572354+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 383
2020-10-02 07:42:23.572417+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 1, 1601620931874
2020-10-02 07:42:23.763251+0100 MessagingExample[326:5170] 6.33.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: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601620931;
    session_number (_sno) = 1;
}
2020-10-02 07:42:23.764637+0100 MessagingExample[326:5170] 6.33.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) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:23.765225+0100 MessagingExample[326:5170] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:23.768401+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 1
2020-10-02 07:42:23.768807+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 1
2020-10-02 07:42:23.771717+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 472
2020-10-02 07:42:23.772230+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 2, 1601620932345
2020-10-02 07:42:23.808735+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS002001] APMExperimentAlarm fired
2020-10-02 07:42:23.809344+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS002003] APMExperimentAlarm canceled
2020-10-02 07:42:23.809772+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 14399.99728488922
2020-10-02 07:42:23.914982+0100 MessagingExample[326:5170] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601620943914, <APMPBMeasurementBatch: 0x2815f5100>
2020-10-02 07:42:23.918252+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-02 07:42:23.983378+0100 MessagingExample[326:5173] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-02 07:42:24.035660+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-02 07:42:24.043099+0100 MessagingExample[326:5198] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -10.69777297973633
2020-10-02 07:42:24.183830+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:37.465747+0100 MessagingExample[326:5196] PBItemCollectionServicer connection disconnected.
2020-10-02 07:42:43.170756+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-02 07:42:43.170931+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-02 07:42:43.170993+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-02 07:42:43.190500+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 22611;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:43.190668+0100 MessagingExample[326:5169] 6.33.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) = 22611;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:43.326764+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 22611;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:43.504392+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.6213090419769287
2020-10-02 07:42:43.505062+0100 MessagingExample[326:5678] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:43.505672+0100 MessagingExample[326:5678] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:43.508831+0100 MessagingExample[326:5680] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:43.606984+0100 MessagingExample[326:5678] 6.33.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) = 22611;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -5053978636418340834;
}
2020-10-02 07:42:43.607157+0100 MessagingExample[326:5678] 6.33.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) = -5053978636418340834;
}
2020-10-02 07:42:43.609111+0100 MessagingExample[326:5680] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 22612
2020-10-02 07:42:43.613417+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 22612
2020-10-02 07:42:43.615599+0100 MessagingExample[326:5680] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 465
2020-10-02 07:42:43.616782+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 3, 1601620963126
2020-10-02 07:42:43.866246+0100 MessagingExample[326:5680] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601620963865, <APMPBMeasurementBatch: 0x2815fa180>
2020-10-02 07:42:43.876864+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-02 07:42:44.106757+0100 MessagingExample[326:5678] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-02 07:42:44.126789+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-02 07:42:44.127143+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:44.155714+0100 MessagingExample[326:5677] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-02 07:42:44.163427+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -0.03679203987121582
2020-10-02 07:42:44.318601+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:42:44.318666+0100 MessagingExample[326:5169] 6.33.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 18

Hard close, re-run from x-code, working:

2020-10-02 07:44:07.486139+0100 MessagingExample[354:7248] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-02 07:44:07.486298+0100 MessagingExample[354:7248] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-10-02 07:44:07.489291+0100 MessagingExample[354:7244] 6.33.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-02 07:44:07.490296+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS005000] The AdSupport Framework is not currently linked. Some features will not function properly. Learn more at http://goo.gl/9vSsPb
2020-10-02 07:44:07.491575+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:07.507810+0100 MessagingExample[354:7249] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 07:44:07.508714+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-02 07:44:07.508822+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-02 07:44:07.509201+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-02 07:44:07.509673+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-02 07:44:07.511112+0100 MessagingExample[354:7237] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 07:44:07.513028+0100 MessagingExample[354:7245] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-10-02 07:44:07.513527+0100 MessagingExample[354:7245] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 07:44:07.514994+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-02 07:44:07.515148+0100 MessagingExample[354:7237] 6.33.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-02 07:44:07.620069+0100 MessagingExample[354:7245] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 14296.18689489365
2020-10-02 07:44:07.668729+0100 MessagingExample[354:7244] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-02 07:44:07.669269+0100 MessagingExample[354:7244] 6.33.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-02 07:44:07.669939+0100 MessagingExample[354:7244] 6.33.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-02 07:44:07.678085+0100 MessagingExample[354:7249] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-02 07:44:07.678603+0100 MessagingExample[354:7249] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 07:44:07.680259+0100 MessagingExample[354:7249] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 07:44:07.680303+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-10-02 07:44:07.680356+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-10-02 07:44:07.680628+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 86648024660 (*) due to APNs token change.
2020-10-02 07:44:07.680916+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:07.681086+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID023000] Info is not found in Keychain. OSStatus: -25300. Keychain query: {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    "m_Limit" = "m_LimitAll";
    "r_Attributes" = 1;
    "r_Data" = 1;
}
2020-10-02 07:44:07.681184+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, -4516193367245414193
2020-10-02 07:44:07.681494+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, -4516193367245414193
2020-10-02 07:44:07.682036+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:07.682532+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-02 07:44:07.691826+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-02 07:44:07.693192+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=4680868791145190947&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=eobKNqDbi07FjWqKpx39U1&apns_token=s_fc06425483d53c491c03b1e64b74a657067ba34d0ea5d5acdca83e695fa6b12a&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-02 07:44:07.693484+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-02 07:44:07.694007+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:07.694059+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-02 07:44:07.694280+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-02 07:44:07.694545+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-02 07:44:07.696875+0100 MessagingExample[354:7237] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:07.697808+0100 MessagingExample[354:7249] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:07.705180+0100 MessagingExample[354:7248] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:07.716329+0100 MessagingExample[354:7248] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.9619901180267334
2020-10-02 07:44:07.917067+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:07.917949+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:07.918366+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "86648024660:*";
}
2020-10-02 07:44:07.931981+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=4680868791145190947&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=eobKNqDbi07FjWqKpx39U1&apns_token=s_fc06425483d53c491c03b1e64b74a657067ba34d0ea5d5acdca83e695fa6b12a&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-02 07:44:07.938610+0100 MessagingExample[354:7248] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC, authorizedEntity: 86648024660, scope:*
2020-10-02 07:44:08.003803+0100 MessagingExample[354:7070] FCM registration token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:44:08.004727+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC, authorizedEntity: 86648024660, scope:*
2020-10-02 07:44:08.016979+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/InstanceID][I-IID003010] Successfully fetched default token.
2020-10-02 07:44:08.017528+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/InstanceID][I-IID003008] Got default token eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:44:08.690120+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-02 07:44:08.690215+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:44:08.693129+0100 MessagingExample[354:7249] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:08.700659+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 443
2020-10-02 07:44:08.700933+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 4, 1601621047677
2020-10-02 07:44:08.848122+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601621048847, <APMPBMeasurementBatch: 0x2801cca90>
2020-10-02 07:44:08.850623+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-02 07:44:08.932858+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-02 07:44:08.975934+0100 MessagingExample[354:7249] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-02 07:44:08.978230+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -0.3010280132293701
2020-10-02 07:44:09.048700+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:44:12.935289+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, -4516193367245414193
2020-10-02 07:44:12.971689+0100 MessagingExample[354:7070] Local FCM registration token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:44:12.990151+0100 MessagingExample[354:7070] Returning local object of class NSString
2020-10-02 07:44:13.001864+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:13.004404+0100 MessagingExample[354:7246] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 07:44:13.004544+0100 MessagingExample[354:7070] Remote instance ID token: eobKNqDbi07FjWqKpx39U1:APA91bFyjimEXVTEppVGNjEvSxtqX9pLeMmarzxt_J6HMwL8hgk87oZ-g0RA4-HXbEqeTz8r6qLwuf5ZxjwqD3SgXcTxjXVq0FnpmxeAJ45w8_fhKsb9MH75JRETiBk7RsUfVLr_N2RC
2020-10-02 07:44:28.255047+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-02 07:44:28.255976+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-02 07:44:28.256019+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-02 07:44:28.261684+0100 MessagingExample[354:7350] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 20575;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -4516193367245414193;
}
2020-10-02 07:44:28.262546+0100 MessagingExample[354:7237] 6.33.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) = 20575;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -4516193367245414193;
}
2020-10-02 07:44:28.335590+0100 MessagingExample[354:7350] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 20575;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -4516193367245414193;
}
2020-10-02 07:44:28.544385+0100 MessagingExample[354:7350] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.702616810798645
2020-10-02 07:44:28.544906+0100 MessagingExample[354:7350] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:28.545529+0100 MessagingExample[354:7350] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:28.552610+0100 MessagingExample[354:7350] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:28.666939+0100 MessagingExample[354:7237] 6.33.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) = 20575;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -4516193367245414193;
}
2020-10-02 07:44:28.667095+0100 MessagingExample[354:7237] 6.33.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) = -4516193367245414193;
}
2020-10-02 07:44:28.675425+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 43187
2020-10-02 07:44:28.675800+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 43187
2020-10-02 07:44:28.677935+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 474
2020-10-02 07:44:28.678420+0100 MessagingExample[354:7237] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 5, 1601621068248
2020-10-02 07:44:28.682713+0100 MessagingExample[354:7245] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601621068682, <APMPBMeasurementBatch: 0x2801c8750>
2020-10-02 07:44:28.685823+0100 MessagingExample[354:7245] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-02 07:44:28.754361+0100 MessagingExample[354:7252] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-02 07:44:28.804073+0100 MessagingExample[354:7351] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-02 07:44:28.816805+0100 MessagingExample[354:7351] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 07:44:28.817683+0100 MessagingExample[354:7351] 6.33.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 12
@tomkelsey
Copy link
Author

tomkelsey commented Oct 2, 2020

I just tried doing all of the steps above but I skipped the "Restart phone" step.

So I deleted the existing app then re-ran it from x-code and it worked first time.

Here are those logs:

First open from xcode, working (didn't restart the device between uninstalling and reinstalling):

2020-10-02 08:15:09.585922+0100 MessagingExample[410:9025] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-02 08:15:09.586084+0100 MessagingExample[410:9025] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-10-02 08:15:09.591814+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-02 08:15:09.591858+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/Analytics][I-ACS005000] The AdSupport Framework is not currently linked. Some features will not function properly. Learn more at http://goo.gl/9vSsPb
2020-10-02 08:15:09.592511+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:09.598624+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/InstanceID][I-IID008009] Deleted checkin plist file.
2020-10-02 08:15:09.609770+0100 MessagingExample[410:9022] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 08:15:09.611742+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-02 08:15:09.612081+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-02 08:15:09.612534+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-02 08:15:09.612656+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-02 08:15:09.612746+0100 MessagingExample[410:9022] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 08:15:09.616873+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID007006] Checkin parameters: {
    checkin =     {
        iosbuild =         {
            model = "iPhone10,6";
            "os_version" = "IOS_14.0.1";
        };
        "last_checkin_msec" = 0;
        type = 2;
        "user_number" = 0;
    };
    digest = "";
    fragment = 0;
    id = 0;
    locale = "en_GB";
    "security_token" = 0;
    "time_zone" = "Europe/London";
    "user_serial_number" = 0;
    version = 2;
}
2020-10-02 08:15:09.618246+0100 MessagingExample[410:9020] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-10-02 08:15:09.618460+0100 MessagingExample[410:9020] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-02 08:15:09.635344+0100 MessagingExample[410:9032] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-02 08:15:09.636063+0100 MessagingExample[410:9032] 6.33.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-02 08:15:09.708188+0100 MessagingExample[410:9018] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-02 08:15:09.708478+0100 MessagingExample[410:9018] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 08:15:09.709205+0100 MessagingExample[410:9018] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-02 08:15:09.709289+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID013002] Removed cached checkin preferences from Keychain because this is a fresh install.
2020-10-02 08:15:09.711426+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID013006] App reset detected. Will delete server registrations.
2020-10-02 08:15:09.711945+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:09.712021+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-10-02 08:15:09.712144+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-10-02 08:15:09.712429+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 86648024660 (*) due to APNs token change.
2020-10-02 08:15:09.712646+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, 7796962666782483044
2020-10-02 08:15:09.713085+0100 MessagingExample[410:9025] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, 7796962666782483044
2020-10-02 08:15:09.721258+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID023000] Info is not found in Keychain. OSStatus: -25300. Keychain query: {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    "m_Limit" = "m_LimitAll";
    "r_Attributes" = 1;
    "r_Data" = 1;
}
2020-10-02 08:15:09.721369+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID005004] Checkin is in progress
2020-10-02 08:15:09.721458+0100 MessagingExample[410:9018] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-02 08:15:09.783829+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Installations][I-FIS001001] Sending request: <NSMutableURLRequest: 0x281920400> { URL: https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/ }, body:{"appId":"1:86648024660:ios:0f5bfc6cf5109b79aa47c1","fid":"dCJWxUF4bkDvvqPrP60Qdv","authVersion":"FIS_v2","sdkVersion":"i:1.7.0"}, headers: {
    "Content-Type" = "application/json";
    "X-Goog-Api-Key" = "[REMOVED]";
    "X-Ios-Bundle-Identifier" = "[REMOVED]";
    "X-firebase-client" = "apple-platform/ios apple-sdk/18A390 fire-analytics/6.8.3 fire-fcm/4.7.0 fire-iid/4.7.0 fire-install/1.7.0 fire-ios/6.10.3 swift/false xcode/12A7300";
    "X-firebase-client-log-type" = 3;
}.
2020-10-02 08:15:09.793743+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 9.99997615814209
2020-10-02 08:15:10.007750+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/InstanceID][I-IID007003] Checkin successful with authId: 5645516393766402140, digest: N3IjRuvr69qwsrcqosXkzA==, lastCheckinTimestamp: 1601622909922
2020-10-02 08:15:10.007808+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/InstanceID][I-IID005004] Successfully got checkin credentials
2020-10-02 08:15:10.007904+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/InstanceID][I-IID008010] Checkin plist file is saved
2020-10-02 08:15:10.008567+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid";
    svce = "com.google.iid.checkin";
}
2020-10-02 08:15:10.014312+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/InstanceID][I-IID005002] Checkin success.
2020-10-02 08:15:10.014371+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:10.014775+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-02 08:15:10.047544+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-02 08:15:10.048909+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-02 08:15:10.050052+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-02 08:15:10.075110+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: first_open_after_install (_fi), 1
2020-10-02 08:15:10.082391+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: first_open_time (_fot), 1601625600000
2020-10-02 08:15:10.091054+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, first_open (_f), {
    ga_event_origin (_o) = auto;
    previous_first_open_count (_pfo) = 27;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 08:15:10.098672+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    previous_first_open_count (_pfo) = 27;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 08:15:10.120490+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Installations][I-FIS001003] Request response received: <NSMutableURLRequest: 0x281920400> { URL: https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/ }, error: (null), body: {
  "name": "projects/86648024660/installations/dCJWxUF4bkDvvqPrP60Qdv",
  "fid": "dCJWxUF4bkDvvqPrP60Qdv",
  "refreshToken": "[REMOVED]",
  "authToken": {
    "token": "[REMOVED]",
    "expiresIn": "604800s"
  }
}
.
2020-10-02 08:15:10.120621+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Installations][I-FIS001005] Parsing server response for https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/.
2020-10-02 08:15:10.122286+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Installations][I-FIS001007] FIRInstallationsItem parsed successfully.
2020-10-02 08:15:10.140556+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    previous_first_open_count (_pfo) = 27;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 08:15:10.147529+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/InstanceID][I-IID021000] Unregister request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=4615915522927274020&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&delete=true
2020-10-02 08:15:10.156450+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.3239820003509521
2020-10-02 08:15:10.177967+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: ga_session_id (_sid), 1601622909
2020-10-02 08:15:10.198466+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: ga_session_number (_sno), 1
2020-10-02 08:15:10.198539+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, session_start (_s), {
    ga_event_origin (_o) = auto;
    session_id (_sid) = 1601622909;
    session_number (_sno) = 1;
}
2020-10-02 08:15:10.210116+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601622909;
    session_number (_sno) = 1;
}
2020-10-02 08:15:10.282379+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601622909;
    session_number (_sno) = 1;
}
2020-10-02 08:15:10.291645+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:10.295095+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.1875250339508057
2020-10-02 08:15:10.295238+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 1;
    ga_event_origin (_o) = auto;
}
2020-10-02 08:15:10.295367+0100 MessagingExample[410:9026] 6.33.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) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
}
2020-10-02 08:15:10.324624+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
}
2020-10-02 08:15:10.330113+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:10.330282+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.1493160724639893
2020-10-02 08:15:10.336619+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-02 08:15:10.338856+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:10.338954+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.1406440734863281
2020-10-02 08:15:10.339184+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-02 08:15:10.340183+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-02 08:15:10.341851+0100 MessagingExample[410:9031] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:10.343709+0100 MessagingExample[410:9019] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:10.352804+0100 MessagingExample[410:9019] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:10.367243+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/InstanceID][I-IID014007] Successfully deleted GCM server registrations on app reset
2020-10-02 08:15:10.367959+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:10.369228+0100 MessagingExample[410:9022] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:10.407111+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5645516393766402140&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=dCJWxUF4bkDvvqPrP60Qdv&apns_token=s_7eaa7da859a2e1ac4d54b8d90be3c0a3ded5d699103f203d9ccfe8beef1a07df&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-02 08:15:10.538594+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-02 08:15:10.538648+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:10.544074+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "86648024660:*";
}
2020-10-02 08:15:10.550382+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS900001] Downloading data. Host: https://app-measurement.com/config/app/1:86648024660:ios:0f5bfc6cf5109b79aa47c1?platform=ios&app_instance_id=1A0FAE92B0C944BC96E311B2F2A9180F&gmp_version=60803
2020-10-02 08:15:10.553271+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: dCJWxUF4bkDvvqPrP60Qdv:APA91bFAWZcj3waMU2CjmodQ5jEENzsH30ygzRhKH52h2WJJjamZDeCqioUPErfIaliJTU_5sf6y9B5lYoug7vaCkuoBjmpHxx015Y-pHKkPgyhyKTL2CLWvalHFFodO1WEC10uA6V7A, authorizedEntity: 86648024660, scope:*
2020-10-02 08:15:10.553342+0100 MessagingExample[410:8833] FCM registration token: dCJWxUF4bkDvvqPrP60Qdv:APA91bFAWZcj3waMU2CjmodQ5jEENzsH30ygzRhKH52h2WJJjamZDeCqioUPErfIaliJTU_5sf6y9B5lYoug7vaCkuoBjmpHxx015Y-pHKkPgyhyKTL2CLWvalHFFodO1WEC10uA6V7A
2020-10-02 08:15:10.565762+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/InstanceID][I-IID003010] Successfully fetched default token.
2020-10-02 08:15:10.565852+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/InstanceID][I-IID003008] Got default token dCJWxUF4bkDvvqPrP60Qdv:APA91bFAWZcj3waMU2CjmodQ5jEENzsH30ygzRhKH52h2WJJjamZDeCqioUPErfIaliJTU_5sf6y9B5lYoug7vaCkuoBjmpHxx015Y-pHKkPgyhyKTL2CLWvalHFFodO1WEC10uA6V7A
2020-10-02 08:15:10.651436+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/config/app/1:86648024660:ios:0f5bfc6cf5109b79aa47c1?platform=ios&app_instance_id=1A0FAE92B0C944BC96E311B2F2A9180F&gmp_version=60803
2020-10-02 08:15:10.874955+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration
2020-10-02 08:15:10.906114+0100 MessagingExample[410:9026] 6.33.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: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    previous_first_open_count (_pfo) = 27;
    update_with_analytics (_uwa) = 0;
}
2020-10-02 08:15:10.937219+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 347
2020-10-02 08:15:10.939060+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 1, 1601622909478
2020-10-02 08:15:10.948230+0100 MessagingExample[410:9026] 6.33.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: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601622909;
    session_number (_sno) = 1;
}
2020-10-02 08:15:10.948645+0100 MessagingExample[410:9026] 6.33.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) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
}
2020-10-02 08:15:10.951377+0100 MessagingExample[410:9031] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:10.958647+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 1
2020-10-02 08:15:10.960499+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 1
2020-10-02 08:15:10.967829+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 467
2020-10-02 08:15:10.971314+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 2, 1601622909710
2020-10-02 08:15:10.985400+0100 MessagingExample[410:9026] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601622910979, <APMPBMeasurementBatch: 0x281920d70>
2020-10-02 08:15:10.993143+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-02 08:15:11.070185+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-02 08:15:11.125892+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-02 08:15:11.131410+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -0.419530987739563
2020-10-02 08:15:11.137136+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:11.768384+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-02 08:15:12.361836+0100 MessagingExample[410:8833] Local FCM registration token: dCJWxUF4bkDvvqPrP60Qdv:APA91bFAWZcj3waMU2CjmodQ5jEENzsH30ygzRhKH52h2WJJjamZDeCqioUPErfIaliJTU_5sf6y9B5lYoug7vaCkuoBjmpHxx015Y-pHKkPgyhyKTL2CLWvalHFFodO1WEC10uA6V7A
2020-10-02 08:15:12.374194+0100 MessagingExample[410:8833] Returning local object of class NSString
2020-10-02 08:15:12.383937+0100 MessagingExample[410:9024] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:12.386647+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, 7796962666782483044
2020-10-02 08:15:12.387271+0100 MessagingExample[410:9031] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-02 08:15:12.387472+0100 MessagingExample[410:8833] Remote instance ID token: dCJWxUF4bkDvvqPrP60Qdv:APA91bFAWZcj3waMU2CjmodQ5jEENzsH30ygzRhKH52h2WJJjamZDeCqioUPErfIaliJTU_5sf6y9B5lYoug7vaCkuoBjmpHxx015Y-pHKkPgyhyKTL2CLWvalHFFodO1WEC10uA6V7A
2020-10-02 08:15:19.364662+0100 MessagingExample[410:9022] PBItemCollectionServicer connection disconnected.
2020-10-02 08:15:19.795691+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS002001] APMExperimentAlarm fired
2020-10-02 08:15:19.796095+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS002003] APMExperimentAlarm canceled
2020-10-02 08:15:19.808086+0100 MessagingExample[410:9025] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 14399.98770081997
2020-10-02 08:15:20.867403+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-02 08:15:20.867529+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-02 08:15:20.867561+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-02 08:15:20.874127+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 9075;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 7796962666782483044;
}
2020-10-02 08:15:20.887380+0100 MessagingExample[410:9130] 6.33.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) = 9075;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 7796962666782483044;
}
2020-10-02 08:15:20.929453+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 9075;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 7796962666782483044;
}
2020-10-02 08:15:20.937295+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.1889330148696899
2020-10-02 08:15:20.937600+0100 MessagingExample[410:9034] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:20.939620+0100 MessagingExample[410:9130] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:20.951248+0100 MessagingExample[410:9034] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:20.953628+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:20.953942+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.1724669933319092
2020-10-02 08:15:20.961343+0100 MessagingExample[410:9034] 6.33.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) = 9075;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 7796962666782483044;
}
2020-10-02 08:15:20.961415+0100 MessagingExample[410:9034] 6.33.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) = 7796962666782483044;
}
2020-10-02 08:15:20.963853+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 9076
2020-10-02 08:15:20.969164+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 9076
2020-10-02 08:15:20.970917+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 457
2020-10-02 08:15:20.971303+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 3, 1601622920844
2020-10-02 08:15:20.975543+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601622920974, <APMPBMeasurementBatch: 0x281929780>
2020-10-02 08:15:20.979070+0100 MessagingExample[410:9034] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-02 08:15:21.043675+0100 MessagingExample[410:9130] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-02 08:15:21.088960+0100 MessagingExample[410:9131] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-02 08:15:21.098473+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-02 08:15:21.098558+0100 MessagingExample[410:9019] 6.33.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 20
@charlotteliang
Copy link
Contributor

Thank you for sharing that, I will take a look at. Seems like the token retry wasn't triggered when system detects a late APNS arrives. This is rare but somehow Apple returns the valid APNS token at a later time.
I will see how we can improve the situation.

@charlotteliang
Copy link
Contributor

@tomkelsey Are you using the quickstart objectiveC app or the swift app?

@tomkelsey
Copy link
Author

@chliangGoogle Objective-C 👍

@ewfian
Copy link

ewfian commented Oct 3, 2020

I will take a look at. Seems like the token retry wasn't triggered when system detects a late APNS arrives.

When I can't receive the push by using the FCM, I directly use the APNs as same app instance with nothing to do, it always works.
So, Is there a way to confirm that the FCM token and APNs token have been successfully bound? Or is it possible to provide a way to bind manually? @chliangGoogle It would be best if you can explain the flow of APNs token bind.

I am trying to provide users with a reset button when they complain about not receiving the push. It will call [[UIApplication sharedApplication] unregisterForRemoteNotifications], [[FIRMessaging messaging] deleteDataWithCompletion:], [[UIApplication sharedApplication] registerForRemoteNotifications], [[FIRMessaging messaging] tokenWithCompletion:] in turn. I'm not sure if this is the best practice but it is effective most of the time.

@charlotteliang
Copy link
Contributor

charlotteliang commented Oct 5, 2020

@ewfian You can file a customer report issue with us and our team can query if the token has successfully bind for you.
https://firebase.google.com/support

@charlotteliang
Copy link
Contributor

@tomkelsey I'm not able to reproduce, but somehow your apns token is updated in the middle of fetch that the request didn't go through. Looking into it right now.

@charlotteliang
Copy link
Contributor

@tomkelsey I'm able to reproduce, but then stop reproducing again. I've put up a fix and feel free to test it with the fix branch in the mean time.

@Monkeystation
Copy link

Monkeystation commented Oct 6, 2020

I am also experiencing this issue in my current React Native app:
"@react-native-firebase/app": "^8.4.5"
"@react-native-firebase/messaging": "^7.8.11"
which uses the Firebase SDK version: 6.30.0

This is what happens:

  • Remove previous build from device (if any)
  • Build Release to device, allow notifications and get token
  • Send message to device with Firebase Functions:
    admin.messaging().sendToDevice
  • I get the following responses (3 tests):
    {"messageId":"0:1601943267274750%585fd2f2585fd2f2"}
    {"messageId":"0:1601943317968318%585fd2f2585fd2f2"}
    {"messageId":"0:1601943360621290%585fd2f2585fd2f2"}
  • App is not receiving any messages.
  • Next I force quit and relaunch the app
  • Send message to device with Firebase Functions (same token)
  • I get the following response:
    {"messageId":"1601943466367901"}
  • App is receiving message and working as expected.
@tomkelsey
Copy link
Author

@chliangGoogle Thanks again for your time and effort on this.

Unfortunately I'm still having the same issues after applying your fix.

After a bit of Googling, I attempted to use your fix by including this line in my Podfile.

pod 'FirebaseInstanceID', :git => 'https://github.com/firebase/firebase-ios-sdk.git', :branch => 'chen/fm-swizzling'

But believe I received errors as the master is currently at version 7.0 and the other pods (Firebase/Analytics and Firebase/Messaging) weren't?

So I cleared out the Pods folder and Podfile.lock and reinstalled, then I manually patched FIRInstanceID.m to include your fix.

Just thought I'd explain the above in case that's the wrong approach and isn't properly patching things.

I then repeated the steps above as before. Here are the logs:

PS. For me the key to being able to reproduce it appears to be restarting the device between uninstalling and reinstalling the app. Not sure if that triggers a delay with the APNS stuff or not? May just be a device specific quirk!

First install from xcode. Not working.

2020-10-06 07:48:16.524891+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-06 07:48:16.524955+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-10-06 07:48:16.531958+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-06 07:48:16.532122+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS005000] The AdSupport Framework is not currently linked. Some features will not function properly. Learn more at http://goo.gl/9vSsPb
2020-10-06 07:48:16.533713+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:16.537784+0100 MessagingExample[349:6241] 6.33.0 - [Firebase/InstanceID][I-IID008009] Deleted checkin plist file.
2020-10-06 07:48:16.542900+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/InstanceID][I-IID007006] Checkin parameters: {
    checkin =     {
        iosbuild =         {
            model = "iPhone10,6";
            "os_version" = "IOS_14.0.1";
        };
        "last_checkin_msec" = 0;
        type = 2;
        "user_number" = 0;
    };
    digest = "";
    fragment = 0;
    id = 0;
    locale = "en_GB";
    "security_token" = 0;
    "time_zone" = "Europe/London";
    "user_serial_number" = 0;
    version = 2;
}
2020-10-06 07:48:16.544454+0100 MessagingExample[349:6241] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-10-06 07:48:16.554375+0100 MessagingExample[349:6244] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:48:16.555130+0100 MessagingExample[349:6244] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:48:16.555445+0100 MessagingExample[349:6244] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:48:16.557779+0100 MessagingExample[349:6247] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-06 07:48:16.557871+0100 MessagingExample[349:6247] 6.33.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-06 07:48:16.558173+0100 MessagingExample[349:6247] 6.33.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-06 07:48:16.558246+0100 MessagingExample[349:6247] 6.33.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-06 07:48:16.563961+0100 MessagingExample[349:6243] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-06 07:48:16.564745+0100 MessagingExample[349:6243] 6.33.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-06 07:48:16.615416+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID013002] Removed cached checkin preferences from Keychain because this is a fresh install.
2020-10-06 07:48:16.615469+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID013006] App reset detected. Will delete server registrations.
2020-10-06 07:48:16.615797+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:16.615907+0100 MessagingExample[349:6240] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-06 07:48:16.616299+0100 MessagingExample[349:6240] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-06 07:48:16.616407+0100 MessagingExample[349:6240] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-06 07:48:16.618235+0100 MessagingExample[349:6250] 6.33.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, 799545601804158692
2020-10-06 07:48:16.618284+0100 MessagingExample[349:6250] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, 799545601804158692
2020-10-06 07:48:16.630473+0100 MessagingExample[349:6088] FCM registration token: f4ZpAl28I0oPii2dq85oFX:APA91bHY_gGS5wAmpYAJzK-EkuxI5gG8CxhJJ8iPKraZDQ_WASuD-gTinuvkufUce-6LZwi2DTDol3HW6tJAlHs9zZ_0oiSxikp4tBvjTooNdKCfmxSrn5p80fYAAZR-GprzGy6pWOQp
2020-10-06 07:48:16.631639+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/InstanceID][I-IID014012] Invalidating cached token for 86648024660 (*) due to token is no longer fresh.
2020-10-06 07:48:16.633042+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/InstanceID][I-IID005004] Checkin is in progress
2020-10-06 07:48:16.693994+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS001001] Sending request: <NSMutableURLRequest: 0x280f90140> { URL: https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/ }, body:{"appId":"1:86648024660:ios:0f5bfc6cf5109b79aa47c1","fid":"elEfKSGGgUlDr9DuqNKv57","authVersion":"FIS_v2","sdkVersion":"i:1.7.0"}, headers: {
    "Content-Type" = "application/json";
    "X-Goog-Api-Key" = "[REMOVED]";
    "X-Ios-Bundle-Identifier" = "[REMOVED]";
    "X-firebase-client" = "apple-platform/ios apple-sdk/18A390 fire-analytics/6.8.3 fire-fcm/4.7.0 fire-iid/4.7.0 fire-install/1.7.0 fire-ios/6.10.3 swift/false xcode/12A7300";
    "X-firebase-client-log-type" = 3;
}.
2020-10-06 07:48:16.736874+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 9.999981880187988
2020-10-06 07:48:16.950871+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-06 07:48:16.951994+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-06 07:48:16.953246+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-06 07:48:16.984222+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: first_open_after_install (_fi), 1
2020-10-06 07:48:17.010762+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: first_open_time (_fot), 1601967600000
2020-10-06 07:48:17.021703+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/InstanceID][I-IID007003] Checkin successful with authId: 5485046919180417257, digest: 1WHhsATAwQa+VE2s+EJ00A==, lastCheckinTimestamp: 1601966896954
2020-10-06 07:48:17.022495+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/InstanceID][I-IID005004] Successfully got checkin credentials
2020-10-06 07:48:17.022537+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/InstanceID][I-IID008010] Checkin plist file is saved
2020-10-06 07:48:17.022751+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, first_open (_f), {
    ga_event_origin (_o) = auto;
    previous_first_open_count (_pfo) = 30;
    update_with_analytics (_uwa) = 0;
}
2020-10-06 07:48:17.022845+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid";
    svce = "com.google.iid.checkin";
}
2020-10-06 07:48:17.026495+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    previous_first_open_count (_pfo) = 30;
    update_with_analytics (_uwa) = 0;
}
2020-10-06 07:48:17.031457+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID005002] Checkin success.
2020-10-06 07:48:17.032092+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.032556+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-06 07:48:17.067797+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    previous_first_open_count (_pfo) = 30;
    update_with_analytics (_uwa) = 0;
}
2020-10-06 07:48:17.073152+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.3297480344772339
2020-10-06 07:48:17.085797+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: ga_session_id (_sid), 1601966896
2020-10-06 07:48:17.092361+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: ga_session_number (_sno), 1
2020-10-06 07:48:17.098360+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, session_start (_s), {
    ga_event_origin (_o) = auto;
    session_id (_sid) = 1601966896;
    session_number (_sno) = 1;
}
2020-10-06 07:48:17.099229+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS023073] Debug mode is enabled. Marking event as debug and real-time. Event name, parameters: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601966896;
    session_number (_sno) = 1;
}
2020-10-06 07:48:17.105434+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS001003] Request response received: <NSMutableURLRequest: 0x280f90140> { URL: https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/ }, error: (null), body: {
  "name": "projects/86648024660/installations/elEfKSGGgUlDr9DuqNKv57",
  "fid": "elEfKSGGgUlDr9DuqNKv57",
  "refreshToken": "[REMOVED]",
  "authToken": {
    "token": "[REMOVED]",
    "expiresIn": "604800s"
  }
}
.
2020-10-06 07:48:17.105762+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS001005] Parsing server response for https://firebaseinstallations.googleapis.com/v1/projects/test-project-a8fa2/installations/.
2020-10-06 07:48:17.105876+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS001007] FIRInstallationsItem parsed successfully.
2020-10-06 07:48:17.111429+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601966896;
    session_number (_sno) = 1;
}
2020-10-06 07:48:17.113419+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/InstanceID][I-IID021000] Unregister request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5109741931183478582&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&delete=true
2020-10-06 07:48:17.121431+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:48:17.121782+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.2811609506607056
2020-10-06 07:48:17.123101+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 1;
    ga_event_origin (_o) = auto;
}
2020-10-06 07:48:17.125954+0100 MessagingExample[349:6240] 6.33.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) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
}
2020-10-06 07:48:17.135020+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
}
2020-10-06 07:48:17.140254+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:48:17.140956+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.2624269723892212
2020-10-06 07:48:17.154545+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-06 07:48:17.158538+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:48:17.159310+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.245460033416748
2020-10-06 07:48:17.169711+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-06 07:48:17.170878+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-06 07:48:17.172069+0100 MessagingExample[349:6242] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:17.173653+0100 MessagingExample[349:6240] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:17.182942+0100 MessagingExample[349:6240] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:17.186968+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-10-06 07:48:17.187047+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-10-06 07:48:17.191803+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 86648024660 (*) due to APNs token change.
2020-10-06 07:48:17.194105+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.194205+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.284079+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID014007] Successfully deleted GCM server registrations on app reset
2020-10-06 07:48:17.285368+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.286381+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.315610+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5485046919180417257&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=elEfKSGGgUlDr9DuqNKv57&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-06 07:48:17.453079+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-06 07:48:17.453275+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:48:17.471143+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS900001] Downloading data. Host: https://app-measurement.com/config/app/1:86648024660:ios:0f5bfc6cf5109b79aa47c1?platform=ios&app_instance_id=98416A9747DA4C9390CD5E9D8750E017&gmp_version=60803
2020-10-06 07:48:17.535706+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "86648024660:*";
}
2020-10-06 07:48:17.537687+0100 MessagingExample[349:6088] FCM registration token: elEfKSGGgUlDr9DuqNKv57:APA91bGatfEmVctVXsojo7Zd06j_TXgE0QEtaQrGp6bBSaJn6587_ET07jJRlc_6NWi6DSZ0cTCSufgrhR-pZFTEVmUBsYTqqZmB0JlFH16GAhjmjhnkQvPQ3KjvUuEktG_cOsanl_De
2020-10-06 07:48:17.539622+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: elEfKSGGgUlDr9DuqNKv57:APA91bGatfEmVctVXsojo7Zd06j_TXgE0QEtaQrGp6bBSaJn6587_ET07jJRlc_6NWi6DSZ0cTCSufgrhR-pZFTEVmUBsYTqqZmB0JlFH16GAhjmjhnkQvPQ3KjvUuEktG_cOsanl_De, authorizedEntity: 86648024660, scope:*
2020-10-06 07:48:17.545656+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.549333+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID003016] Received APNS token while fetching default token. Refetching default token.
2020-10-06 07:48:17.549399+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:17.549439+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-06 07:48:17.566567+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5485046919180417257&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=elEfKSGGgUlDr9DuqNKv57
2020-10-06 07:48:17.567627+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/config/app/1:86648024660:ios:0f5bfc6cf5109b79aa47c1?platform=ios&app_instance_id=98416A9747DA4C9390CD5E9D8750E017&gmp_version=60803
2020-10-06 07:48:17.649872+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Analytics][I-ACS023130] Configuration not found. Using default configuration
2020-10-06 07:48:17.656733+0100 MessagingExample[349:6240] 6.33.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: first_open (_f), {
    ga_conversion (_c) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    previous_first_open_count (_pfo) = 30;
    update_with_analytics (_uwa) = 0;
}
2020-10-06 07:48:17.661026+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 346
2020-10-06 07:48:17.661105+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 1, 1601966896402
2020-10-06 07:48:17.670074+0100 MessagingExample[349:6240] 6.33.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: session_start (_s), {
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    session_id (_sid) = 1601966896;
    session_number (_sno) = 1;
}
2020-10-06 07:48:17.670175+0100 MessagingExample[349:6240] 6.33.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) = 1;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
}
2020-10-06 07:48:17.672284+0100 MessagingExample[349:6246] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:17.675042+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 1
2020-10-06 07:48:17.676163+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 1
2020-10-06 07:48:17.680747+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 470
2020-10-06 07:48:17.682474+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 2, 1601966896617
2020-10-06 07:48:17.692265+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601966897690, <APMPBMeasurementBatch: 0x280f88580>
2020-10-06 07:48:17.699703+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-06 07:48:17.740695+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6, authorizedEntity: 86648024660, scope:*
2020-10-06 07:48:17.775855+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-06 07:48:17.835592+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-06 07:48:17.839819+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -0.2221839427947998
2020-10-06 07:48:17.847858+0100 MessagingExample[349:6242] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:48:21.037630+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-06 07:48:22.205796+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, 799545601804158692
2020-10-06 07:48:22.248917+0100 MessagingExample[349:6088] Local FCM registration token: elEfKSGGgUlDr9DuqNKv57:APA91bGatfEmVctVXsojo7Zd06j_TXgE0QEtaQrGp6bBSaJn6587_ET07jJRlc_6NWi6DSZ0cTCSufgrhR-pZFTEVmUBsYTqqZmB0JlFH16GAhjmjhnkQvPQ3KjvUuEktG_cOsanl_De
2020-10-06 07:48:22.273712+0100 MessagingExample[349:6088] Returning local object of class NSString
2020-10-06 07:48:22.292634+0100 MessagingExample[349:6240] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:22.295536+0100 MessagingExample[349:6244] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:48:22.295597+0100 MessagingExample[349:6088] Remote instance ID token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:48:26.737409+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS002001] APMExperimentAlarm fired
2020-10-06 07:48:26.737650+0100 MessagingExample[349:6246] 6.33.0 - [Firebase/Analytics][I-ACS002003] APMExperimentAlarm canceled
2020-10-06 07:48:26.749023+0100 MessagingExample[349:6241] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 14399.98848593235
2020-10-06 07:48:50.923028+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-06 07:48:50.923098+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-06 07:48:50.923199+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-06 07:48:50.924454+0100 MessagingExample[349:6241] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 29883;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 799545601804158692;
}
2020-10-06 07:48:50.925469+0100 MessagingExample[349:6249] 6.33.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) = 29883;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 799545601804158692;
}
2020-10-06 07:48:50.943261+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 29883;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 799545601804158692;
}
2020-10-06 07:48:50.947818+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.9706219434738159
2020-10-06 07:48:50.948548+0100 MessagingExample[349:6249] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:50.949260+0100 MessagingExample[349:6249] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:50.954283+0100 MessagingExample[349:6527] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:50.961058+0100 MessagingExample[349:6249] 6.33.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) = 29883;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 799545601804158692;
}
2020-10-06 07:48:50.961298+0100 MessagingExample[349:6527] 6.33.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) = 799545601804158692;
}
2020-10-06 07:48:50.965981+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 29884
2020-10-06 07:48:50.966507+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 29884
2020-10-06 07:48:50.970360+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 463
2020-10-06 07:48:50.970494+0100 MessagingExample[349:6527] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 3, 1601966930919
2020-10-06 07:48:50.975040+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601966930974, <APMPBMeasurementBatch: 0x280f8c490>
2020-10-06 07:48:50.979346+0100 MessagingExample[349:6251] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-06 07:48:51.054455+0100 MessagingExample[349:6527] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-06 07:48:51.103910+0100 MessagingExample[349:6249] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-06 07:48:51.120325+0100 MessagingExample[349:6241] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:48:51.120572+0100 MessagingExample[349:6241] 6.33.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 15

Hard close, re-run from xcode. Not working

2020-10-06 07:51:19.852436+0100 MessagingExample[365:7566] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-06 07:51:19.852629+0100 MessagingExample[365:7566] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-10-06 07:51:19.855344+0100 MessagingExample[365:7566] 6.33.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-06 07:51:19.856785+0100 MessagingExample[365:7562] 6.33.0 - [Firebase/Analytics][I-ACS005000] The AdSupport Framework is not currently linked. Some features will not function properly. Learn more at http://goo.gl/9vSsPb
2020-10-06 07:51:19.857520+0100 MessagingExample[365:7562] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:19.864296+0100 MessagingExample[365:7562] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-10-06 07:51:19.871357+0100 MessagingExample[365:7562] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:51:19.900183+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-06 07:51:19.900466+0100 MessagingExample[365:7569] 6.33.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-06 07:51:19.900554+0100 MessagingExample[365:7569] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:51:19.935804+0100 MessagingExample[365:7570] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:51:19.935883+0100 MessagingExample[365:7570] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-06 07:51:19.936288+0100 MessagingExample[365:7570] 6.33.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-06 07:51:19.936862+0100 MessagingExample[365:7570] 6.33.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-06 07:51:19.937243+0100 MessagingExample[365:7570] 6.33.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-06 07:51:20.036762+0100 MessagingExample[365:7565] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-06 07:51:20.036827+0100 MessagingExample[365:7565] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-06 07:51:20.036854+0100 MessagingExample[365:7565] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-06 07:51:20.036901+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-10-06 07:51:20.036958+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-10-06 07:51:20.036998+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID014011] Invalidating cached token for 86648024660 (*) due to APNs token change.
2020-10-06 07:51:20.037049+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.037102+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID023000] Info is not found in Keychain. OSStatus: -25300. Keychain query: {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    "m_Limit" = "m_LimitAll";
    "r_Attributes" = 1;
    "r_Data" = 1;
}
2020-10-06 07:51:20.038542+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.038644+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-06 07:51:20.038713+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.038788+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID014000] Fetch new token for authorizedEntity: 86648024660, scope: *
2020-10-06 07:51:20.038857+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.038935+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.039076+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, -715301959687115319
2020-10-06 07:51:20.039143+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, -715301959687115319
2020-10-06 07:51:20.045398+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5485046919180417257&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=elEfKSGGgUlDr9DuqNKv57&apns_token=s_846712acd1ff2cf5d58eb17c2bdf5c146913e82d0a79afcb1469b613651b22fd&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-06 07:51:20.046909+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 14226.69022798538
2020-10-06 07:51:20.049946+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-06 07:51:20.050116+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-06 07:51:20.050829+0100 MessagingExample[365:7571] 6.33.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-06 07:51:20.056877+0100 MessagingExample[365:7571] 6.33.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-06 07:51:20.059231+0100 MessagingExample[365:7571] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-06 07:51:20.059269+0100 MessagingExample[365:7571] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-06 07:51:20.059598+0100 MessagingExample[365:7571] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-06 07:51:20.060835+0100 MessagingExample[365:7569] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:20.061665+0100 MessagingExample[365:7571] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:20.067696+0100 MessagingExample[365:7564] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:20.069789+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.9577161073684692
2020-10-06 07:51:20.285859+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.285939+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:20.286436+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/InstanceID][I-IID023002] Couldn't delete item from Keychain OSStatus: -25300 with the keychain query {
    acct = "[REMOVED]";
    class = genp;
    gena = "com.google.iid-tokens";
    svce = "86648024660:*";
}
2020-10-06 07:51:20.306235+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/InstanceID][I-IID020000] Register request to https://fcmtoken.googleapis.com/register content: X-osv=14.0.1&device=5485046919180417257&X-scope=*&plat=2&app=[REMOVED]&app_ver=1.5&X-cliv=fiid-4.7.0&sender=86648024660&X-subtype=86648024660&appid=elEfKSGGgUlDr9DuqNKv57&apns_token=s_846712acd1ff2cf5d58eb17c2bdf5c146913e82d0a79afcb1469b613651b22fd&gmp_app_id=1:86648024660:ios:0f5bfc6cf5109b79aa47c1
2020-10-06 07:51:20.310901+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6, authorizedEntity: 86648024660, scope:*
2020-10-06 07:51:20.386857+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID014001] Token fetch successful, token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6, authorizedEntity: 86648024660, scope:*
2020-10-06 07:51:20.386842+0100 MessagingExample[365:7539] FCM registration token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:51:20.394852+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID003010] Successfully fetched default token.
2020-10-06 07:51:20.395052+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/InstanceID][I-IID003008] Got default token elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:51:21.042914+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-06 07:51:21.043036+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:51:21.046478+0100 MessagingExample[365:7565] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:21.057281+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 442
2020-10-06 07:51:21.057389+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 4, 1601967080027
2020-10-06 07:51:21.063678+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601967081062, <APMPBMeasurementBatch: 0x281584ae0>
2020-10-06 07:51:21.069688+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-06 07:51:21.164157+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-06 07:51:21.229464+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-06 07:51:21.235083+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -0.2072640657424927
2020-10-06 07:51:21.241047+0100 MessagingExample[365:7570] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:51:22.302261+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, -715301959687115319
2020-10-06 07:51:22.312874+0100 MessagingExample[365:7539] Local FCM registration token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:51:22.328427+0100 MessagingExample[365:7539] Returning local object of class NSString
2020-10-06 07:51:22.336903+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:22.338647+0100 MessagingExample[365:7539] Remote instance ID token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:51:22.339247+0100 MessagingExample[365:7569] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:51:25.697365+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-06 07:51:25.697440+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-06 07:51:25.697538+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-06 07:51:25.707916+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 5639;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -715301959687115319;
}
2020-10-06 07:51:25.709028+0100 MessagingExample[365:7565] 6.33.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) = 5639;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -715301959687115319;
}
2020-10-06 07:51:25.727871+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 5639;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -715301959687115319;
}
2020-10-06 07:51:25.911945+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.7802830934524536
2020-10-06 07:51:25.912499+0100 MessagingExample[365:7565] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:25.914274+0100 MessagingExample[365:7565] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:25.922059+0100 MessagingExample[365:7565] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:25.993580+0100 MessagingExample[365:7572] 6.33.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) = 5639;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = -715301959687115319;
}
2020-10-06 07:51:25.993823+0100 MessagingExample[365:7572] 6.33.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) = -715301959687115319;
}
2020-10-06 07:51:26.015094+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 35523
2020-10-06 07:51:26.015206+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 35523
2020-10-06 07:51:26.016682+0100 MessagingExample[365:7572] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 467
2020-10-06 07:51:26.016828+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 5, 1601967085692
2020-10-06 07:51:26.030754+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601967086029, <APMPBMeasurementBatch: 0x281580ea0>
2020-10-06 07:51:26.033033+0100 MessagingExample[365:7572] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-06 07:51:26.098369+0100 MessagingExample[365:7565] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-06 07:51:26.144258+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-06 07:51:26.165360+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:51:26.165972+0100 MessagingExample[365:7564] 6.33.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 12

Second hard close, re-run from xcode. Working

2020-10-06 07:53:08.517229+0100 MessagingExample[397:9183] 6.33.0 - [Firebase/Core][I-COR000001] Configuring the default app.
2020-10-06 07:53:08.517288+0100 MessagingExample[397:9183] 6.33.0 - [Firebase/Core][I-COR000033] Data Collection flag is not set.
2020-10-06 07:53:08.520387+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS024000] Debug mode is on
2020-10-06 07:53:08.548165+0100 MessagingExample[397:9180] 6.33.0 - [Firebase/Analytics][I-ACS005000] The AdSupport Framework is not currently linked. Some features will not function properly. Learn more at http://goo.gl/9vSsPb
2020-10-06 07:53:08.549711+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:53:08.550816+0100 MessagingExample[397:9178] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:53:08.552143+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS023007] Analytics v.60803000 started
2020-10-06 07:53:08.552399+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS023008] To disable debug logging set the following application argument: -noFIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r)
2020-10-06 07:53:08.572077+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS023009] Debug logging enabled
2020-10-06 07:53:08.572237+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS023207] To enable verbose logging set the following application argument: -FIRAnalyticsVerboseLoggingEnabled (see http://goo.gl/RfcP7r)
2020-10-06 07:53:08.572486+0100 MessagingExample[397:9185] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:53:08.597872+0100 MessagingExample[397:9175] 6.33.0 - [Firebase/Messaging][I-FCM002000] FIRMessaging library version 4.7.0
2020-10-06 07:53:08.598508+0100 MessagingExample[397:9175] 6.33.0 - [GULReachability][I-REA902003] Monitoring the network status
2020-10-06 07:53:08.599899+0100 MessagingExample[397:9180] 6.33.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at:
https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging
to ensure proper integration.
2020-10-06 07:53:08.600652+0100 MessagingExample[397:9180] 6.33.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-06 07:53:08.611018+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS002002] APMExperimentAlarm scheduled to fire in approx. (s): 14118.1262229681
2020-10-06 07:53:08.626611+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS800023] No pending snapshot to activate. SDK name: app_measurement
2020-10-06 07:53:08.626754+0100 MessagingExample[397:9185] 6.33.0 - [Firebase/Analytics][I-ACS029017] Configuration was not found in database. The configuration will be fetched from the network when necessary
2020-10-06 07:53:08.627548+0100 MessagingExample[397:9180] 6.33.0 - [Firebase/Analytics][I-ACS023016] Analytics is ready to receive events
2020-10-06 07:53:08.661777+0100 MessagingExample[397:9152] FCM registration token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:53:08.663458+0100 MessagingExample[397:9183] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:1, status:Disconnected
2020-10-06 07:53:08.663956+0100 MessagingExample[397:9183] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-06 07:53:08.664253+0100 MessagingExample[397:9183] 6.33.0 - [GULReachability][I-REA902004] Network status has changed. Code:2, status:Connected
2020-10-06 07:53:08.664321+0100 MessagingExample[397:9183] 6.33.0 - [Firebase/InstanceID][I-IID003012] Provisioning profile has specifically provisioned devices, most likely a Dev profile.
2020-10-06 07:53:08.664662+0100 MessagingExample[397:9183] 6.33.0 - [Firebase/InstanceID][I-IID003013] APNS Environment in profile: development
2020-10-06 07:53:08.665341+0100 MessagingExample[397:9183] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:53:08.666008+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS031010] Tracking view controller. Class, ID: ViewController, 8499797315444720459
2020-10-06 07:53:08.666058+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, 8499797315444720459
2020-10-06 07:53:08.670965+0100 MessagingExample[397:9183] 6.33.0 - [Firebase/Analytics][I-ACS032003] iAd framework is not linked. Search Ad Attribution Reporter is disabled.
2020-10-06 07:53:08.672676+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-06 07:53:08.672728+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS023012] Analytics collection enabled
2020-10-06 07:53:08.673065+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS023220] Analytics screen reporting is enabled. Call +[FIRAnalytics logEventWithName:FIREventScreenView parameters:] to log a screen view event. To disable automatic screen reporting, set the flag FirebaseAutomaticScreenReportingEnabled to NO (boolean) in the Info.plist
2020-10-06 07:53:08.674992+0100 MessagingExample[397:9183] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:08.676708+0100 MessagingExample[397:9178] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:08.685375+0100 MessagingExample[397:9178] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:08.687647+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.9720389842987061
2020-10-06 07:53:09.724078+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS002001] Measurement timer fired
2020-10-06 07:53:09.724228+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:53:09.728726+0100 MessagingExample[397:9184] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:09.742155+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 442
2020-10-06 07:53:09.743154+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 7, 1601967188659
2020-10-06 07:53:09.750300+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601967189749, <APMPBMeasurementBatch: 0x2805c8a80>
2020-10-06 07:53:09.756614+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-06 07:53:09.833204+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-06 07:53:09.898568+0100 MessagingExample[397:9178] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-06 07:53:09.903160+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): -0.2435420751571655
2020-10-06 07:53:09.906986+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:53:15.028218+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS031006] View controller already tracked. Class, ID: ViewController, 8499797315444720459
2020-10-06 07:53:15.124163+0100 MessagingExample[397:9152] Local FCM registration token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:53:15.143736+0100 MessagingExample[397:9152] Returning local object of class NSString
2020-10-06 07:53:15.159121+0100 MessagingExample[397:9184] 6.33.0 - [Firebase/Installations][I-FIS002000] -[FIRInstallationsIDController createGetInstallationItemPromise], appName: __FIRAPP_DEFAULT
2020-10-06 07:53:15.163440+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Installations][I-FIS002001] -[FIRInstallationsIDController installationWithValidAuthTokenForcingRefresh:0], appName: __FIRAPP_DEFAULT
2020-10-06 07:53:15.163925+0100 MessagingExample[397:9152] Remote instance ID token: elEfKSGGgUlDr9DuqNKv57:APA91bElhd355HSenoX1kZRNOWvQBGzazMHBd_fB9YN7xwf8ScmTx8tXsm0rLjzX9YNi19ZLLi0wWaaEk94XdE6eShuTVohgQZDSsQn7MufxPZWql3M4ZyxVTedNovEaXa5sBEr4vhx6
2020-10-06 07:53:16.413995+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-06 07:53:16.414260+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS002002] Engagement timer scheduled to fire in approx. (s): 3600
2020-10-06 07:53:16.414523+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS002003] Engagement timer canceled
2020-10-06 07:53:16.414794+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS023051] Logging event: origin, name, params: auto, user_engagement (_e), {
    engagement_time_msec (_et) = 7773;
    ga_event_origin (_o) = auto;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 8499797315444720459;
}
2020-10-06 07:53:16.415700+0100 MessagingExample[397:9182] 6.33.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) = 7773;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 8499797315444720459;
}
2020-10-06 07:53:16.430806+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS023072] Event logged. Event name, event params: user_engagement (_e), {
    engagement_time_msec (_et) = 7773;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 8499797315444720459;
}
2020-10-06 07:53:16.433912+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS002002] Measurement timer scheduled to fire in approx. (s): 0.9682350158691406
2020-10-06 07:53:16.435134+0100 MessagingExample[397:9182] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:16.435284+0100 MessagingExample[397:9182] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:16.440273+0100 MessagingExample[397:9179] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:16.444728+0100 MessagingExample[397:9182] 6.33.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) = 7773;
    ga_debug (_dbg) = 1;
    ga_event_origin (_o) = auto;
    ga_realtime (_r) = 1;
    ga_screen_class (_sc) = ViewController;
    ga_screen_id (_si) = 8499797315444720459;
}
2020-10-06 07:53:16.444835+0100 MessagingExample[397:9179] 6.33.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) = 8499797315444720459;
}
2020-10-06 07:53:16.447503+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: lifetime_user_engagement (_lte), 44937
2020-10-06 07:53:16.447911+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS023087] User property set. Name, value: session_user_engagement (_se), 44937
2020-10-06 07:53:16.451374+0100 MessagingExample[397:9179] 6.33.0 - [Firebase/Analytics][I-ACS012018] Saving bundle. size (bytes): 468
2020-10-06 07:53:16.451525+0100 MessagingExample[397:9179] 6.33.0 - [Firebase/Analytics][I-ACS023116] Bundle added to the upload queue. BundleID, timestamp (ms): 8, 1601967196402
2020-10-06 07:53:16.455623+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS023039] Measurement data sent to network. Timestamp (ms), data: 1601967196455, <APMPBMeasurementBatch: 0x2805c0ab0>
2020-10-06 07:53:16.458617+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS900000] Uploading data. Host: https://app-measurement.com/a
2020-10-06 07:53:16.519654+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS901006] Received SSL challenge for host. Host: https://app-measurement.com/a
2020-10-06 07:53:16.567426+0100 MessagingExample[397:9182] 6.33.0 - [Firebase/Analytics][I-ACS023044] Successful upload. Got network response. Code, size: 204, -1
2020-10-06 07:53:16.582791+0100 MessagingExample[397:9214] 6.33.0 - [Firebase/Analytics][I-ACS002003] Measurement timer canceled
2020-10-06 07:53:16.583002+0100 MessagingExample[397:9214] 6.33.0 - [Firebase/Analytics][I-ACS023142] Cancelling background upload task. Task ID : 12
@charlotteliang
Copy link
Contributor

@tomkelsey Awesome! Thank you for testing it. I'm able to reproduce based on your trick. The latest fix should resolve your problem.

@morganchen12 morganchen12 added this to the Firebase 7 milestone Oct 6, 2020
@tomkelsey
Copy link
Author

@chliangGoogle Perfect, thank you. I've just tested it and it appears to be working well! 🎉

On a somewhat related note, there does appear to be a delay in the APNS stuff after restarting a device.

If you uninstall the app, restart the device then almost immediately run the app from xcode there was, for me, almost a 10 second delay before the APNS stuff kicked in.

If I background the app too quickly (before the ~10 second mark) sending a message via firebase still doesn't work (as presumably the APNS bits aren't linked up?). But foregrounding the app again seems to allow it to catch up and link everything up properly. This is much better than before as it doesn't require a hard restart of the app.

Also, if you restart the device and wait for a period of time before running from xcode the APNS stuff is pretty much instant.

So it seems unlikely it would be an issue very often in "real life" but is perhaps worth documenting/mentioning somewhere as could well be run into when testing and not realising a delay is potentially required after a restart?

Thanks again for for your (and everyone else's) help in resolving this 👍

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.