App icon indicating copy to clipboard operation
App copied to clipboard

HIGH: [Debugability] [$250] Add logging around offline state to help diagnose bugs

Open m-natarajan opened this issue 2 months ago β€’ 9 comments

If you haven’t already, check out our contributing guidelines for onboarding and email [email protected] to request to join our Slack channel!


Version Number: Needs reproduction Reproducible in staging?: N/A Reproducible in production?: N/A If this was caught during regression testing, add the test name, ID and link from TestRail: Email or phone of affected tester (no customers): @quinthar Logs: https://stackoverflow.com/c/expensify/questions/4856 Expensify/Expensify Issue URL: Issue reported by: @quinthar Slack conversation: https://expensify.slack.com/archives/C049HHMV9SM/p1712886605520589

Action Performed:

  1. Open NewDot being online
  2. Observe there is a message " You appear to be offline"
  3. Do some actions and check the API request

Expected Result:

  • " You appear to be offline" should not show when online
  • If offline then success 200 OK API request should not be showin
  • No pusher notification Add something to the logs that will help diagnose this

Actual Result:

  • " You appear to be offline" message appears when online
  • Success 200 OK API request appears
  • Push notification is received I don't see anything useful in the logs. Can we maybe improve the logging around whatever code we use to determine if we're online to output its logic so we can diagnose and fix it.

Workaround:

unknown

Platforms:

Which of our officially supported platforms is this issue occurring on?

  • [ ] Android: Native
  • [ ] Android: mWeb Chrome
  • [ ] iOS: Native
  • [ ] iOS: mWeb Safari
  • [x] MacOS: Chrome / Safari
  • [ ] MacOS: Desktop

Screenshots/Videos

Add any screenshot/video evidence

image (4)

image (5)

View all open jobs on GitHub

Upwork Automation - Do Not Edit
  • Upwork Job URL: https://www.upwork.com/jobs/~016051a56c40abfc4a
  • Upwork Job ID: 1782729213837856768
  • Last Price Increase: 2024-04-23

m-natarajan avatar Apr 15 '24 19:04 m-natarajan

Triggered auto assignment to @sonialiap (Bug), see https://stackoverflow.com/c/expensify/questions/14418 for more details. Please add this bug to a GH project, as outlined in the SO.

melvin-bot[bot] avatar Apr 15 '24 19:04 melvin-bot[bot]

This has been labelled "Needs Reproduction". Follow the steps here: https://stackoverflowteams.com/c/expensify/questions/16989

MelvinBot avatar Apr 15 '24 19:04 MelvinBot

@sonialiap Whoops! This issue is 2 days overdue. Let's get this updated quick!

melvin-bot[bot] avatar Apr 19 '24 18:04 melvin-bot[bot]

I can reproduce, but not consistently. Sometimes when I open ND the "you're offline" message appears and then usually clears within 30 seconds. If I open ND, close, and immediately open a new tab with ND the message doesn't appear. It seems to require some time between opening ND in a new tab for it to show as offline

sonialiap avatar Apr 23 '24 11:04 sonialiap

Job added to Upwork: https://www.upwork.com/jobs/~016051a56c40abfc4a

melvin-bot[bot] avatar Apr 23 '24 11:04 melvin-bot[bot]

Triggered auto assignment to Contributor-plus team member for initial proposal review - @Santhosh-Sellavel (External)

melvin-bot[bot] avatar Apr 23 '24 11:04 melvin-bot[bot]

Proposal

Please re-state the problem that we are trying to solve in this issue.

Nothing useful in the logs to debug online/offline issues

What is the root cause of that problem?

We don't have such logs.

What changes do you think we should make in order to solve the problem?

There're 3 places we're setting offline status via setOfflineStatus, we should add additional logs with the context by Log.info

  1. In https://github.com/Expensify/App/blob/7ca0748be632d0ef4f93a6171a8dc768fc11817b/src/libs/NetworkConnection.ts#L62 We should log:
Log.info(`[NetworkStatus] Setting "offlineStatus" to "true" because user is under force offline`);
  1. In https://github.com/Expensify/App/blob/7ca0748be632d0ef4f93a6171a8dc768fc11817b/src/libs/NetworkConnection.ts#L65

We should log the full state that NetInfo returned

Log.info(`[NetworkStatus] The user is not under force offline, calling NetInfo.fetch, setting "offlineStatus" to ${(state.isInternetReachable ?? false) === false} with network state: ${JSON.stringify(state)}`);
  1. In https://github.com/Expensify/App/blob/7ca0748be632d0ef4f93a6171a8dc768fc11817b/src/libs/NetworkConnection.ts#L109

We should log the full state that NetInfo returned, and mention that this is set in NetInfo.addEventListener

Log.info(`[NetworkStatus] NetInfo.addEventListener event coming, setting "offlineStatus" to ${(state.isInternetReachable ?? false) === false} with network state: ${JSON.stringify(state)}`);

We can logs the logic we use to determine offline status (state.isInternetReachable ?? false) === false, if that's necessary.

Those are the core places we need the logs, the specifics of the log content/params can be adjusted in the PR phase.

What alternative solutions did you explore? (Optional)

Currently we have only true/false network status, if later we decide to have enum/number/string network statuses, then we need to log that value in the same way.

nkdengineer avatar Apr 23 '24 11:04 nkdengineer

@sonialiap, @Santhosh-Sellavel Whoops! This issue is 2 days overdue. Let's get this updated quick!

melvin-bot[bot] avatar Apr 26 '24 18:04 melvin-bot[bot]

What's the next step here, and the ETA?

quinthar avatar Apr 27 '24 04:04 quinthar

@sonialiap @Santhosh-Sellavel this issue was created 2 weeks ago. Are we close to approving a proposal? If not, what's blocking us from getting this issue assigned? Don't hesitate to create a thread in #expensify-open-source to align faster in real time. Thanks!

melvin-bot[bot] avatar Apr 29 '24 18:04 melvin-bot[bot]

@nkdengineer's proposal LGTM!

C+ Reviewed πŸŽ€ πŸ‘€ πŸŽ€

Santhosh-Sellavel avatar Apr 29 '24 18:04 Santhosh-Sellavel

Triggered auto assignment to @hayata-suenaga, see https://stackoverflow.com/c/expensify/questions/7972 for more details.

melvin-bot[bot] avatar Apr 29 '24 18:04 melvin-bot[bot]

Yes I believe we just need to log in places where we're setting the log status. @nkdengineer's proposal looks good to me

Let me double-check how we're handling the logs from New Expensify internally though first

hayata-suenaga avatar Apr 30 '24 18:04 hayata-suenaga

okay, so because these events or logs are not associated with any request ID, when we use these logs to troubleshoot the offline status issues, we will use the customer's email address to search for logs

hayata-suenaga avatar Apr 30 '24 18:04 hayata-suenaga

okay I'll assign @nkdengineer

hayata-suenaga avatar Apr 30 '24 18:04 hayata-suenaga

πŸ“£ @nkdengineer πŸŽ‰ An offer has been automatically sent to your Upwork account for the Contributor role πŸŽ‰ Thanks for contributing to the Expensify app!

Offer link Upwork job Please accept the offer and leave a comment on the Github issue letting us know when we can expect a PR to be ready for review πŸ§‘β€πŸ’» Keep in mind: Code of Conduct | Contributing πŸ“–

melvin-bot[bot] avatar Apr 30 '24 18:04 melvin-bot[bot]

Issue not reproducible during KI retests. (First week)

mvtglobally avatar May 01 '24 13:05 mvtglobally

this GH ticket is not specific to an issue. the issue in the description is just an example.

we will proceed adding logs

hayata-suenaga avatar May 02 '24 19:05 hayata-suenaga

@nkdengineer what's your EAT for PR? πŸ˜„

hayata-suenaga avatar May 02 '24 19:05 hayata-suenaga

bumped @nkdengineer in Slack

hayata-suenaga avatar May 06 '24 22:05 hayata-suenaga

@hayata-suenaga Sorry for delay, Will open PR today.

nkdengineer avatar May 07 '24 02:05 nkdengineer

@Santhosh-Sellavel The PR is here

nkdengineer avatar May 07 '24 03:05 nkdengineer