WebChannel Transport Error, Long Polling

[nxfi777]

Operating System

Windows 11

Browser Version

Chrome/111.0.5563.65

Firebase SDK Version

9.17.1

Firebase SDK Product:

Auth, Firestore, Functions

Describe your project's tooling

React app with webpack

Describe the problem

I am often receiving a 400 error with a firestore listener in React JS, Tested on multiple browsers.

Here is the error, stack trace minimally reduced:

GET https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?gsessionid=[Truncated...] 400
k.send @ fetchxmlhttpfactory.js:270
k.da @ xhrio.js:664
ec @ channelrequest.js:570
Fd @ channelrequest.js:525
k.Ia @ webchannelbase.js:1673
xb @ run.js:140
Promise.then (async)
ub @ run.js:58
sb @ run.js:33
yc @ webchannelbase.js:1617
mc @ webchannelbase.js:2090
k.La @ channelrequest.js:619
k.ib @ channelrequest.js:596
kb @ eventtarget.js:351
C @ eventtarget.js:481
td @ xhrio.js:878
k.fb @ xhrio.js:866
k.Ha @ xhrio.js:842
hd @ fetchxmlhttpfactory.js:558
id @ fetchxmlhttpfactory.js:476
k.Ta @ fetchxmlhttpfactory.js:403
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Wa @ fetchxmlhttpfactory.js:355
Promise.then (async)
k.send @ fetchxmlhttpfactory.js:270
k.da @ xhrio.js:664
ec @ channelrequest.js:570
Fd @ channelrequest.js:525
k.Ia @ webchannelbase.js:1673
xb @ run.js:140
Promise.then (async)
ub @ run.js:58
sb @ run.js:33
yc @ webchannelbase.js:1617
mc @ webchannelbase.js:2090
k.La @ channelrequest.js:619
k.ib @ channelrequest.js:596
kb @ eventtarget.js:351
C @ eventtarget.js:481
td @ xhrio.js:878
k.fb @ xhrio.js:866
k.Ha @ xhrio.js:842
hd @ fetchxmlhttpfactory.js:558
id @ fetchxmlhttpfactory.js:476
k.Ta @ fetchxmlhttpfactory.js:403
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Wa @ fetchxmlhttpfactory.js:355
Promise.then (async)
k.send @ fetchxmlhttpfactory.js:270
k.da @ xhrio.js:664
ec @ channelrequest.js:570
Fd @ channelrequest.js:525
k.Ia @ webchannelbase.js:1673
xb @ run.js:140
Promise.then (async)
ub @ run.js:58
sb @ run.js:33
yc @ webchannelbase.js:1617
kc @ webchannelbase.js:2331
lc @ channelrequest.js:941
k.La @ channelrequest.js:619
k.ib @ channelrequest.js:596
kb @ eventtarget.js:351
C @ eventtarget.js:481
td @ xhrio.js:878
k.fb @ xhrio.js:866
k.Ha @ xhrio.js:842
hd @ fetchxmlhttpfactory.js:558
k.Ta @ fetchxmlhttpfactory.js:403
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Wa @ fetchxmlhttpfactory.js:355
Promise.then (async)
k.send @ fetchxmlhttpfactory.js:270
k.da @ xhrio.js:664
ec @ channelrequest.js:570
cc @ channelrequest.js:504
k.Ja @ webchannelbase.js:1331
xb @ run.js:140
Promise.then (async)
ub @ run.js:58
sb @ run.js:33
zc @ webchannelbase.js:1258
X.m @ webchannelbase.js:778
Hr @ index.esm2017.js:13053
send @ index.esm2017.js:12877
Bo @ index.esm2017.js:13392
zo @ index.esm2017.js:13554
xu @ index.esm2017.js:13905
(anonymous) @ index.esm2017.js:13940
$u @ index.esm2017.js:13939
(anonymous) @ index.esm2017.js:13472
(anonymous) @ index.esm2017.js:13500
(anonymous) @ index.esm2017.js:17414
(anonymous) @ index.esm2017.js:17447
Promise.then (async)
Hc @ index.esm2017.js:17447
enqueue @ index.esm2017.js:17414
enqueueAndForget @ index.esm2017.js:17392
(anonymous) @ index.esm2017.js:13500
(anonymous) @ index.esm2017.js:13472
so @ index.esm2017.js:12880
(anonymous) @ index.esm2017.js:13120
setTimeout (async)
wo @ index.esm2017.js:13115
jo @ index.esm2017.js:13518
Go @ index.esm2017.js:13471
(anonymous) @ index.esm2017.js:13461
Promise.then (async)
auth @ index.esm2017.js:13452
start @ index.esm2017.js:13357
(anonymous) @ index.esm2017.js:13482
(anonymous) @ index.esm2017.js:13258
(anonymous) @ index.esm2017.js:14306
(anonymous) @ index.esm2017.js:17414
(anonymous) @ index.esm2017.js:17447
Promise.then (async)
Hc @ index.esm2017.js:17447
enqueue @ index.esm2017.js:17414
enqueueAndForget @ index.esm2017.js:17392
handleDelayElapsed @ index.esm2017.js:14306
(anonymous) @ index.esm2017.js:14288
setTimeout (async)
start @ index.esm2017.js:14288
createAndSchedule @ index.esm2017.js:14282
enqueueAfterDelay @ index.esm2017.js:17487
Ro @ index.esm2017.js:13258
Oo @ index.esm2017.js:13481
start @ index.esm2017.js:13357
ku @ index.esm2017.js:13920
Bu @ index.esm2017.js:13947
close @ index.esm2017.js:13442
Qo @ index.esm2017.js:13491
(anonymous) @ index.esm2017.js:13475
(anonymous) @ index.esm2017.js:13500
(anonymous) @ index.esm2017.js:17414
(anonymous) @ index.esm2017.js:17447
Promise.then (async)
Hc @ index.esm2017.js:17447
enqueue @ index.esm2017.js:17414
enqueueAndForget @ index.esm2017.js:17392
(anonymous) @ index.esm2017.js:13500
(anonymous) @ index.esm2017.js:13475
io @ index.esm2017.js:12883
(anonymous) @ index.esm2017.js:13079
(anonymous) @ index.esm2017.js:13062
kb @ eventtarget.js:351
C @ eventtarget.js:481
Y.va @ webchannelbasetransport.js:428
R @ webchannelbase.js:2375
mc @ webchannelbase.js:2147
Q @ webchannelbase.js:522
k.La @ channelrequest.js:698
k.ib @ channelrequest.js:596
kb @ eventtarget.js:351
C @ eventtarget.js:481
td @ xhrio.js:878
k.fb @ xhrio.js:866
k.Ha @ xhrio.js:842
hd @ fetchxmlhttpfactory.js:558
k.Ta @ fetchxmlhttpfactory.js:403
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Wa @ fetchxmlhttpfactory.js:355
Promise.then (async)
k.send @ fetchxmlhttpfactory.js:270
k.da @ xhrio.js:664
ec @ channelrequest.js:570
Fd @ channelrequest.js:525
k.Ia @ webchannelbase.js:1673
xb @ run.js:140
Promise.then (async)
ub @ run.js:58
sb @ run.js:33
yc @ webchannelbase.js:1617
mc @ webchannelbase.js:2090
k.La @ channelrequest.js:619
k.ib @ channelrequest.js:596
kb @ eventtarget.js:351
C @ eventtarget.js:481
td @ xhrio.js:878
k.fb @ xhrio.js:866
k.Ha @ xhrio.js:842
hd @ fetchxmlhttpfactory.js:558
id @ fetchxmlhttpfactory.js:476
k.Ta @ fetchxmlhttpfactory.js:403
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Wa @ fetchxmlhttpfactory.js:355
Promise.then (async)
k.send @ fetchxmlhttpfactory.js:270
k.da @ xhrio.js:664
ec @ channelrequest.js:570
Fd @ channelrequest.js:525
k.Ia @ webchannelbase.js:1673
xb @ run.js:140
Promise.then (async)
ub @ run.js:58
sb @ run.js:33
yc @ webchannelbase.js:1617
mc @ webchannelbase.js:2090
k.La @ channelrequest.js:619
k.ib @ channelrequest.js:596
kb @ eventtarget.js:351
C @ eventtarget.js:481
td @ xhrio.js:878
k.fb @ xhrio.js:866
k.Ha @ xhrio.js:842
hd @ fetchxmlhttpfactory.js:558
id @ fetchxmlhttpfactory.js:476
k.Ta @ fetchxmlhttpfactory.js:403
Promise.then (async)
jd @ fetchxmlhttpfactory.js:370
k.Ta @ fetchxmlhttpfactory.js:228
Promise.then (async)
etc...

React Devtools Warning

@firebase/firestore: Firestore (9.17.1): Connection WebChannel transport errored: 
Jd {type: 'c', target: X, g: X, defaultPrevented: false, status: 1}
defaultPrevented
: 
false
g
: 
X {s: false, o: undefined, i: Ua, P: X, I: null, …}
status
: 
1
target
: 
X {s: false, o: undefined, i: Ua, P: X, I: null, …}
type
: 
"c"
[[Prototype]]
: 
Wb

Using setLogLevel('debug') I have found the following error logs:

[2023-06-04T21:54:23.588Z]  @firebase/firestore: Firestore (9.22.1): PersistentStream close with error: FirebaseError: [code=unavailable]: The operation could not be completed
logger.ts:115 [2023-06-04T21:54:23.591Z]  @firebase/firestore: Firestore (9.22.1): WebChannelConnection Creating RPC 'Listen' stream 0x86195108: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel {"httpSessionIdParam":"gsessionid","initMessageHeaders":{"X-Goog-Api-Client":"gl-js/ fire/9.22.1","Content-Type":"text/plain","X-Firebase-GMPID":"1:845193017408:web:d13f2eab4cf84fbe2e47a5","Authorization":"Bearer |OMITTED|"},"messageUrlParams":{"database":"projects/|OMITTED|/databases/(default)"},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"forceLongPolling":false,"detectBufferingProxy":true,"xmlHttpFactory":{"l":null,"j":false},"encodeInitMessageHeaders":true}

This looks like a timeout error, but I think I am correctly unsubscribing to the listener. This may be an issue with the useCallback() hook nature.

Steps and code to reproduce issue

Here is how I am initializing Firestore:

import { initializeApp } from "firebase/app";
import { initializeFirestore } from "firebase/firestore";

const fK = process.env.REACT_APP_FK;
const fDom = process.env.REACT_APP_FDOM;
const pId = process.env.REACT_APP_FPID;
const fSB = process.env.REACT_APP_FSB;
const fMSID = process.env.REACT_APP_FMSID;
const fId = process.env.REACT_APP_FID;
const fMID = process.env.REACT_APP_FMID;

const firebaseConfig = {
    apiKey: fK,
    authDomain: fDom,
    projectId: pId,
    storageBucket: fSB,
    messagingSenderId: fMSID,
    appId: fId,
    measurementId: fMID
  };

export const app = initializeApp(firebaseConfig);
export const db = initializeFirestore(app, {
  experimentalAutoDetectLongPolling: true,
});

I have also tried experimentalForceLongPolling, and disabling useFetchStreams. using getFirestore() to initialize firestore does not work either, I still get the same error.

MRE

  const fetchUserData = useCallback(() => {
    if (!navigator.onLine) {
      navigate("/");
      return;
    }
    if (auth.currentUser) {
      const docRef = doc(db, "users", auth.currentUser.uid);
      const unsubscribe = onSnapshot(
        docRef,
        (docSnap) => {
          if (docSnap.exists()) {
            const userData = docSnap.data();
            // Handle userData
          }
        },
        (error) => {
          handleLogout();
          console.error("Error fetching user data:", error);
        }
      );
  
      // Cleanup function to detach listener
      return () => {
        unsubscribe();
      };
    } else {
      navigate("/");
    }
  }, [navigate, handleLogout]);
  

  useEffect(() => {
    try {
      fetchUserData();
    } catch (error) {
      setInfo("User data not found");
      console.error(error);
      navigate("/");
    }
  }, [fetchUserData, navigate]);

I have a feeling that it may have to do with my useEffect() and useCallback() hooks, but I am uncertain. The user data is working fine, changes in the user document are seen from the app, but this error always occurs multiple times.

[wu-hui]

Can you try to visit https://debug-my.firebaseapp.com/ and tell us what you see?

[nxfi777]

@wu-hui Here is an extract from the output from https://debug-my.firebaseapp.com/

[ 22.156s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 22.157s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/000","fields":{"desc":{"stringValue":"Diagnostic Document 0"}},"createTime":"2019-12-28T19:50:18.256728Z","updateTime":"2019-12-28T19:50:18.256728Z"},"targetIds":[2]}}]
[ 22.159s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/001","fields":{"desc":{"stringValue":"Diagnostic Document 1"}},"createTime":"2019-12-28T19:50:31.803733Z","updateTime":"2019-12-28T19:50:31.803733Z"},"targetIds":[2]}}]
[ 22.160s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/002","fields":{"desc":{"stringValue":"Diagnostic Document 2"}},"createTime":"2019-12-28T19:50:37.682905Z","updateTime":"2019-12-28T19:50:43.797778Z"},"targetIds":[2]}}]
[ 22.161s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/003","fields":{"desc":{"stringValue":"Diagnostic Document 3"}},"createTime":"2019-12-28T19:50:52.602052Z","updateTime":"2019-12-28T19:50:52.602052Z"},"targetIds":[2]}}]
[ 22.162s] [diagnostic]
********************************************************
[TEST 7/9: Firestore listen test with default options] completed successfully.
********************************************************
[ 22.163s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 22.165s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 22.166s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] channel closed
[ 23.893s] [diagnostic]
********************************************************
[TEST 8/9: Firestore listen test with detectBufferingProxy] STARTING at 2023-06-13T16:20:04.525Z]
********************************************************
[ 23.895s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] createWebChannel("https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true,"detectBufferingProxy":true,"messageUrlParams":{"database":"projects/debug-my/databases/(default)"}})
[ 23.897s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] open()
[ 23.899s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 23.901s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 23.902s] [diagnostic] RequestStat event: 0
[ 23.904s] [diagnostic] RequestStat event: 0
[ 23.906s] [diagnostic] RequestStat event: 0
[ 23.907s] [diagnostic] RequestStat event: 0
[ 23.909s] [diagnostic] RequestStat event: 0
[ 23.911s] [diagnostic] RequestStat event: 0
[ 23.912s] [diagnostic] RequestStat event: 0
[ 23.913s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 23.915s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8
[ 23.917s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] channel.send({"database":"projects/debug-my/databases/(default)","addTarget":{"query":{"structuredQuery":{"from":[{"collectionId":"debug"}],"orderBy":[{"field":{"fieldPath":"__name__"},"direction":"ASCENDING"}]},"parent":"projects/debug-my/databases/(default)/documents"},"targetId":2}})
[ 23.919s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 23.921s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 23.922s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 23.925s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (76356) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
[ 24.045s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (76356) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
3 200
[ 24.046s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (76356): [[0,["c","(OMITTED)","",8,12,30000]]]

[ 24.047s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 24.049s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 24.050s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 24.051s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 24.052s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] channel opened: 157ms (1686673204684)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 24.054s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 130ms
[ 24.055s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
[ 24.056s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 24.059s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
null
[ 24.060s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 24.061s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 260
[ 24.063s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (76356) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMMITED)
4 200
[ 24.065s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 24.066s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 24.067s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 24.172s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
3 200
[ 24.174s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,[{
 "targetChange": {
   "targetChangeType": "ADD",
   "targetIds": [
     2
   ]
 }
}
]],[2,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/000",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 0"
       }
     },
     "createTime": "2019-12-28T19:50:18.256728Z",
     "updateTime": "2019-12-28T19:50:18.256728Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[3,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/001",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 1"
       }
     },
     "createTime": "2019-12-28T19:50:31.803733Z",
     "updateTime": "2019-12-28T19:50:31.803733Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[4,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/002",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 2"
       }
     },
     "createTime": "2019-12-28T19:50:37.682905Z",
     "updateTime": "2019-12-28T19:50:43.797778Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[5,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/003",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 3"
       }
     },
     "createTime": "2019-12-28T19:50:52.602052Z",
     "updateTime": "2019-12-28T19:50:52.602052Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[6,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/004",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 4"
       }
     },
     "createTime": "2019-12-28T19:50:59.545646Z",
     "updateTime": "2019-12-28T19:50:59.545646Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[7,[{
 "targetChange": {
   "targetChangeType": "CURRENT",
   "targetIds": [
     2
   ],
   "resumeToken": "CgkItfmpqtPA/wI=",
   "readTime": "2023-06-13T16:20:05.918901Z"
 }
}
]],[8,[{
 "targetChange": {
   "resumeToken": "CgkItfmpqtPA/wI=",
   "readTime": "2023-06-13T16:20:05.918901Z"
 }
}
]]]
[ 24.176s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 24.178s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/000","fields":{"desc":{"stringValue":"Diagnostic Document 0"}},"createTime":"2019-12-28T19:50:18.256728Z","updateTime":"2019-12-28T19:50:18.256728Z"},"targetIds":[2]}}]
[ 24.179s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/001","fields":{"desc":{"stringValue":"Diagnostic Document 1"}},"createTime":"2019-12-28T19:50:31.803733Z","updateTime":"2019-12-28T19:50:31.803733Z"},"targetIds":[2]}}]
[ 24.180s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/002","fields":{"desc":{"stringValue":"Diagnostic Document 2"}},"createTime":"2019-12-28T19:50:37.682905Z","updateTime":"2019-12-28T19:50:43.797778Z"},"targetIds":[2]}}]
[ 24.181s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/003","fields":{"desc":{"stringValue":"Diagnostic Document 3"}},"createTime":"2019-12-28T19:50:52.602052Z","updateTime":"2019-12-28T19:50:52.602052Z"},"targetIds":[2]}}]
[ 24.182s] [diagnostic]
********************************************************
[TEST 8/9: Firestore listen test with detectBufferingProxy] completed successfully.
********************************************************
[ 24.184s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 24.185s] [goog.labs.net.webChannel.WebChannelDebug] Cancel the BP detection timer.
[ 24.187s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 24.188s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] channel closed
[ 25.893s] [diagnostic]
********************************************************
[TEST 9/9: Firestore listen test with forceLongPolling] STARTING at 2023-06-13T16:20:06.525Z]
********************************************************
[ 25.894s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] createWebChannel("https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true,"forceLongPolling":true,"messageUrlParams":{"database":"projects/debug-my/databases/(default)"}})
[ 25.896s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] open()
[ 25.897s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 25.898s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 25.899s] [diagnostic] RequestStat event: 0
[ 25.900s] [diagnostic] RequestStat event: 0
[ 25.901s] [diagnostic] RequestStat event: 0
[ 25.902s] [diagnostic] RequestStat event: 0
[ 25.903s] [diagnostic] RequestStat event: 0
[ 25.904s] [diagnostic] RequestStat event: 0
[ 25.905s] [diagnostic] RequestStat event: 0
[ 25.906s] [diagnostic] RequestStat event: 0
[ 25.907s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 25.908s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
[ 25.910s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] channel.send({"database":"projects/debug-my/databases/(default)","addTarget":{"query":{"structuredQuery":{"from":[{"collectionId":"debug"}],"orderBy":[{"field":{"fieldPath":"__name__"},"direction":"ASCENDING"}]},"parent":"projects/debug-my/databases/(default)/documents"},"targetId":2}})
[ 25.912s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 25.913s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 25.914s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 25.916s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (67798) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
[ 26.036s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (67798) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
3 200
[ 26.037s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (67798): [[0,["c","(OMITTED)","",8,12,30000]]]

[ 26.038s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 26.039s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 26.040s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 26.041s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 26.042s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] channel opened: 148ms (1686673206674)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 26.043s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
[ 26.044s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 26.045s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
null
[ 26.047s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 26.048s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (67798) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
4 200
[ 26.050s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 26.051s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 26.052s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 26.158s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=(OMITTED)
3 200
[ 26.159s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,[{
 "targetChange": {
   "targetChangeType": "ADD",
   "targetIds": [
     2
   ]
 }
}
]],[2,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/000",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 0"
       }
     },
     "createTime": "2019-12-28T19:50:18.256728Z",
     "updateTime": "2019-12-28T19:50:18.256728Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[3,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/001",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 1"
       }
     },
     "createTime": "2019-12-28T19:50:31.803733Z",
     "updateTime": "2019-12-28T19:50:31.803733Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[4,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/002",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 2"
       }
     },
     "createTime": "2019-12-28T19:50:37.682905Z",
     "updateTime": "2019-12-28T19:50:43.797778Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[5,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/003",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 3"
       }
     },
     "createTime": "2019-12-28T19:50:52.602052Z",
     "updateTime": "2019-12-28T19:50:52.602052Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[6,[{
 "documentChange": {
   "document": {
     "name": "projects/debug-my/databases/(default)/documents/debug/004",
     "fields": {
       "desc": {
         "stringValue": "Diagnostic Document 4"
       }
     },
     "createTime": "2019-12-28T19:50:59.545646Z",
     "updateTime": "2019-12-28T19:50:59.545646Z"
   },
   "targetIds": [
     2
   ]
 }
}
]],[7,[{
 "targetChange": {
   "targetChangeType": "CURRENT",
   "targetIds": [
     2
   ],
   "resumeToken": (OMITTED),
   "readTime": "2023-06-13T16:20:07.908708Z"
 }
}
]],[8,[{
 "targetChange": {
   "resumeToken": (OMITTED),
   "readTime": "2023-06-13T16:20:07.908708Z"
 }
}
]]]
[ 26.162s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 26.164s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/000","fields":{"desc":{"stringValue":"Diagnostic Document 0"}},"createTime":"2019-12-28T19:50:18.256728Z","updateTime":"2019-12-28T19:50:18.256728Z"},"targetIds":[2]}}]
[ 26.165s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/001","fields":{"desc":{"stringValue":"Diagnostic Document 1"}},"createTime":"2019-12-28T19:50:31.803733Z","updateTime":"2019-12-28T19:50:31.803733Z"},"targetIds":[2]}}]
[ 26.166s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/002","fields":{"desc":{"stringValue":"Diagnostic Document 2"}},"createTime":"2019-12-28T19:50:37.682905Z","updateTime":"2019-12-28T19:50:43.797778Z"},"targetIds":[2]}}]
[ 26.167s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/003","fields":{"desc":{"stringValue":"Diagnostic Document 3"}},"createTime":"2019-12-28T19:50:52.602052Z","updateTime":"2019-12-28T19:50:52.602052Z"},"targetIds":[2]}}]
[ 26.168s] [diagnostic]
********************************************************
[TEST 9/9: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 26.169s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 26.171s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 26.173s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] channel closed
[ 27.890s] [diagnostic]
********************************************************
All tests done.
1: webchannel.googleapis.com with default options: SUCCEEDED (1658ms)
2: webchannel.googleapis.com with detectBufferingProxy: SUCCEEDED (1461ms)
3: webchannel.googleapis.com with forceLongPolling: SUCCEEDED (1955ms)
4: webchannel.sandbox.google.com with default options: SUCCEEDED (1623ms)
5: webchannel.sandbox.google.com with detectBufferingProxy: SUCCEEDED (1593ms)
6: webchannel.sandbox.google.com with forceLongPolling: SUCCEEDED (2277ms)
7: Firestore listen test with default options: SUCCEEDED (264ms)
8: Firestore listen test with detectBufferingProxy: SUCCEEDED (289ms)
9: Firestore listen test with forceLongPolling: SUCCEEDED (275ms)

********************************************************
[ 46.700s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 49.632s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 53.098s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 56.526s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 60.498s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 65.181s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 67.167s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
... continues

[nxfi777]

Update: I have upgraded to firebase sdk 19.23.0, the issue persists.

[nxfi777]

Its been months, no solution found yet... Currently using firebase sdk 10.4.0

[NinitoAS]

Hi @nxfi777, can you try to set FirestoreSettings.experimentalAutoDetectLongPolling to false and see if you can reproduce this issue?

[armanism24]

any update guys on this issue?

[userbox020]

same problem