Skip to content

Instantly share code, notes, and snippets.

@btjones

btjones/gist:a2a2661e858bdbdc6a06b3801f8859a8 Secret

Created August 18, 2021 14:58
Show Gist options
  • Save btjones/a2a2661e858bdbdc6a06b3801f8859a8 to your computer and use it in GitHub Desktop.
Save btjones/a2a2661e858bdbdc6a06b3801f8859a8 to your computer and use it in GitHub Desktop.
Download ZIP
Firebase `forceLongPolling` Test Results
Raw
gistfile1.txt
[ 0.001s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] STARTING at 2021-08-18T14:54:28.551Z]
********************************************************
[ 0.024s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] createWebChannel("https://webchannel.googleapis.com/staging/channel/generator", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true})
[ 0.026s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] open()
[ 0.026s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 0.026s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 0.027s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 0.028s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 0.028s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":500,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 0.029s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 0.029s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.029s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 0.031s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (67407) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=67407&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5ff9t0ip80v&t=1
count=1&ofs=0&req0___data__=%7B%22message%22%3A%22Hello%22%2C%22message_size%22%3A0%2C%22delay_ms%22%3A0%2C%22num_messages%22%3A5%2C%22message_interval%22%3A500%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 0.441s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (67407) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=67407&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5ff9t0ip80v&t=1
3 200
[ 0.443s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (67407): [[0,["c","KuoueW6fV7dsrzlKxgZegg","",8,12,30000]]]
[ 0.444s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 0.445s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 0.445s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 0.446s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 0.447s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel opened: 422ms (1629298468996)
concurrent request limit : 10
isSpdyEnabled : true
[ 0.447s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8
[ 0.448s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 0.450s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8&RID=rpc&SID=KuoueW6fV7dsrzlKxgZegg&CI=0&AID=0&TYPE=xmlhttp&zx=tmr22qjwtjvi&t=1
null
[ 0.451s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 0.452s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (67407) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=67407&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5ff9t0ip80v&t=1
4 200
[ 0.453s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 0.454s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.454s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 0.660s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8&RID=rpc&SID=KuoueW6fV7dsrzlKxgZegg&CI=0&AID=0&TYPE=xmlhttp&zx=tmr22qjwtjvi&t=1
3 200
[ 0.661s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 0.661s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 0.937s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8&RID=rpc&SID=KuoueW6fV7dsrzlKxgZegg&CI=0&AID=0&TYPE=xmlhttp&zx=tmr22qjwtjvi&t=1
3 200
[ 0.939s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 0.940s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 1.441s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8&RID=rpc&SID=KuoueW6fV7dsrzlKxgZegg&CI=0&AID=0&TYPE=xmlhttp&zx=tmr22qjwtjvi&t=1
3 200
[ 1.441s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 1.442s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 1.938s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8&RID=rpc&SID=KuoueW6fV7dsrzlKxgZegg&CI=0&AID=0&TYPE=xmlhttp&zx=tmr22qjwtjvi&t=1
3 200
[ 1.940s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 1.941s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 2.439s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=6l3JiK7bOFn5jKLnN9bGnx5whsVbipkDQtrBaSdyxoU&VER=8&RID=rpc&SID=KuoueW6fV7dsrzlKxgZegg&CI=0&AID=0&TYPE=xmlhttp&zx=tmr22qjwtjvi&t=1
3 200
[ 2.441s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 2.442s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 2.443s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] completed successfully.
********************************************************
[ 2.444s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 2.447s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 2.448s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel closed
[ 3.954s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] STARTING at 2021-08-18T14:54:32.504Z]
********************************************************
[ 3.956s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] createWebChannel("https://webchannel.sandbox.google.com/staging/channel/generator", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true})
[ 3.958s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] open()
[ 3.958s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 3.959s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 3.960s] [diagnostic] RequestStat event: 0
[ 3.961s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 3.961s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[ 3.962s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":500,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 3.963s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[ 3.963s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 3.964s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 3.966s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (11190) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=11190&CVER=22&X-HTTP-Session-Id=gsessionid&zx=ld8vbd7z0xfz&t=1
count=1&ofs=0&req0___data__=%7B%22message%22%3A%22Hello%22%2C%22message_size%22%3A0%2C%22delay_ms%22%3A0%2C%22num_messages%22%3A5%2C%22message_interval%22%3A500%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 4.466s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (11190) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=11190&CVER=22&X-HTTP-Session-Id=gsessionid&zx=ld8vbd7z0xfz&t=1
3 200
[ 4.468s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (11190): [[0,["c","ld9I1OwtI0vILTdQnLm_Rw","",8,12,30000]]]
[ 4.469s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 4.469s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 4.470s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 4.470s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.sandbox.google.com/staging/channel/generator
[ 4.471s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel opened: 515ms (1629298473021)
concurrent request limit : 10
isSpdyEnabled : true
[ 4.472s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8
[ 4.472s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 4.474s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8&RID=rpc&SID=ld9I1OwtI0vILTdQnLm_Rw&CI=0&AID=0&TYPE=xmlhttp&zx=fxdrvmbd8ht1&t=1
null
[ 4.475s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 4.475s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (11190) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=11190&CVER=22&X-HTTP-Session-Id=gsessionid&zx=ld8vbd7z0xfz&t=1
4 200
[ 4.476s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 4.476s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 4.477s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 4.755s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8&RID=rpc&SID=ld9I1OwtI0vILTdQnLm_Rw&CI=0&AID=0&TYPE=xmlhttp&zx=fxdrvmbd8ht1&t=1
3 200
[ 4.757s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 4.757s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 4.959s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8&RID=rpc&SID=ld9I1OwtI0vILTdQnLm_Rw&CI=0&AID=0&TYPE=xmlhttp&zx=fxdrvmbd8ht1&t=1
3 200
[ 4.962s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 4.963s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 5.460s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8&RID=rpc&SID=ld9I1OwtI0vILTdQnLm_Rw&CI=0&AID=0&TYPE=xmlhttp&zx=fxdrvmbd8ht1&t=1
3 200
[ 5.461s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 5.462s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 5.961s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8&RID=rpc&SID=ld9I1OwtI0vILTdQnLm_Rw&CI=0&AID=0&TYPE=xmlhttp&zx=fxdrvmbd8ht1&t=1
3 200
[ 5.964s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 5.965s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 6.464s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=7dJS0T81DpXnHH_POCrrcmhAeHvkEg_DjBHwloq2NpU&VER=8&RID=rpc&SID=ld9I1OwtI0vILTdQnLm_Rw&CI=0&AID=0&TYPE=xmlhttp&zx=fxdrvmbd8ht1&t=1
3 200
[ 6.466s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 6.467s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 6.468s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] completed successfully.
********************************************************
[ 6.469s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 6.472s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[ 6.472s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel closed
[ 7.974s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] STARTING at 2021-08-18T14:54:36.524Z]
********************************************************
[ 7.977s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] createWebChannel("https://webchannel.googleapis.com/staging/channel/generator", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true,"detectBufferingProxy":true})
[ 7.979s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] open()
[ 7.979s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 7.980s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 7.981s] [diagnostic] RequestStat event: 0
[ 7.981s] [diagnostic] RequestStat event: 0
[ 7.982s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 7.982s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 7.983s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":500,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 7.984s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 7.985s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 7.986s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 7.987s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (54375) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=54375&CVER=22&X-HTTP-Session-Id=gsessionid&zx=oks91yay7adb&t=1
count=1&ofs=0&req0___data__=%7B%22message%22%3A%22Hello%22%2C%22message_size%22%3A0%2C%22delay_ms%22%3A0%2C%22num_messages%22%3A5%2C%22message_interval%22%3A500%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 8.170s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (54375) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=54375&CVER=22&X-HTTP-Session-Id=gsessionid&zx=oks91yay7adb&t=1
3 200
[ 8.171s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (54375): [[0,["c","Hzn_9SLKLmnwLYMdTJtsWg","",8,12,30000]]]
[ 8.171s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 8.172s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 8.172s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 8.173s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 8.173s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel opened: 196ms (1629298476723)
concurrent request limit : 10
isSpdyEnabled : true
[ 8.173s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 187ms
[ 8.174s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8
[ 8.175s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 8.176s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8&RID=rpc&SID=Hzn_9SLKLmnwLYMdTJtsWg&CI=0&AID=0&TYPE=xmlhttp&zx=x4cvqa8qip0r&t=1
null
[ 8.177s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 8.177s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 374
[ 8.178s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (54375) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=54375&CVER=22&X-HTTP-Session-Id=gsessionid&zx=oks91yay7adb&t=1
4 200
[ 8.178s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 8.179s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 8.179s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 8.350s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8&RID=rpc&SID=Hzn_9SLKLmnwLYMdTJtsWg&CI=0&AID=0&TYPE=xmlhttp&zx=x4cvqa8qip0r&t=1
3 200
[ 8.352s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 8.353s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 8.354s] [goog.labs.net.webChannel.WebChannelDebug] Great, no buffering proxy detected. Bytes received: 28
[ 8.355s] [goog.labs.net.webChannel.WebChannelDebug] Cancel the BP detection timer.
[ 8.355s] [diagnostic] RequestStat event: 11
[ 8.356s] [diagnostic] RequestStat event: 11
[ 8.356s] [diagnostic] RequestStat event: 11
[ 8.672s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8&RID=rpc&SID=Hzn_9SLKLmnwLYMdTJtsWg&CI=0&AID=0&TYPE=xmlhttp&zx=x4cvqa8qip0r&t=1
3 200
[ 8.675s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 8.675s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 9.173s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8&RID=rpc&SID=Hzn_9SLKLmnwLYMdTJtsWg&CI=0&AID=0&TYPE=xmlhttp&zx=x4cvqa8qip0r&t=1
3 200
[ 9.175s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 9.177s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 9.676s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8&RID=rpc&SID=Hzn_9SLKLmnwLYMdTJtsWg&CI=0&AID=0&TYPE=xmlhttp&zx=x4cvqa8qip0r&t=1
3 200
[ 9.678s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 9.679s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 10.174s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=e26JjMdxcHSvtbxJdK7p_A8v0FokyMYHBVLxC3nNH84&VER=8&RID=rpc&SID=Hzn_9SLKLmnwLYMdTJtsWg&CI=0&AID=0&TYPE=xmlhttp&zx=x4cvqa8qip0r&t=1
3 200
[ 10.177s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 10.178s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 10.179s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] completed successfully.
********************************************************
[ 10.180s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 10.182s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 10.183s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel closed
[ 11.689s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] STARTING at 2021-08-18T14:54:40.239Z]
********************************************************
[ 11.692s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] createWebChannel("https://webchannel.googleapis.com/staging/channel/generator", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true,"forceLongPolling":true})
[ 11.694s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] open()
[ 11.694s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 11.695s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 11.696s] [diagnostic] RequestStat event: 0
[ 11.697s] [diagnostic] RequestStat event: 0
[ 11.697s] [diagnostic] RequestStat event: 0
[ 11.698s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 11.699s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 11.699s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":500,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 11.701s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 11.701s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 11.702s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 11.704s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (31988) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=31988&CVER=22&X-HTTP-Session-Id=gsessionid&zx=cf8ny9qditfc&t=1
count=1&ofs=0&req0___data__=%7B%22message%22%3A%22Hello%22%2C%22message_size%22%3A0%2C%22delay_ms%22%3A0%2C%22num_messages%22%3A5%2C%22message_interval%22%3A500%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 11.881s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (31988) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=31988&CVER=22&X-HTTP-Session-Id=gsessionid&zx=cf8ny9qditfc&t=1
3 200
[ 11.883s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (31988): [[0,["c","kqBpNAf7dwBnDLiDKAenBQ","",8,12,30000]]]
[ 11.884s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 11.885s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 11.886s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 11.886s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 11.887s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel opened: 195ms (1629298480437)
concurrent request limit : 10
isSpdyEnabled : true
[ 11.888s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8
[ 11.888s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 11.890s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=0&TYPE=xmlhttp&zx=jybkyjtrf64w&t=1
null
[ 11.890s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 11.891s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (31988) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=31988&CVER=22&X-HTTP-Session-Id=gsessionid&zx=cf8ny9qditfc&t=1
4 200
[ 11.891s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 11.892s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 11.892s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 12.061s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=0&TYPE=xmlhttp&zx=jybkyjtrf64w&t=1
3 200
[ 12.064s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 12.064s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 12.066s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=0&TYPE=xmlhttp&zx=jybkyjtrf64w&t=1
4 200
[ 12.067s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 12.068s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 12.070s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=1&TYPE=xmlhttp&zx=ydu1p9i80q4p&t=1
null
[ 12.070s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 12.254s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=1&TYPE=xmlhttp&zx=ydu1p9i80q4p&t=1
3 200
[ 12.256s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,["noop"]]]
[ 12.374s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=1&TYPE=xmlhttp&zx=ydu1p9i80q4p&t=1
3 200
[ 12.376s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 12.377s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 12.381s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=1&TYPE=xmlhttp&zx=ydu1p9i80q4p&t=1
4 200
[ 12.383s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 12.383s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 12.385s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=3&TYPE=xmlhttp&zx=oq1y0lle0yh9&t=1
null
[ 12.385s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 12.559s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=3&TYPE=xmlhttp&zx=oq1y0lle0yh9&t=1
3 200
[ 12.562s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,["noop"]]]
[ 12.877s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=3&TYPE=xmlhttp&zx=oq1y0lle0yh9&t=1
3 200
[ 12.880s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 12.881s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 12.885s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=3&TYPE=xmlhttp&zx=oq1y0lle0yh9&t=1
4 200
[ 12.887s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 12.888s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 12.889s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=5&TYPE=xmlhttp&zx=upryo642hd1g&t=1
null
[ 12.890s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 13.060s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=5&TYPE=xmlhttp&zx=upryo642hd1g&t=1
3 200
[ 13.063s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[6,["noop"]]]
[ 13.377s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=5&TYPE=xmlhttp&zx=upryo642hd1g&t=1
3 200
[ 13.380s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[7,{"message":"Hello"}]]
[ 13.381s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 13.385s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=5&TYPE=xmlhttp&zx=upryo642hd1g&t=1
4 200
[ 13.387s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 13.387s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 13.389s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=7&TYPE=xmlhttp&zx=sxqg837fnlyi&t=1
null
[ 13.389s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 13.558s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=7&TYPE=xmlhttp&zx=sxqg837fnlyi&t=1
3 200
[ 13.561s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[8,["noop"]]]
[ 13.878s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=99skPakItpIad5KLqDFUwnWIOXT2Pq3iK6GQiwvGPRA&VER=8&RID=rpc&SID=kqBpNAf7dwBnDLiDKAenBQ&CI=1&AID=7&TYPE=xmlhttp&zx=sxqg837fnlyi&t=1
3 200
[ 13.881s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[9,{"message":"Hello"}]]
[ 13.882s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 13.883s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] completed successfully.
********************************************************
[ 13.884s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 13.886s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 13.887s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel closed
[ 15.393s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] STARTING at 2021-08-18T14:54:43.943Z]
********************************************************
[ 15.397s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] 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,"messageUrlParams":{"database":"projects/debug-my/databases/(default)"}})
[ 15.398s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] open()
[ 15.399s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 15.400s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 15.400s] [diagnostic] RequestStat event: 0
[ 15.401s] [diagnostic] RequestStat event: 0
[ 15.402s] [diagnostic] RequestStat event: 0
[ 15.403s] [diagnostic] RequestStat event: 0
[ 15.403s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 15.404s] [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
[ 15.405s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] 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}})
[ 15.406s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.406s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 15.407s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 15.409s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (26316) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=26316&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gotruycha0iv&t=1
count=1&ofs=0&req0___data__=%7B%22database%22%3A%22projects%2Fdebug-my%2Fdatabases%2F(default)%22%2C%22addTarget%22%3A%7B%22query%22%3A%7B%22structuredQuery%22%3A%7B%22from%22%3A%5B%7B%22collectionId%22%3A%22debug%22%7D%5D%2C%22orderBy%22%3A%5B%7B%22field%22%3A%7B%22fieldPath%22%3A%22__name__%22%7D%2C%22direction%22%3A%22ASCENDING%22%7D%5D%7D%2C%22parent%22%3A%22projects%2Fdebug-my%2Fdatabases%2F(default)%2Fdocuments%22%7D%2C%22targetId%22%3A2%7D%7D
[ 15.627s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (26316) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=26316&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gotruycha0iv&t=1
3 200
[ 15.630s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (26316): [[0,["c","9a2N8LBJLTgEhhd05DguBg","",8,12,30000]]]
[ 15.630s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 15.631s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 15.631s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 15.632s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.634s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel opened: 237ms (1629298484184)
concurrent request limit : 10
isSpdyEnabled : true
[ 15.635s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=RJoDCyQbohtdHc0MJ0im-JrUHeMjo6WQsJ0cBYc9kjs&VER=8
[ 15.635s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 15.637s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=RJoDCyQbohtdHc0MJ0im-JrUHeMjo6WQsJ0cBYc9kjs&VER=8&RID=rpc&SID=9a2N8LBJLTgEhhd05DguBg&CI=0&AID=0&TYPE=xmlhttp&zx=3gmbuysq5lxw&t=1
null
[ 15.637s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 15.638s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (26316) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=26316&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gotruycha0iv&t=1
4 200
[ 15.639s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 15.640s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 15.641s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 15.727s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=RJoDCyQbohtdHc0MJ0im-JrUHeMjo6WQsJ0cBYc9kjs&VER=8&RID=rpc&SID=9a2N8LBJLTgEhhd05DguBg&CI=0&AID=0&TYPE=xmlhttp&zx=3gmbuysq5lxw&t=1
3 200
[ 15.729s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,[{
"targetChange": {
"targetChangeType": "ADD",
"targetIds": [
2
]
}
}
]]]
[ 15.731s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 15.786s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=RJoDCyQbohtdHc0MJ0im-JrUHeMjo6WQsJ0cBYc9kjs&VER=8&RID=rpc&SID=9a2N8LBJLTgEhhd05DguBg&CI=0&AID=0&TYPE=xmlhttp&zx=3gmbuysq5lxw&t=1
3 200
[ 15.789s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[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
]
}
}
]]]
[ 15.790s] [diagnostic] >>> [TEST 5/7: 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]}}]
[ 15.791s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[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
]
}
}
]]]
[ 15.792s] [diagnostic] >>> [TEST 5/7: 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]}}]
[ 15.793s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[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
]
}
}
]]]
[ 15.794s] [diagnostic] >>> [TEST 5/7: 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]}}]
[ 15.795s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[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
]
}
}
]]]
[ 15.797s] [diagnostic] >>> [TEST 5/7: 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]}}]
[ 15.797s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] completed successfully.
********************************************************
[ 15.798s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 15.799s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.800s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel closed
[ 17.303s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] STARTING at 2021-08-18T14:54:45.853Z]
********************************************************
[ 17.306s] [diagnostic] >>> [TEST 6/7: 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,"detectBufferingProxy":true,"messageUrlParams":{"database":"projects/debug-my/databases/(default)"}})
[ 17.308s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] open()
[ 17.309s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 17.310s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 17.310s] [diagnostic] RequestStat event: 0
[ 17.311s] [diagnostic] RequestStat event: 0
[ 17.312s] [diagnostic] RequestStat event: 0
[ 17.313s] [diagnostic] RequestStat event: 0
[ 17.313s] [diagnostic] RequestStat event: 0
[ 17.314s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 17.315s] [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
[ 17.316s] [diagnostic] >>> [TEST 6/7: 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}})
[ 17.317s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 17.317s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 17.318s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 17.319s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (18649) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=18649&CVER=22&X-HTTP-Session-Id=gsessionid&zx=c5zpxmvcetza&t=1
count=1&ofs=0&req0___data__=%7B%22database%22%3A%22projects%2Fdebug-my%2Fdatabases%2F(default)%22%2C%22addTarget%22%3A%7B%22query%22%3A%7B%22structuredQuery%22%3A%7B%22from%22%3A%5B%7B%22collectionId%22%3A%22debug%22%7D%5D%2C%22orderBy%22%3A%5B%7B%22field%22%3A%7B%22fieldPath%22%3A%22__name__%22%7D%2C%22direction%22%3A%22ASCENDING%22%7D%5D%7D%2C%22parent%22%3A%22projects%2Fdebug-my%2Fdatabases%2F(default)%2Fdocuments%22%7D%2C%22targetId%22%3A2%7D%7D
[ 17.379s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (18649) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=18649&CVER=22&X-HTTP-Session-Id=gsessionid&zx=c5zpxmvcetza&t=1
3 200
[ 17.380s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (18649): [[0,["c","KsfV3boTJY_lFCHZ6crVsg","",8,12,30000]]]
[ 17.381s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 17.381s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 17.382s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 17.382s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 17.383s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel opened: 77ms (1629298485933)
concurrent request limit : 10
isSpdyEnabled : true
[ 17.383s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 65ms
[ 17.384s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=EGyok8lNDqNe1vDcfTxOXn_4UOjXlzKgcboBwlWXFu4&VER=8
[ 17.384s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 17.386s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=EGyok8lNDqNe1vDcfTxOXn_4UOjXlzKgcboBwlWXFu4&VER=8&RID=rpc&SID=KsfV3boTJY_lFCHZ6crVsg&CI=0&AID=0&TYPE=xmlhttp&zx=xqt14mtnby3s&t=1
null
[ 17.386s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 17.387s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 130
[ 17.388s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (18649) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=18649&CVER=22&X-HTTP-Session-Id=gsessionid&zx=c5zpxmvcetza&t=1
4 200
[ 17.389s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 17.389s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 17.389s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 17.520s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timeout reached.
[ 17.523s] [goog.labs.net.webChannel.WebChannelDebug] Buffering proxy detected and switch to long-polling!
[ 17.524s] [diagnostic] RequestStat event: 10
[ 17.525s] [diagnostic] RequestStat event: 10
[ 17.526s] [diagnostic] RequestStat event: 10
[ 17.526s] [diagnostic] RequestStat event: 10
[ 17.527s] [diagnostic] RequestStat event: 10
[ 17.528s] [diagnostic] RequestStat event: 10
[ 17.529s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 17.531s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=EGyok8lNDqNe1vDcfTxOXn_4UOjXlzKgcboBwlWXFu4&VER=8&RID=rpc&SID=KsfV3boTJY_lFCHZ6crVsg&CI=1&AID=0&TYPE=xmlhttp&zx=7det9c1q0koc&t=1
null
[ 17.532s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 47.453s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 47.627s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=EGyok8lNDqNe1vDcfTxOXn_4UOjXlzKgcboBwlWXFu4&VER=8&RID=rpc&SID=KsfV3boTJY_lFCHZ6crVsg&CI=1&AID=0&TYPE=xmlhttp&zx=7det9c1q0koc&t=1
3 200
[ 47.630s] [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": "CgkI34X16+m68gI=",
"readTime": "2021-08-18T14:54:45.969631Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkI34X16+m68gI=",
"readTime": "2021-08-18T14:54:45.969631Z"
}
}
]],[9,["noop"]]]
[ 47.634s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 47.635s] [diagnostic] >>> [TEST 6/7: 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]}}]
[ 47.636s] [diagnostic] >>> [TEST 6/7: 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]}}]
[ 47.637s] [diagnostic] >>> [TEST 6/7: 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]}}]
[ 47.637s] [diagnostic] >>> [TEST 6/7: 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]}}]
[ 47.638s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 47.639s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 47.640s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 47.641s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel closed
[ 49.142s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] STARTING at 2021-08-18T14:55:17.692Z]
********************************************************
[ 49.145s] [diagnostic] >>> [TEST 7/7: 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)"}})
[ 49.148s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] open()
[ 49.149s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 49.150s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 49.151s] [diagnostic] RequestStat event: 0
[ 49.152s] [diagnostic] RequestStat event: 0
[ 49.153s] [diagnostic] RequestStat event: 0
[ 49.154s] [diagnostic] RequestStat event: 0
[ 49.155s] [diagnostic] RequestStat event: 0
[ 49.156s] [diagnostic] RequestStat event: 0
[ 49.156s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 49.157s] [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
[ 49.158s] [diagnostic] >>> [TEST 7/7: 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}})
[ 49.159s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 49.160s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 49.160s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 49.162s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (60670) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=60670&CVER=22&X-HTTP-Session-Id=gsessionid&zx=xcywkk3g7dvz&t=1
count=1&ofs=0&req0___data__=%7B%22database%22%3A%22projects%2Fdebug-my%2Fdatabases%2F(default)%22%2C%22addTarget%22%3A%7B%22query%22%3A%7B%22structuredQuery%22%3A%7B%22from%22%3A%5B%7B%22collectionId%22%3A%22debug%22%7D%5D%2C%22orderBy%22%3A%5B%7B%22field%22%3A%7B%22fieldPath%22%3A%22__name__%22%7D%2C%22direction%22%3A%22ASCENDING%22%7D%5D%7D%2C%22parent%22%3A%22projects%2Fdebug-my%2Fdatabases%2F(default)%2Fdocuments%22%7D%2C%22targetId%22%3A2%7D%7D
[ 49.223s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (60670) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=60670&CVER=22&X-HTTP-Session-Id=gsessionid&zx=xcywkk3g7dvz&t=1
3 200
[ 49.225s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (60670): [[0,["c","yHNveAW2v2aZFVp6wOrEEg","",8,12,30000]]]
[ 49.225s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 49.226s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 49.226s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 49.227s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 49.228s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel opened: 83ms (1629298517778)
concurrent request limit : 10
isSpdyEnabled : true
[ 49.229s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=6L3he0ctWj-vm5ZNAu8ShL_4XxXxBQ63onlLzYY8RRw&VER=8
[ 49.229s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 49.231s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=6L3he0ctWj-vm5ZNAu8ShL_4XxXxBQ63onlLzYY8RRw&VER=8&RID=rpc&SID=yHNveAW2v2aZFVp6wOrEEg&CI=1&AID=0&TYPE=xmlhttp&zx=d1aqyuxih2g0&t=1
null
[ 49.231s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 49.232s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (60670) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=60670&CVER=22&X-HTTP-Session-Id=gsessionid&zx=xcywkk3g7dvz&t=1
4 200
[ 49.233s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 49.234s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 49.234s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 49.286s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=6L3he0ctWj-vm5ZNAu8ShL_4XxXxBQ63onlLzYY8RRw&VER=8&RID=rpc&SID=yHNveAW2v2aZFVp6wOrEEg&CI=1&AID=0&TYPE=xmlhttp&zx=d1aqyuxih2g0&t=1
3 200
[ 49.287s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,[{
"targetChange": {
"targetChangeType": "ADD",
"targetIds": [
2
]
}
}
]]]
[ 49.288s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 49.289s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=6L3he0ctWj-vm5ZNAu8ShL_4XxXxBQ63onlLzYY8RRw&VER=8&RID=rpc&SID=yHNveAW2v2aZFVp6wOrEEg&CI=1&AID=0&TYPE=xmlhttp&zx=d1aqyuxih2g0&t=1
4 200
[ 49.290s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 49.291s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 49.292s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=6L3he0ctWj-vm5ZNAu8ShL_4XxXxBQ63onlLzYY8RRw&VER=8&RID=rpc&SID=yHNveAW2v2aZFVp6wOrEEg&CI=1&AID=1&TYPE=xmlhttp&zx=4nbhvi7011o3&t=1
null
[ 49.293s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 49.411s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=6L3he0ctWj-vm5ZNAu8ShL_4XxXxBQ63onlLzYY8RRw&VER=8&RID=rpc&SID=yHNveAW2v2aZFVp6wOrEEg&CI=1&AID=1&TYPE=xmlhttp&zx=4nbhvi7011o3&t=1
3 200
[ 49.414s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[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": "CgkIuZiM++m68gI=",
"readTime": "2021-08-18T14:55:17.806137Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkIuZiM++m68gI=",
"readTime": "2021-08-18T14:55:17.806137Z"
}
}
]]]
[ 49.418s] [diagnostic] >>> [TEST 7/7: 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]}}]
[ 49.419s] [diagnostic] >>> [TEST 7/7: 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]}}]
[ 49.419s] [diagnostic] >>> [TEST 7/7: 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]}}]
[ 49.420s] [diagnostic] >>> [TEST 7/7: 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]}}]
[ 49.421s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 49.422s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 49.423s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 49.424s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel closed
[ 50.930s] [diagnostic]
********************************************************
All tests done.
1: webchannel.googleapis.com with default options: SUCCEEDED (2442ms)
2: webchannel.sandbox.google.com with default options: SUCCEEDED (2514ms)
3: webchannel.googleapis.com with detectBufferingProxy: SUCCEEDED (2205ms)
4: webchannel.googleapis.com with forceLongPolling: SUCCEEDED (2194ms)
5: Firestore listen test with default options: SUCCEEDED (404ms)
6: Firestore listen test with forceLongPolling: SUCCEEDED (30335ms)
7: Firestore listen test with forceLongPolling: SUCCEEDED (279ms)
********************************************************
[ 51.472s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 55.185s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 58.890s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 60.804s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 93.743s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 95.345s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
@btjones
Copy link
Author

btjones commented Aug 18, 2021

Response to request for test results: firebase/firebase-js-sdk#1674 (comment)

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