Skip to content

Instantly share code, notes, and snippets.

@fitterman
Created July 8, 2021 16:30
Show Gist options
  • Save fitterman/d6d61adf2ad527cec5accbad0d0353d7 to your computer and use it in GitHub Desktop.
Save fitterman/d6d61adf2ad527cec5accbad0d0353d7 to your computer and use it in GitHub Desktop.
[ 0.007s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] STARTING at 2021-07-08T16:04:53.778Z]
********************************************************
[ 0.041s] [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.050s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] open()
[ 0.053s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 0.054s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 0.058s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 0.066s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 0.067s] [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.071s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 0.074s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.076s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 0.088s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (39347) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=39347&CVER=22&X-HTTP-Session-Id=gsessionid&zx=k1vbpc3g40mz&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.457s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (39347) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=39347&CVER=22&X-HTTP-Session-Id=gsessionid&zx=k1vbpc3g40mz&t=1
3 200
[ 0.461s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (39347): [[0,["c","MMp5xwIhbO4qqdbMW5uFPQ","",8,12,30000]]]
[ 0.465s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 0.466s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 0.467s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 0.469s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 0.471s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel opened: 429ms (1625760294242)
concurrent request limit : 10
isSpdyEnabled : true
[ 0.473s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8
[ 0.477s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 0.481s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8&RID=rpc&SID=MMp5xwIhbO4qqdbMW5uFPQ&CI=0&AID=0&TYPE=xmlhttp&zx=6l69udgrtxi4&t=1
null
[ 0.482s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 0.485s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (39347) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=39347&CVER=22&X-HTTP-Session-Id=gsessionid&zx=k1vbpc3g40mz&t=1
4 200
[ 0.489s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 0.491s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.493s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 0.697s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8&RID=rpc&SID=MMp5xwIhbO4qqdbMW5uFPQ&CI=0&AID=0&TYPE=xmlhttp&zx=6l69udgrtxi4&t=1
3 200
[ 0.698s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 0.700s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 0.957s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8&RID=rpc&SID=MMp5xwIhbO4qqdbMW5uFPQ&CI=0&AID=0&TYPE=xmlhttp&zx=6l69udgrtxi4&t=1
3 200
[ 0.958s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 0.960s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 1.457s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8&RID=rpc&SID=MMp5xwIhbO4qqdbMW5uFPQ&CI=0&AID=0&TYPE=xmlhttp&zx=6l69udgrtxi4&t=1
3 200
[ 1.458s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 1.459s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 1.960s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8&RID=rpc&SID=MMp5xwIhbO4qqdbMW5uFPQ&CI=0&AID=0&TYPE=xmlhttp&zx=6l69udgrtxi4&t=1
3 200
[ 1.963s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 1.965s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 2.572s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=54iLrPS24ot_yHRCl1CII6tgo39jwlL8BHeMdL-QcuQ&VER=8&RID=rpc&SID=MMp5xwIhbO4qqdbMW5uFPQ&CI=0&AID=0&TYPE=xmlhttp&zx=6l69udgrtxi4&t=1
3 200
[ 2.574s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 2.581s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 2.583s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] completed successfully.
********************************************************
[ 2.585s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 2.596s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 2.599s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel closed
[ 4.102s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] STARTING at 2021-07-08T16:04:57.874Z]
********************************************************
[ 4.104s] [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})
[ 4.107s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] open()
[ 4.108s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 4.111s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 4.113s] [diagnostic] RequestStat event: 0
[ 4.114s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 4.116s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[ 4.117s] [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})
[ 4.119s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[ 4.120s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 4.122s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 4.127s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (58533) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=58533&CVER=22&X-HTTP-Session-Id=gsessionid&zx=7t655ww598ts&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.491s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (58533) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=58533&CVER=22&X-HTTP-Session-Id=gsessionid&zx=7t655ww598ts&t=1
3 200
[ 4.494s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (58533): [[0,["c","OsHF_bAuZo00MFj0BMKmFA","",8,12,30000]]]
[ 4.495s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 4.496s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 4.497s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 4.498s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.sandbox.google.com/staging/channel/generator
[ 4.499s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel opened: 395ms (1625760298271)
concurrent request limit : 10
isSpdyEnabled : true
[ 4.500s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8
[ 4.503s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 4.506s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8&RID=rpc&SID=OsHF_bAuZo00MFj0BMKmFA&CI=0&AID=0&TYPE=xmlhttp&zx=r5mcxaknhhf0&t=1
null
[ 4.507s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 4.510s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (58533) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=58533&CVER=22&X-HTTP-Session-Id=gsessionid&zx=7t655ww598ts&t=1
4 200
[ 4.512s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 4.514s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 4.516s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 4.726s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8&RID=rpc&SID=OsHF_bAuZo00MFj0BMKmFA&CI=0&AID=0&TYPE=xmlhttp&zx=r5mcxaknhhf0&t=1
3 200
[ 4.727s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 4.729s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 5.028s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8&RID=rpc&SID=OsHF_bAuZo00MFj0BMKmFA&CI=0&AID=0&TYPE=xmlhttp&zx=r5mcxaknhhf0&t=1
3 200
[ 5.030s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 5.031s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 5.491s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8&RID=rpc&SID=OsHF_bAuZo00MFj0BMKmFA&CI=0&AID=0&TYPE=xmlhttp&zx=r5mcxaknhhf0&t=1
3 200
[ 5.494s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 5.495s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 6.053s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8&RID=rpc&SID=OsHF_bAuZo00MFj0BMKmFA&CI=0&AID=0&TYPE=xmlhttp&zx=r5mcxaknhhf0&t=1
3 200
[ 6.055s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 6.057s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 6.502s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=oO4onfSjf3CI2bs9Vl8v02wH2E703mwoyeBYwxffC1w&VER=8&RID=rpc&SID=OsHF_bAuZo00MFj0BMKmFA&CI=0&AID=0&TYPE=xmlhttp&zx=r5mcxaknhhf0&t=1
3 200
[ 6.505s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 6.507s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 6.510s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] completed successfully.
********************************************************
[ 6.513s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 6.518s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[ 6.519s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel closed
[ 8.024s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] STARTING at 2021-07-08T16:05:01.796Z]
********************************************************
[ 8.027s] [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})
[ 8.029s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] open()
[ 8.030s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 8.031s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 8.033s] [diagnostic] RequestStat event: 0
[ 8.034s] [diagnostic] RequestStat event: 0
[ 8.035s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 8.036s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 8.037s] [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})
[ 8.039s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 8.040s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 8.041s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 8.045s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (10325) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=10325&CVER=22&X-HTTP-Session-Id=gsessionid&zx=3r6rwvm6r0px&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.270s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (10325) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=10325&CVER=22&X-HTTP-Session-Id=gsessionid&zx=3r6rwvm6r0px&t=1
3 200
[ 8.272s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (10325): [[0,["c","49360haeDNvIZIUg_R4aKw","",8,12,30000]]]
[ 8.274s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 8.275s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 8.276s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 8.279s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 8.280s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel opened: 253ms (1625760302052)
concurrent request limit : 10
isSpdyEnabled : true
[ 8.282s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 238ms
[ 8.283s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8
[ 8.284s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 8.288s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8&RID=rpc&SID=49360haeDNvIZIUg_R4aKw&CI=0&AID=0&TYPE=xmlhttp&zx=yd9rb5abwnjs&t=1
null
[ 8.289s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 8.290s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 476
[ 8.292s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (10325) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=10325&CVER=22&X-HTTP-Session-Id=gsessionid&zx=3r6rwvm6r0px&t=1
4 200
[ 8.294s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 8.295s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 8.296s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 8.507s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8&RID=rpc&SID=49360haeDNvIZIUg_R4aKw&CI=0&AID=0&TYPE=xmlhttp&zx=yd9rb5abwnjs&t=1
3 200
[ 8.510s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 8.511s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 8.513s] [goog.labs.net.webChannel.WebChannelDebug] Great, no buffering proxy detected. Bytes received: 28
[ 8.514s] [goog.labs.net.webChannel.WebChannelDebug] Cancel the BP detection timer.
[ 8.515s] [diagnostic] RequestStat event: 11
[ 8.516s] [diagnostic] RequestStat event: 11
[ 8.517s] [diagnostic] RequestStat event: 11
[ 8.767s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8&RID=rpc&SID=49360haeDNvIZIUg_R4aKw&CI=0&AID=0&TYPE=xmlhttp&zx=yd9rb5abwnjs&t=1
3 200
[ 8.770s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 8.771s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 9.269s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8&RID=rpc&SID=49360haeDNvIZIUg_R4aKw&CI=0&AID=0&TYPE=xmlhttp&zx=yd9rb5abwnjs&t=1
3 200
[ 9.270s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 9.271s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 9.771s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8&RID=rpc&SID=49360haeDNvIZIUg_R4aKw&CI=0&AID=0&TYPE=xmlhttp&zx=yd9rb5abwnjs&t=1
3 200
[ 9.772s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 9.773s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 10.272s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=GyVPPv5B33oQjaPlb8JxTMcWPuzW5YgZqduYZuCoCz8&VER=8&RID=rpc&SID=49360haeDNvIZIUg_R4aKw&CI=0&AID=0&TYPE=xmlhttp&zx=yd9rb5abwnjs&t=1
3 200
[ 10.274s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 10.275s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 10.278s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] completed successfully.
********************************************************
[ 10.280s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 10.283s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 10.284s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel closed
[ 11.787s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] STARTING at 2021-07-08T16:05:05.559Z]
********************************************************
[ 11.789s] [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.791s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] open()
[ 11.793s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 11.794s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 11.795s] [diagnostic] RequestStat event: 0
[ 11.796s] [diagnostic] RequestStat event: 0
[ 11.797s] [diagnostic] RequestStat event: 0
[ 11.797s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 11.799s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 11.800s] [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.801s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 11.802s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 11.803s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 11.805s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (18675) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=18675&CVER=22&X-HTTP-Session-Id=gsessionid&zx=rk44u8nla8rr&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
[ 12.025s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (18675) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=18675&CVER=22&X-HTTP-Session-Id=gsessionid&zx=rk44u8nla8rr&t=1
3 200
[ 12.027s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (18675): [[0,["c","UhOCuaWDsZjfNiC3i-lW8Q","",8,12,30000]]]
[ 12.029s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 12.030s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 12.030s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 12.031s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 12.033s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel opened: 244ms (1625760305805)
concurrent request limit : 10
isSpdyEnabled : true
[ 12.034s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8
[ 12.035s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 12.037s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=0&TYPE=xmlhttp&zx=ytj1pg6hbksy&t=1
null
[ 12.038s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 12.040s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (18675) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=18675&CVER=22&X-HTTP-Session-Id=gsessionid&zx=rk44u8nla8rr&t=1
4 200
[ 12.041s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 12.043s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 12.044s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 12.262s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=0&TYPE=xmlhttp&zx=ytj1pg6hbksy&t=1
3 200
[ 12.264s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 12.266s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 12.268s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=0&TYPE=xmlhttp&zx=ytj1pg6hbksy&t=1
4 200
[ 12.269s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 12.270s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 12.272s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=1&TYPE=xmlhttp&zx=i6k8mdh692dv&t=1
null
[ 12.274s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 12.486s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=1&TYPE=xmlhttp&zx=i6k8mdh692dv&t=1
3 200
[ 12.489s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,["noop"]]]
[ 12.525s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=1&TYPE=xmlhttp&zx=i6k8mdh692dv&t=1
4 200
[ 12.526s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 12.528s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 12.529s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 12.530s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 12.532s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=3&TYPE=xmlhttp&zx=uyyswly3krf6&t=1
null
[ 12.534s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 12.748s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=3&TYPE=xmlhttp&zx=uyyswly3krf6&t=1
3 200
[ 12.750s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,["noop"]]]
[ 13.118s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=3&TYPE=xmlhttp&zx=uyyswly3krf6&t=1
3 200
[ 13.119s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 13.121s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 13.123s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=3&TYPE=xmlhttp&zx=uyyswly3krf6&t=1
4 200
[ 13.124s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 13.127s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 13.130s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=5&TYPE=xmlhttp&zx=qart5x2lml4i&t=1
null
[ 13.131s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 13.345s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=5&TYPE=xmlhttp&zx=qart5x2lml4i&t=1
3 200
[ 13.346s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[6,["noop"]]]
[ 13.526s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=5&TYPE=xmlhttp&zx=qart5x2lml4i&t=1
3 200
[ 13.529s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[7,{"message":"Hello"}]]
[ 13.530s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 13.532s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=5&TYPE=xmlhttp&zx=qart5x2lml4i&t=1
4 200
[ 13.533s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 13.534s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 13.536s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=7&TYPE=xmlhttp&zx=8vxmot2d3jdk&t=1
null
[ 13.537s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 13.750s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=7&TYPE=xmlhttp&zx=8vxmot2d3jdk&t=1
3 200
[ 13.753s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[8,["noop"]]]
[ 14.137s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=VUPuN4ygy-8doElJI8_VKq9hk-MEwkS-VpcC6fBHm7I&VER=8&RID=rpc&SID=UhOCuaWDsZjfNiC3i-lW8Q&CI=1&AID=7&TYPE=xmlhttp&zx=8vxmot2d3jdk&t=1
3 200
[ 14.138s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[9,{"message":"Hello"}]]
[ 14.139s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 14.141s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] completed successfully.
********************************************************
[ 14.143s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 14.146s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 14.148s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel closed
[ 15.650s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] STARTING at 2021-07-08T16:05:09.422Z]
********************************************************
[ 15.652s] [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.657s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] open()
[ 15.658s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 15.659s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 15.661s] [diagnostic] RequestStat event: 0
[ 15.662s] [diagnostic] RequestStat event: 0
[ 15.663s] [diagnostic] RequestStat event: 0
[ 15.664s] [diagnostic] RequestStat event: 0
[ 15.665s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 15.666s] [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.667s] [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.668s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.669s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 15.670s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 15.673s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (1303) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=1303&CVER=22&X-HTTP-Session-Id=gsessionid&zx=btwdv07sy9pd&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.869s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (1303) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=1303&CVER=22&X-HTTP-Session-Id=gsessionid&zx=btwdv07sy9pd&t=1
3 200
[ 15.871s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (1303): [[0,["c","W7u8P82Hf4h19GYzDP294w","",8,12,30000]]]
[ 15.872s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 15.873s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 15.874s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 15.875s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.876s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel opened: 224ms (1625760309648)
concurrent request limit : 10
isSpdyEnabled : true
[ 15.878s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=VRJmkkT5gCG82glf3n0aZeHxPPQXthzA8sHLIy9BQYc&VER=8
[ 15.879s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 15.881s] [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=VRJmkkT5gCG82glf3n0aZeHxPPQXthzA8sHLIy9BQYc&VER=8&RID=rpc&SID=W7u8P82Hf4h19GYzDP294w&CI=0&AID=0&TYPE=xmlhttp&zx=7gltpmegtb9&t=1
null
[ 15.882s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 15.884s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (1303) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=1303&CVER=22&X-HTTP-Session-Id=gsessionid&zx=btwdv07sy9pd&t=1
4 200
[ 15.886s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 15.887s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 15.888s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 16.054s] [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=VRJmkkT5gCG82glf3n0aZeHxPPQXthzA8sHLIy9BQYc&VER=8&RID=rpc&SID=W7u8P82Hf4h19GYzDP294w&CI=0&AID=0&TYPE=xmlhttp&zx=7gltpmegtb9&t=1
3 200
[ 16.056s] [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": "CgkI56K2ju3T8QI=",
"readTime": "2021-07-08T16:05:10.595943Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkI56K2ju3T8QI=",
"readTime": "2021-07-08T16:05:10.595943Z"
}
}
]]]
[ 16.061s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 16.062s] [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]}}]
[ 16.063s] [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]}}]
[ 16.065s] [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]}}]
[ 16.066s] [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]}}]
[ 16.067s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] completed successfully.
********************************************************
[ 16.068s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 16.071s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 16.072s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel closed
[ 17.575s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] STARTING at 2021-07-08T16:05:11.347Z]
********************************************************
[ 17.578s] [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.581s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] open()
[ 17.582s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 17.584s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 17.585s] [diagnostic] RequestStat event: 0
[ 17.586s] [diagnostic] RequestStat event: 0
[ 17.587s] [diagnostic] RequestStat event: 0
[ 17.588s] [diagnostic] RequestStat event: 0
[ 17.589s] [diagnostic] RequestStat event: 0
[ 17.590s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 17.591s] [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.593s] [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.594s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 17.596s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 17.597s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 17.599s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (65051) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=65051&CVER=22&X-HTTP-Session-Id=gsessionid&zx=iwlunfdcyex6&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.662s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (65051) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=65051&CVER=22&X-HTTP-Session-Id=gsessionid&zx=iwlunfdcyex6&t=1
3 200
[ 17.664s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (65051): [[0,["c","Qs2zWNLTrUZ39uYgCrkk7Q","",8,12,30000]]]
[ 17.665s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 17.666s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 17.667s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 17.668s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 17.669s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel opened: 91ms (1625760311441)
concurrent request limit : 10
isSpdyEnabled : true
[ 17.670s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 72ms
[ 17.671s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=1Kd2K1V2WtU9k61bLjxB-cYHxW_QcIamEuaadtvQ5OQ&VER=8
[ 17.673s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 17.676s] [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=1Kd2K1V2WtU9k61bLjxB-cYHxW_QcIamEuaadtvQ5OQ&VER=8&RID=rpc&SID=Qs2zWNLTrUZ39uYgCrkk7Q&CI=0&AID=0&TYPE=xmlhttp&zx=q46qns1uabr&t=1
null
[ 17.678s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 17.679s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 144
[ 17.682s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (65051) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=65051&CVER=22&X-HTTP-Session-Id=gsessionid&zx=iwlunfdcyex6&t=1
4 200
[ 17.684s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 17.685s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 17.686s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 17.826s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timeout reached.
[ 17.828s] [goog.labs.net.webChannel.WebChannelDebug] Buffering proxy detected and switch to long-polling!
[ 17.830s] [diagnostic] RequestStat event: 10
[ 17.831s] [diagnostic] RequestStat event: 10
[ 17.832s] [diagnostic] RequestStat event: 10
[ 17.833s] [diagnostic] RequestStat event: 10
[ 17.834s] [diagnostic] RequestStat event: 10
[ 17.835s] [diagnostic] RequestStat event: 10
[ 17.836s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 17.840s] [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=1Kd2K1V2WtU9k61bLjxB-cYHxW_QcIamEuaadtvQ5OQ&VER=8&RID=rpc&SID=Qs2zWNLTrUZ39uYgCrkk7Q&CI=1&AID=0&TYPE=xmlhttp&zx=oc43v6ummvzy&t=1
null
[ 17.842s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 47.601s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 47.900s] [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=1Kd2K1V2WtU9k61bLjxB-cYHxW_QcIamEuaadtvQ5OQ&VER=8&RID=rpc&SID=Qs2zWNLTrUZ39uYgCrkk7Q&CI=1&AID=0&TYPE=xmlhttp&zx=oc43v6ummvzy&t=1
3 200
[ 47.902s] [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": "CgkIzIajj+3T8QI=",
"readTime": "2021-07-08T16:05:12.378188Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkIzIajj+3T8QI=",
"readTime": "2021-07-08T16:05:12.378188Z"
}
}
]],[9,["noop"]]]
[ 47.906s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 47.907s] [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.908s] [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.910s] [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.911s] [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.913s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 47.914s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 47.917s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 47.920s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel closed
[ 49.422s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] STARTING at 2021-07-08T16:05:43.194Z]
********************************************************
[ 49.426s] [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.428s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] open()
[ 49.429s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 49.430s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 49.431s] [diagnostic] RequestStat event: 0
[ 49.432s] [diagnostic] RequestStat event: 0
[ 49.433s] [diagnostic] RequestStat event: 0
[ 49.434s] [diagnostic] RequestStat event: 0
[ 49.435s] [diagnostic] RequestStat event: 0
[ 49.436s] [diagnostic] RequestStat event: 0
[ 49.437s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 49.438s] [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.440s] [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.441s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 49.443s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 49.444s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 49.447s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (50353) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=50353&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5f0vl1udwrlw&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.551s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (50353) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=50353&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5f0vl1udwrlw&t=1
3 200
[ 49.554s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (50353): [[0,["c","-AQ2M0JaacB4_crrsloUuA","",8,12,30000]]]
[ 49.555s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 49.556s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 49.557s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 49.558s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 49.560s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel opened: 134ms (1625760343332)
concurrent request limit : 10
isSpdyEnabled : true
[ 49.561s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=tvlbT8veiQCjBlI-D_VP5MyMqqIfd7MPcdKtnaZLkHI&VER=8
[ 49.563s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 49.565s] [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=tvlbT8veiQCjBlI-D_VP5MyMqqIfd7MPcdKtnaZLkHI&VER=8&RID=rpc&SID=-AQ2M0JaacB4_crrsloUuA&CI=1&AID=0&TYPE=xmlhttp&zx=6ix7vl29l5pw&t=1
null
[ 49.567s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 49.569s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (50353) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=50353&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5f0vl1udwrlw&t=1
4 200
[ 49.570s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 49.572s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 49.573s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 49.741s] [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=tvlbT8veiQCjBlI-D_VP5MyMqqIfd7MPcdKtnaZLkHI&VER=8&RID=rpc&SID=-AQ2M0JaacB4_crrsloUuA&CI=1&AID=0&TYPE=xmlhttp&zx=6ix7vl29l5pw&t=1
3 200
[ 49.743s] [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": "CgkIj6K7nu3T8QI=",
"readTime": "2021-07-08T16:05:44.232207Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkIj6K7nu3T8QI=",
"readTime": "2021-07-08T16:05:44.232207Z"
}
}
]]]
[ 49.746s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 49.747s] [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.749s] [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.751s] [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.753s] [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.755s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 49.756s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 49.758s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 49.760s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel closed
[ 51.264s] [diagnostic]
********************************************************
All tests done.
1: webchannel.googleapis.com with default options: SUCCEEDED (2577ms)
2: webchannel.sandbox.google.com with default options: SUCCEEDED (2408ms)
3: webchannel.googleapis.com with detectBufferingProxy: SUCCEEDED (2254ms)
4: webchannel.googleapis.com with forceLongPolling: SUCCEEDED (2354ms)
5: Firestore listen test with default options: SUCCEEDED (417ms)
6: Firestore listen test with forceLongPolling: SUCCEEDED (30338ms)
7: Firestore listen test with forceLongPolling: SUCCEEDED (333ms)
********************************************************
[ 51.519s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 55.285s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 59.149s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 61.073s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 92.919s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 94.760s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment