Skip to content

[HOLD for payment 2022-03-02] Conversation - A single message was sent 100s of times #7666

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

Closed
isagoico opened this issue Feb 9, 2022 · 44 comments
Assignees
Labels
Awaiting Payment Auto-added when associated PR is deployed to production Daily KSv2 Engineering External Added to denote the issue can be worked on by a contributor Improvement Item broken or needs improvement. Needs Reproduction Reproducible steps needed

Comments

@isagoico
Copy link

isagoico commented Feb 9, 2022

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


Action Performed:

  1. Send a message in a conversation

Expected Result:

A single message should be seen in the conversation history.

Actual Result:

100s of repeated messages were sent to the other user.

Workaround:

No need.

Platform:

Where is this issue occurring?

  • Web

Version Number: v1.1.38-1

Reproducible in staging?: Unknown
Reproducible in production?: Yes

Logs: https://stackoverflow.com/c/expensify/questions/4856

Notes/Photos/Videos:

image

Expensify/Expensify Issue URL:

Issue reported by: @tgolen
Slack conversation: https://expensify.slack.com/archives/C01GTK53T8Q/p1644440925115879

View all open jobs on GitHub

@MelvinBot
Copy link

Triggered auto assignment to @pecanoro (Engineering), see https://stackoverflow.com/c/expensify/questions/4319 for more details.

@conorpendergrast
Copy link
Contributor

This hit me too, and it was so weird

@VictoriaExpensify
Copy link
Contributor

The same issue happened with @conorpendergrast this morning:

image
It appears that every time he tries to send me a new response, it resends that same message another couple of hundred times:
image

Also happened with Conor's 10ams so it seems to be an issue on his end rather than mine:
image

@mallenexpensify
Copy link
Contributor

mallenexpensify commented Feb 10, 2022

Here's logs when it happened to me, also, my steps, since it happened right after I reopened my computer.

BUG: A message sent while my laptop was closed kept sending a couple dozen times and I got a notification ping for each.
Action Performed: Had production web open in Chrome, closed lid, opened lid 10-15 mins later
Expected Result: To see one new message
Actual Result: Saw ~2 dozen of the same message and got browser notifications for each. It stopped after I sent multiple messages to the user.
Platforms: web, production, Chrome
Version Number: v.1.1.37-2
Screenshots or Videos: in :thred:

image

@StefStavri
Copy link

StefStavri commented Feb 10, 2022

Also happened to @twisterdotcom and I at 12:25 UTC (i kept getting notification after notification for the same line)

image

Can't make it stop by closing the app and restarting unfortunately.

@twisterdotcom
Copy link
Contributor

I first sent that message to Stef at Today at 12:18 PM UTC according to my desktop app (which isn't experiencing this)
image

@twisterdotcom
Copy link
Contributor

twisterdotcom commented Feb 10, 2022

Happened when I next sent a message to @muttmuure and then my test account [email protected]
File

Desktop, endless errors but no repeating message:
https://user-images.githubusercontent.com/9133401/153413258-3a65a126-9ba1-4683-824f-078227244ba4.mp4

Basically endless:

Access to fetch at 'https://www.expensify.com/api?command=Log' from origin 'app://-' has been blocked by CORS policy: No 'Access-Control-Allow-Origin' header is present on the requested resource. If an opaque response serves your needs, set the request's mode to 'no-cors' to fetch the resource with CORS disabled.
HttpUtils.js:23          POST https://www.expensify.com/api?command=Log net::ERR_FAILED 200

Mobile vid:
Still trying to get it off my phone

Logs from the recipient:
image

One such log: 6db5746a0dfa76f9-SAN

64/64hits @0.2670s - ASC order - request_id:("6db5746a0dfa76f9-SAN") AND timestamp:[2022-02-10T11:48:58.143Z TO 2022-02-10T13:48:58.143Z]
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 140	[email protected]	 PID ~~ 3068045
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 141	[email protected]	 Processing 'Log' for 'expensify.com' from '89.240.137.71' ~~ accountID: '8698376' authToken: '<REDACTED>' email: '[email protected]' browserGUID: '5fda887ca3ef6' initialReferer: '<REDACTED>' command: 'Log' api_setCookie: 'false' expensifyCashAppVersion: 'expensifyCash[ios]1.1.38-0' referer: 'ecash' partnerName: 'expensify.com'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Previous log requestID ~~ requestID: '6db57426ff6b76f9-DEN' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '82096737' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [LOCAL_NOTIFICATION] Creating notification ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: report_82096737 properties: reportID,reportName,chatType,ownerEmail,policyID,unreadActionCount,maxSequenceNumber,participants,isPinned,lastVisitedTimestamp,lastMessageTimestamp,lastMessageText,lastActorEmail,hasOutstandingIOU,notificationPreference,stateNum,statusNum,oldPolicyName,iouReportID,icons,optimisticReportActionIDs,visibility,newMarkerSequenceNumber ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: reportActions_82096737 properties: 0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56 ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '82096737' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [LOCAL_NOTIFICATION] Creating notification ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: report_82096737 properties: reportID,reportName,chatType,ownerEmail,policyID,unreadActionCount,maxSequenceNumber,participants,isPinned,lastVisitedTimestamp,lastMessageTimestamp,lastMessageText,lastActorEmail,hasOutstandingIOU,notificationPreference,stateNum,statusNum,oldPolicyName,iouReportID,icons,optimisticReportActionIDs,visibility,newMarkerSequenceNumber ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: reportActions_82096737 properties: 0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57 ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [NetworkConnection] Firing reconnection callbacks because app became active ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: isLoadingAfterReconnect ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'personalDetailsList' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: isLoadingReportData ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'chatList' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'Report_UpdateLastRead' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: report_82096737 properties: reportID,reportName,chatType,ownerEmail,policyID,unreadActionCount,maxSequenceNumber,participants,isPinned,lastVisitedTimestamp,lastMessageTimestamp,lastMessageText,lastActorEmail,hasOutstandingIOU,notificationPreference,stateNum,statusNum,oldPolicyName,iouReportID,icons,optimisticReportActionIDs,visibility,newMarkerSequenceNumber ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db57452fffe76f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: personalDetails properties: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected] ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: myPersonalDetails properties: login,avatar,displayName,firstName,lastName,pronouns,timezone,payPalMeAddress,phoneNumber,localCurrencyCode ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db57452f80176f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'reportSummaryList' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'Report_UpdateLastRead' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db57452f80c76f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db574550c8076f9-LHR' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db574555d2776f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Report] successfully fetched report data ~~ chatList: '[0: '62646273' 1: '64167786' 2: '76810162' 3: '76810163' 4: '76886915' 5: '82079854' 6: '82096737' 7: '82442146']' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'GetIOUReport' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'GetIOUReport' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db57456a87276f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'reportStuff' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Finished API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db57458bcf176f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'PersonalDetails_GetForEmails' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: initialReportDataLoaded ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] [Onyx] set() called for key: isLoadingReportData ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 142	[email protected]	 [info] Making API request ~~ command: 'CreateChatReport' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Onyx] set() called for key: isLoadingAfterReconnect ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] Finished API request ~~ command: 'PersonalDetails_GetForEmails' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db5745a591176f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Onyx] set() called for key: personalDetails properties: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected] ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '82096737' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [LOCAL_NOTIFICATION] No notification because it was a comment for the current report ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Onyx] set() called for key: report_82096737 properties: reportID,reportName,chatType,ownerEmail,policyID,unreadActionCount,maxSequenceNumber,participants,isPinned,lastVisitedTimestamp,lastMessageTimestamp,lastMessageText,lastActorEmail,hasOutstandingIOU,notificationPreference,stateNum,statusNum,oldPolicyName,iouReportID,icons,optimisticReportActionIDs,visibility,newMarkerSequenceNumber ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Onyx] set() called for key: reportActions_82096737 properties: 0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58 ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] Making API request ~~ command: 'Report_UpdateLastRead' type: 'post' shouldUseSecure: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Onyx] set() called for key: report_82096737 properties: reportID,reportName,chatType,ownerEmail,policyID,unreadActionCount,maxSequenceNumber,participants,isPinned,lastVisitedTimestamp,lastMessageTimestamp,lastMessageText,lastActorEmail,hasOutstandingIOU,notificationPreference,stateNum,statusNum,oldPolicyName,iouReportID,icons,optimisticReportActionIDs,visibility,newMarkerSequenceNumber ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] Finished API request ~~ command: 'CreateChatReport' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db5745b6c6576f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Onyx] set() called for key: report_62646273 properties: reportID,reportName,chatType,ownerEmail,policyID,unreadActionCount,maxSequenceNumber,participants,isPinned,lastVisitedTimestamp,lastMessageTimestamp,lastMessageText,lastActorEmail,hasOutstandingIOU,notificationPreference,stateNum,statusNum,oldPolicyName,visibility,newMarkerSequenceNumber,icons,optimisticReportActionIDs ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] Finished API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db5745bcd5c76f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] Finished API request ~~ command: 'Report_UpdateLastRead' type: 'post' shouldUseSecure: '' jsonCode: '200' requestID: '6db5745c5e8176f9-SJC' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Report] Local reportActions up to date. Not fetching additional actions. ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '82096737' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 [info] [LOCAL_NOTIFICATION] No notification because it was a comment for the current report ~~ parameters: '' userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 ~~ userAgent: 'New%20Expensify/1.1.38.0 CFNetwork/1331.0.3 Darwin/21.4.0'
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 StoreUtils - Clearing all the stores.
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 TransactionStore cache fully cleared
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 ReportStore cache cleared
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 NVPStore - clear cache
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 NVPStore - clear cache
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 SharedInNVPStore - clear cache
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 ReportDuplicatedTransactionsStore - Cache cleared
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 Profile ~~ {"total":4,"cmd":"Log","jsonCode":200,"output":{"size":71,"gzip":1,"ratio":139},"gzip":71,"PHP":{"total":4,"%":100,"boot":2,"proc":2,"pack":0,"mem":2}}
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 143	[email protected]	 Timing: cmd= Log totalTime= 4 authTotalTime= authRunTime= 0
6db5746a0dfa76f9-SAN	virt1.lax	2022-02-10 12:48:58 144	

From the sender:
image

One example: 6db560f1683b773b-LHR

48/48hits @0.3550s - ASC order - request_id:("6db560f1683b773b-LHR") AND timestamp:[2022-02-10T11:35:40.547Z TO 2022-02-10T13:35:40.547Z]
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 546	[email protected]	 PID ~~ 497904
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 547	[email protected]	 Processing 'Log' for 'expensify.com' from '89.240.137.71' ~~ command: 'Log' api_setCookie: 'false' expensifyCashAppVersion: 'expensifyCash[desktop]1.1.38-1' referer: 'ecash' email: '[email protected]' browserGUID: '6205069c85ac8' partnerName: 'expensify.com'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 547	[email protected]	 [info] Making API request ~~ command: 'Report_AddComment' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 547	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'userWallet' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'account, loginList, nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'chatList' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'GetRequestCountryCode' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'User_IsFromPublicDomain' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'policySummaryList' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'User_GetBetas' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Report_GetHistory' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Report_GetHistory' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'GetLocalCurrency' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'personalDetailsList' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Report_AddComment' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Graphite_Timer' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'nameValuePairs' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'chatList' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 548	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'userWallet' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 [info] Making API request ~~ command: 'GetRequestCountryCode' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 [info] Making API request ~~ command: 'Get' type: 'post' shouldUseSecure: '' rvl: 'policySummaryList' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 [info] Making API request ~~ command: 'GetLocalCurrency' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 [info] Making API request ~~ command: 'User_IsFromPublicDomain' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 [info] Making API request ~~ command: 'User_GetBetas' type: 'post' shouldUseSecure: '' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 [info] Flushing logs as app is going inactive ~~ userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 ~~ userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.38-1 Chrome/98.0.4758.74 Electron/17.0.0 Safari/537.36'
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 StoreUtils - Clearing all the stores.
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 TransactionStore cache fully cleared
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 ReportStore cache cleared
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 NVPStore - clear cache
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 NVPStore - clear cache
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 SharedInNVPStore - clear cache
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 ReportDuplicatedTransactionsStore - Cache cleared
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 Profile ~~ {"total":3,"cmd":"Log","jsonCode":200,"output":{"size":71,"gzip":1,"ratio":139},"gzip":71,"PHP":{"total":3,"%":100,"boot":1,"proc":2,"pack":0,"mem":2}}
6db560f1683b773b-LHR	virt2.sjc	2022-02-10 12:35:40 549	[email protected]	 Timing: cmd= Log totalTime= 3 authTotalTime= authRunTime= 0

@tgolen
Copy link
Contributor

tgolen commented Feb 10, 2022

Here were the logs from when it happened to me yesterday.

The recipient (544 total):

6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 009 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
-- | -- | -- | -- | --
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 009 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 009 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae255e29d05245-LAX | virt1.rno | 2022-02-09 15:31:40 010 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 850 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 851 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'
6dae2595af035245-LAX | virt1.lax | 2022-02-09 15:31:48 852 | [email protected] | [info] [Report] Handled reportComment event sent by Pusher ~~ reportID: '64705968' userAgent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 11_6_0) AppleWebKit/537.36 (KHTML, like Gecko) new.expensify/1.1.27-1 Chrome/87.0.4280.141 Electron/11.4.8 Safari/537.36'

The sender (554), on web:

6dae24fc4c2a6701-SJC | virt2.sjc | 2022-02-09 15:31:24 235 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de4c39646' partnerName: 'expensify.com'
-- | -- | -- | -- | --
6dae2505add36701-SJC | virt2.sjc | 2022-02-09 15:31:25 747 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de4db67fe' partnerName: 'expensify.com'
6dae250bef956701-SAN | virt2.rno | 2022-02-09 15:31:26 757 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de4eb8f41' partnerName: 'expensify.com'
6dae2512280b6701-SAN | virt2.rno | 2022-02-09 15:31:27 755 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de4fb86c3' partnerName: 'expensify.com'
6dae251858d86701-SAN | virt2.rno | 2022-02-09 15:31:28 755 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de50b87a8' partnerName: 'expensify.com'
6dae251e99ee6701-SAN | virt2.rno | 2022-02-09 15:31:29 769 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de51bbcd8' partnerName: 'expensify.com'
6dae2524e9516701-SAN | virt2.rno | 2022-02-09 15:31:30 746 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de52b6414' partnerName: 'expensify.com'
6dae25316fee6701-SAN | virt2.rno | 2022-02-09 15:31:32 767 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de54bb3c7' partnerName: 'expensify.com'
6dae253798fd6701-SAN | virt2.rno | 2022-02-09 15:31:33 749 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de55b6e7e' partnerName: 'expensify.com'
6dae253de8706701-SAN | virt2.rno | 2022-02-09 15:31:34 747 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de56b65a2' partnerName: 'expensify.com'
6dae2544286b6701-SAN | virt2.rno | 2022-02-09 15:31:35 807 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de57c4fbe' partnerName: 'expensify.com'
6dae254a6f7c6701-SAN | virt2.rno | 2022-02-09 15:31:36 783 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de58bf40a' partnerName: 'expensify.com'
6dae2550af3e6701-SAN | virt2.rno | 2022-02-09 15:31:37 755 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de59b84c8' partnerName: 'expensify.com'
6dae2556dfb56701-SAN | virt2.rno | 2022-02-09 15:31:38 738 | [email protected] | Processing 'Report_AddComment' for 'expensify.com' from '67.177.248.66' ~~ command: 'Report_AddComment' reportID: '64705968' file: 'undefined' reportComment: '<REDACTED>' clientID: '1644420684024480' authToken: '<REDACTED>' referer: 'ecash' api_setCookie: 'false' email: '[email protected]' browserGUID: '6203de5ab42a7' partnerName: 'expensify.com'

@kidroca

This comment was marked as outdated.

@pecanoro pecanoro removed their assignment Feb 10, 2022
@pecanoro pecanoro added Improvement Item broken or needs improvement. External Added to denote the issue can be worked on by a contributor labels Feb 10, 2022
@MelvinBot
Copy link

Triggered auto assignment to @NicMendonca (External), see https://stackoverflow.com/c/expensify/questions/8582 for more details.

@kidroca kidroca mentioned this issue Feb 10, 2022
4 tasks
@kidroca
Copy link
Contributor

kidroca commented Feb 10, 2022

Opened a PR that adds logging and a retry limit: #7673

If the issue is caused by this block we should be able to capture it now:

App/src/libs/Network.js

Lines 231 to 237 in bdf3a74

.catch((error) => {
// When the request did not reach its destination add it back the queue to be retried
const shouldRetry = lodashGet(queuedRequest, 'data.shouldRetry');
if (shouldRetry) {
networkRequestQueue.push(queuedRequest);
return;
}

The theory is that something is false positively triggering the catch block making the request repeat over and over again

@NicMendonca
Copy link
Contributor

@botify botify removed the Daily KSv2 label Feb 10, 2022
@MelvinBot MelvinBot added the Weekly KSv2 label Feb 10, 2022
@MelvinBot
Copy link

Triggered auto assignment to Contributor-plus team member for initial proposal review - @rushatgabhane (Exported)

@MelvinBot MelvinBot added the Help Wanted Apply this label when an issue is open to proposals by contributors label Feb 10, 2022
@MelvinBot
Copy link

Triggered auto assignment to @Beamanator (Exported), see https://stackoverflow.com/c/expensify/questions/7972 for more details.

@twisterdotcom
Copy link
Contributor

I haven't seen it happen since I deleted and redownloaded the staging.dmg. I don't think that should stop us merging logging though because, my guess is it will crop up again for somebody.

@kidroca
Copy link
Contributor

kidroca commented Feb 14, 2022

Yeah, just to be clear it's merged, but not deployed to staging/prod yet
I would be interested to see which requests are triggering retries, so far I've only saw us retry a Persisted request because the token expired

@kidroca
Copy link
Contributor

kidroca commented Feb 21, 2022

Any updates here?
Nobody had reported duplicate messages being received on slack or in this issue

Are there logs suggesting it might be happening?

  • A retrieable request failed (command name, retry count, error message)
  • Request was retried too many times with no success. No more retries left

It would probably help if we see the history leading to these logs

@tgolen
Copy link
Contributor

tgolen commented Feb 22, 2022

I haven't ever seen this happen again. Here are the occurrences of those logs in the last 7 days:

image

Looks like that exploded around February 21st 2022, 15:55:00.000. Here is a more zoomed in look at that time period:
image

The second log correlates with that time period as well:
image

Zoomed in:
image

It looks like most of those are for the email [email protected]

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

Thanks for the info @tgolen

error: 'Failed to fetch' happens when you're offline so it's an expected case for a retry.
It looks like Network is not realising it's offline and keeps retrying the requests until there are no more retries
This PR should help with realising we're offline when such error happens and pause the queue:

I don't know who uses [email protected]

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

I have a theory that the Desktop app caused the 100s of message

See the code here:

App/desktop/main.js

Lines 161 to 190 in 853db57

if (!ELECTRON_ENVIRONMENT.isDev()) {
const newDotURL = ELECTRON_ENVIRONMENT.isProd() ? 'https://new.expensify.com' : 'https://staging.new.expensify.com';
// Modify the origin and referer for requests sent to our API
browserWindow.webContents.session.webRequest.onBeforeSendHeaders(validDestinationFilters, (details, callback) => {
// eslint-disable-next-line no-param-reassign
details.requestHeaders.origin = newDotURL;
// eslint-disable-next-line no-param-reassign
details.requestHeaders.referer = newDotURL;
callback({requestHeaders: details.requestHeaders});
});
// Modify access-control-allow-origin header for the response
browserWindow.webContents.session.webRequest.onHeadersReceived(validDestinationFilters, (details, callback) => {
// eslint-disable-next-line no-param-reassign
details.responseHeaders['access-control-allow-origin'] = ['app://-'];
callback({responseHeaders: details.responseHeaders});
});
}
if (ELECTRON_ENVIRONMENT.isDev()) {
require('dotenv').config();
if (process.env.USE_WEB_PROXY !== 'false') {
browserWindow.webContents.session.webRequest.onHeadersReceived(validDestinationFilters, (details, callback) => {
// eslint-disable-next-line no-param-reassign
details.responseHeaders['access-control-allow-origin'] = ['http://localhost:8080'];
callback({responseHeaders: details.responseHeaders});
});
}
}

Particularly lines that set access-control-allow-origin

details.responseHeaders['access-control-allow-origin'] = ['app://-']; 

The code was merged on 10th February - last date anyone reported the issue happening to them

I would do a test to confirm

@tgolen
Copy link
Contributor

tgolen commented Feb 23, 2022

OK, thanks. That desktop code is pretty familiar to @roryabraham so maybe he can also help.

I would do a test to confirm

What are you thinking of for a test? Like, reverting that code and see if it happens again? Or something else?

@rushatgabhane
Copy link
Member

I don't know who uses [email protected]

Looks like that's @thesahindia from this slack message.

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

What are you thinking of for a test? Like, reverting that code and see if it happens again? Or something else?

Yes, reverting the code
But it didn't recreate the issue.
It resulted in a lot of (all) requests getting retried, but now stopping at the 10th try, and the receiving end didn't receive my messages

Yet the Desktop app worked for a long time with no CORS problems and without the mentioned changes
So perhaps a backend change was made around the same time that made them necessary 🤷
Something around Referrer Policy: origin-when-cross-origin

Something seems to have resolved the issue, but we don't know what

@thesahindia
Copy link
Member

I don't know who uses [email protected]

Looks like that's @thesahindia from this slack message.

Yes I use that email.

@roryabraham
Copy link
Contributor

I think there might be something to @kidroca's theory, but I'm not really sure. The code @kidroca referenced above fixed a CORS issue on desktop – maybe before that CORS issue was fixed, we were blasting Report_AddComment requests. It might make sense that if we got a response (meaning that we are online), but the request still failed due to CORS, then we'd just retry the request because the error was unknown/unhandled.

FWIW, there were no back-end or API changes related to this PR, which introduced the CORS errors in the first place.

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

Thanks @roryabraham, @tgolen, I've managed to recreated it with the Desktop app
It didn't work the first time I tried because my auth token was old. I couldn't tell because everything was getting masked by CORS errors
Since then I've build the same Desktop version but with a fresh auth token and voila

Screen.Recording.2022-02-23.at.21.53.09.mov

People that happened to have the a fresh token an updated to the version in #7567 (1.1.38-0) would have had the issue


Some takeaways:

  • main problem: Request succeeds on server but results in CORS error in browser
  • The limitless retry amplified the problem
  • There seem to be no CORS preflight requests
  • The actual request is sent directly to the backend and then the error is treated as CORS because Response is lacking access-control-allow-origin header
  • The (updated) Desktop app adds this header artificially on received responses to overcome the CORS error

Usually when cross origin requests are made there's OPTIONS preflight request verifying current origin is allowed to perform the requested operation and then the actual request follow. The linked SO thread explains why preflight request can sometimes be skipped

Bottom line:
If you have CORS errors the app is unusable
It's in a state where it's impossible to recover on it's own - a new version that fixes the CORS issue needs to be published
Or the issue be taken care on the backend by setting the access-control-allow-origin on the response

Multiple domains can be whitelisted by implementing something like this: Access-Control-Allow-Origin Multiple Origin Domains?

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

Just to be clear: there are no CORS issues at the moment
I'm sharing these materials because I think we can move the handling of this header to the backend

This would allow us to whitelist the following domains and get rid of the proxy server:

  • localhost
  • *.expensify.com
  • app://
  • etc...

@botify botify added Weekly KSv2 Awaiting Payment Auto-added when associated PR is deployed to production and removed Weekly KSv2 labels Feb 23, 2022
@botify
Copy link

botify commented Feb 23, 2022

The solution for this issue has been 🚀 deployed to production 🚀 in version 1.1.39-3 and is now subject to a 7-day regression period 📆. Here is the list of pull requests that resolve this issue:

If no regressions arise, payment will be issued on 2022-03-02. 🎊

@botify botify changed the title Conversation - A single message was sent 100s of times [HOLD for payment 2022-03-02] Conversation - A single message was sent 100s of times Feb 23, 2022
@tgolen
Copy link
Contributor

tgolen commented Feb 23, 2022

Cool, thanks for that info! This is the backend code for the CORS header:

image

So, maybe we should just ad app:// to that? Isn't that a protocol though, not the host? PHP is doing things with the host there.

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

So, maybe we should just ad app:// to that? Isn't that a protocol though, not the host? PHP is doing things with the host there.

The current code adds this

details.responseHeaders['access-control-allow-origin'] = ['app://-'];

It shouldn't be prefixed with https

Buuut it also changes the origin sent by the request (I geuss $host) so it won't work unless we revert that

details.requestHeaders.origin = newDotURL;

Also maybe there's some way to change app:// to expensify:// or app://expensify or something in that sense

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

@NicMendonca, @tgolen
I'm sorry but I submitted the #7673 before there was an Upwork job and never applied for it

I've tracked any work around this ticket (including recreating the problem in Desktop today) in my hourly contract referencing this issue
Can we just close the Upwork job?

@tgolen
Copy link
Contributor

tgolen commented Feb 23, 2022

@NicMendonca Can you close the upwork job, please?

Maybe to fix the host/origin problem, the PHP code can also look at HTTP_REFERRER to see if it contains app://. So it would look more like this:

    if (in_array($host, ['comp.expensify.com', 'new.expensify.com', 'staging.new.expensify.com']) ) {
        header('Access-Control-Allow-Origin: https://'.$host);
        header('Access-Control-Allow-Credentials: true');
    } elseif (str_starts_with($_SERVER['HTTP_REFERER'], 'app://')) {
        header('Access-Control-Allow-Origin: app://-');
        header('Access-Control-Allow-Credentials: true');
    } elseif (DEBUG) {
        header('Access-Control-Allow-Origin: *');
        header('Access-Control-Allow-Credentials: true');
    }

We would have to change this too:

details.requestHeaders.origin = newDotURL.replace('https://', 'app://');

@kidroca
Copy link
Contributor

kidroca commented Feb 23, 2022

Well just so you know both the referrer and origin are overridden here, so if you do decide to take this to the backend we should unset them on Desktop (which can happen after the server is updated)

App/desktop/main.js

Lines 167 to 169 in 853db57

details.requestHeaders.origin = newDotURL;
// eslint-disable-next-line no-param-reassign
details.requestHeaders.referer = newDotURL;

@mallenexpensify
Copy link
Contributor

@kidroca is there still work being done here before filling out the Root Cause Analysis (RCA) doc?
https://docs.google.com/document/d/1OwX4QGqemKbm2yb1opwqIDrYkt8b5wskHcgYNwAKkzo/edit

@kidroca
Copy link
Contributor

kidroca commented Mar 1, 2022

@kidroca is there still work being done here before filling out the Root Cause Analysis (RCA) doc?

Not that I know of.
I'll fill out the doc and post back

@botify botify removed the Weekly KSv2 label Mar 4, 2022
@MelvinBot MelvinBot added the Daily KSv2 label Mar 4, 2022
@kidroca
Copy link
Contributor

kidroca commented Mar 4, 2022

@mallenexpensify
I've filled the RCA

This ticket can be closed: #7666 (comment)

@mallenexpensify
Copy link
Contributor

Thanks @kidroca , closing.
cc @michaelhaxhiu , cuz it's RCA

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Awaiting Payment Auto-added when associated PR is deployed to production Daily KSv2 Engineering External Added to denote the issue can be worked on by a contributor Improvement Item broken or needs improvement. Needs Reproduction Reproducible steps needed
Projects
None yet
Development

No branches or pull requests