Skip to content

Instantly share code, notes, and snippets.

@srand2
Created August 18, 2021 11:38
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save srand2/6d4dcdb9832fffdc7abfbf1869211ff1 to your computer and use it in GitHub Desktop.
Save srand2/6d4dcdb9832fffdc7abfbf1869211ff1 to your computer and use it in GitHub Desktop.
debug my firebase app test output using burpsuite pro proxy
[ 0.001s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] STARTING at 2021-08-18T11:32:40.125Z]
********************************************************
[ 0.012s] [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.016s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] open()
[ 0.017s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 0.017s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 0.018s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 0.019s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 0.020s] [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.021s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 0.021s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.021s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 0.024s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (31614) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=31614&CVER=22&X-HTTP-Session-Id=gsessionid&zx=sendpuxipeh9&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
[ 1.562s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (31614) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=31614&CVER=22&X-HTTP-Session-Id=gsessionid&zx=sendpuxipeh9&t=1
3 200
[ 1.563s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (31614): [[0,["c","VgT8sq9y3t6m-LR8gGfVVQ","",8,12,30000]]]
[ 1.564s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 1.564s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 1.564s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 1.565s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 1.565s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel opened: 1553ms (1629286361689)
concurrent request limit : 10
isSpdyEnabled : true
[ 1.565s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=ZsDohDfW2Ch5Rj_v4yq_OG_GiV7JlVh6fxTlsIZh0J4&VER=8
[ 1.566s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 1.567s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=ZsDohDfW2Ch5Rj_v4yq_OG_GiV7JlVh6fxTlsIZh0J4&VER=8&RID=rpc&SID=VgT8sq9y3t6m-LR8gGfVVQ&CI=0&AID=0&TYPE=xmlhttp&zx=dajxlcfk7o91&t=1
null
[ 1.568s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 1.569s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (31614) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=31614&CVER=22&X-HTTP-Session-Id=gsessionid&zx=sendpuxipeh9&t=1
4 200
[ 1.570s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 1.570s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 1.570s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 47.523s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=ZsDohDfW2Ch5Rj_v4yq_OG_GiV7JlVh6fxTlsIZh0J4&VER=8&RID=rpc&SID=VgT8sq9y3t6m-LR8gGfVVQ&CI=0&AID=0&TYPE=xmlhttp&zx=dajxlcfk7o91&t=1
[ 47.524s] [diagnostic] RequestStat event: 17
[ 47.525s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 47.526s] [goog.labs.net.webChannel.WebChannelDebug] Maybe retrying, last error: HttpConnection timeout
[ 47.526s] [goog.labs.net.webChannel.WebChannelDebug] Going to retry GET
[ 47.528s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 47.532s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 2]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=ZsDohDfW2Ch5Rj_v4yq_OG_GiV7JlVh6fxTlsIZh0J4&VER=8&RID=rpc&SID=VgT8sq9y3t6m-LR8gGfVVQ&CI=0&AID=0&TYPE=xmlhttp&zx=3dr0xskzttaa&t=2
null
[ 47.533s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 75.522s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] TIMED OUT
********************************************************
[ 75.523s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 75.524s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 75.525s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel closed
[ 77.522s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] STARTING at 2021-08-18T11:33:57.646Z]
********************************************************
[ 77.523s] [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})
[ 77.524s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] open()
[ 77.524s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 77.524s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 77.525s] [diagnostic] RequestStat event: 0
[ 77.525s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 77.526s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[ 77.526s] [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})
[ 77.527s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[ 77.527s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 77.527s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 77.529s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (21082) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=21082&CVER=22&X-HTTP-Session-Id=gsessionid&zx=pvpxah345v7j&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
[ 79.097s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (21082) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=21082&CVER=22&X-HTTP-Session-Id=gsessionid&zx=pvpxah345v7j&t=1
3 200
[ 79.100s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (21082): [[0,["c","yils7gJMCQw7PEq842tdpQ","",8,12,30000]]]
[ 79.101s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 79.102s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 79.102s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 79.102s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.sandbox.google.com/staging/channel/generator
[ 79.103s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel opened: 1580ms (1629286439227)
concurrent request limit : 10
isSpdyEnabled : true
[ 79.104s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=2fEQgxi5cEH4mT_s5SrUmxaDWqp-pi4AkmbXNFccw8g&VER=8
[ 79.105s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 79.106s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=2fEQgxi5cEH4mT_s5SrUmxaDWqp-pi4AkmbXNFccw8g&VER=8&RID=rpc&SID=yils7gJMCQw7PEq842tdpQ&CI=0&AID=0&TYPE=xmlhttp&zx=nf043arjyszp&t=1
null
[ 79.107s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 79.107s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (21082) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=21082&CVER=22&X-HTTP-Session-Id=gsessionid&zx=pvpxah345v7j&t=1
4 200
[ 79.108s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 79.109s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 79.109s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[120.525s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[124.522s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=2fEQgxi5cEH4mT_s5SrUmxaDWqp-pi4AkmbXNFccw8g&VER=8&RID=rpc&SID=yils7gJMCQw7PEq842tdpQ&CI=0&AID=0&TYPE=xmlhttp&zx=nf043arjyszp&t=1
[124.523s] [diagnostic] RequestStat event: 17
[124.524s] [diagnostic] RequestStat event: 17
[124.525s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[124.526s] [goog.labs.net.webChannel.WebChannelDebug] Maybe retrying, last error: HttpConnection timeout
[124.527s] [goog.labs.net.webChannel.WebChannelDebug] Going to retry GET
[124.529s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[124.530s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 2]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=2fEQgxi5cEH4mT_s5SrUmxaDWqp-pi4AkmbXNFccw8g&VER=8&RID=rpc&SID=yils7gJMCQw7PEq842tdpQ&CI=0&AID=0&TYPE=xmlhttp&zx=19c2hkvkkjdf&t=2
null
[124.531s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[153.521s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] TIMED OUT
********************************************************
[153.523s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[153.524s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[153.524s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel closed
[155.521s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] STARTING at 2021-08-18T11:35:15.645Z]
********************************************************
[155.522s] [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})
[155.523s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] open()
[155.523s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[155.523s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[155.523s] [diagnostic] RequestStat event: 0
[155.524s] [diagnostic] RequestStat event: 0
[155.524s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[155.524s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[155.524s] [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})
[155.525s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[155.526s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[155.526s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[155.528s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (79939) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=79939&CVER=22&X-HTTP-Session-Id=gsessionid&zx=i4z4y6nm45ud&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
[156.413s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (79939) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=79939&CVER=22&X-HTTP-Session-Id=gsessionid&zx=i4z4y6nm45ud&t=1
3 200
[156.414s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (79939): [[0,["c","KilbBUWIVe_gmSKoiFSFAg","",8,12,30000]]]
[156.415s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[156.416s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[156.416s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[156.417s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[156.417s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel opened: 895ms (1629286516541)
concurrent request limit : 10
isSpdyEnabled : true
[156.418s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 891ms
[156.418s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=Deg_0uQQCmLWZggU0z67bzQS3-a7l2Rhzv58OnnqqkU&VER=8
[156.419s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[156.420s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=Deg_0uQQCmLWZggU0z67bzQS3-a7l2Rhzv58OnnqqkU&VER=8&RID=rpc&SID=KilbBUWIVe_gmSKoiFSFAg&CI=0&AID=0&TYPE=xmlhttp&zx=1eipw0cguaiu&t=1
null
[156.420s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[156.420s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 1782
[156.421s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (79939) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=79939&CVER=22&X-HTTP-Session-Id=gsessionid&zx=i4z4y6nm45ud&t=1
4 200
[156.421s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[156.421s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[156.422s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[158.521s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timeout reached.
[158.522s] [goog.labs.net.webChannel.WebChannelDebug] Buffering proxy detected and switch to long-polling!
[158.522s] [diagnostic] RequestStat event: 10
[158.522s] [diagnostic] RequestStat event: 10
[158.523s] [diagnostic] RequestStat event: 10
[158.523s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[158.524s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=Deg_0uQQCmLWZggU0z67bzQS3-a7l2Rhzv58OnnqqkU&VER=8&RID=rpc&SID=KilbBUWIVe_gmSKoiFSFAg&CI=1&AID=0&TYPE=xmlhttp&zx=cvrl4t6t24w3&t=1
null
[158.524s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[188.705s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=Deg_0uQQCmLWZggU0z67bzQS3-a7l2Rhzv58OnnqqkU&VER=8&RID=rpc&SID=KilbBUWIVe_gmSKoiFSFAg&CI=1&AID=0&TYPE=xmlhttp&zx=cvrl4t6t24w3&t=1
3 200
[188.707s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}],[3,{"message":"Hello"}],[4,{"message":"Hello"}],[5,{"message":"Hello"}],[6,["noop"]]]
[188.707s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[188.708s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[188.708s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[188.709s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[188.709s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[188.709s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] completed successfully.
********************************************************
[188.710s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[188.711s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[188.711s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel closed
[190.521s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] STARTING at 2021-08-18T11:35:50.645Z]
********************************************************
[190.522s] [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})
[190.523s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] open()
[190.523s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[190.524s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[190.524s] [diagnostic] RequestStat event: 0
[190.524s] [diagnostic] RequestStat event: 0
[190.525s] [diagnostic] RequestStat event: 0
[190.525s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[190.526s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[190.527s] [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})
[190.528s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[190.528s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[190.528s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[190.529s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (2581) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=2581&CVER=22&X-HTTP-Session-Id=gsessionid&zx=qyqr71rva20y&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
[191.199s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (2581) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=2581&CVER=22&X-HTTP-Session-Id=gsessionid&zx=qyqr71rva20y&t=1
3 200
[191.200s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (2581): [[0,["c","a67oIBymZlxzvIjM_ZE8pg","",8,12,30000]]]
[191.201s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[191.201s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[191.201s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[191.202s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[191.203s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel opened: 681ms (1629286551327)
concurrent request limit : 10
isSpdyEnabled : true
[191.204s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8
[191.204s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[191.206s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=0&TYPE=xmlhttp&zx=r87la4rgx9ez&t=1
null
[191.207s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[191.208s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (2581) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=2581&CVER=22&X-HTTP-Session-Id=gsessionid&zx=qyqr71rva20y&t=1
4 200
[191.210s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[191.210s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[191.210s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[191.880s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=0&TYPE=xmlhttp&zx=r87la4rgx9ez&t=1
3 200
[191.881s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}]]
[191.882s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[191.883s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[191.884s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=0&TYPE=xmlhttp&zx=r87la4rgx9ez&t=1
4 200
[191.884s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[191.884s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[191.885s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=2&TYPE=xmlhttp&zx=wq6a8rb45upg&t=1
null
[191.885s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[192.558s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=2&TYPE=xmlhttp&zx=wq6a8rb45upg&t=1
3 200
[192.559s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[192.560s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[192.560s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=2&TYPE=xmlhttp&zx=wq6a8rb45upg&t=1
4 200
[192.561s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[192.562s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[192.563s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=3&TYPE=xmlhttp&zx=prll0w7mtn74&t=1
null
[192.564s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[193.244s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=pAX15F5Q_Ya0plZLuXIxOzHKx63oEAm3UrXc0s-S3sk&VER=8&RID=rpc&SID=a67oIBymZlxzvIjM_ZE8pg&CI=1&AID=3&TYPE=xmlhttp&zx=prll0w7mtn74&t=1
3 200
[193.245s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}],[5,{"message":"Hello"}]]
[193.246s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[193.247s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[193.248s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] completed successfully.
********************************************************
[193.249s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[193.250s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[193.250s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel closed
[195.521s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] STARTING at 2021-08-18T11:35:55.645Z]
********************************************************
[195.522s] [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)"}})
[195.523s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] open()
[195.523s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[195.523s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[195.524s] [diagnostic] RequestStat event: 0
[195.524s] [diagnostic] RequestStat event: 0
[195.525s] [diagnostic] RequestStat event: 0
[195.525s] [diagnostic] RequestStat event: 0
[195.525s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[195.526s] [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
[195.526s] [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}})
[195.527s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[195.527s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[195.528s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[195.529s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (16921) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=16921&CVER=22&X-HTTP-Session-Id=gsessionid&zx=w7al158p7jo&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
[196.224s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (16921) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=16921&CVER=22&X-HTTP-Session-Id=gsessionid&zx=w7al158p7jo&t=1
3 200
[196.225s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (16921): [[0,["c","-zInVUfSlsZJf4_bWa2cQg","",8,12,30000]]]
[196.226s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[196.226s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[196.226s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[196.227s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[196.228s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel opened: 705ms (1629286556351)
concurrent request limit : 10
isSpdyEnabled : true
[196.228s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=4dRaL445gtCkFldJXzRnQWKXA1xxpgOFzza3vmx9vIM&VER=8
[196.229s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[196.230s] [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=4dRaL445gtCkFldJXzRnQWKXA1xxpgOFzza3vmx9vIM&VER=8&RID=rpc&SID=-zInVUfSlsZJf4_bWa2cQg&CI=0&AID=0&TYPE=xmlhttp&zx=sn5y1h1myh3a&t=1
null
[196.231s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[196.232s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (16921) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=16921&CVER=22&X-HTTP-Session-Id=gsessionid&zx=w7al158p7jo&t=1
4 200
[196.232s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[196.233s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[196.234s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[199.520s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[234.521s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[238.520s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[241.521s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=4dRaL445gtCkFldJXzRnQWKXA1xxpgOFzza3vmx9vIM&VER=8&RID=rpc&SID=-zInVUfSlsZJf4_bWa2cQg&CI=0&AID=0&TYPE=xmlhttp&zx=sn5y1h1myh3a&t=1
[241.522s] [diagnostic] RequestStat event: 17
[241.523s] [diagnostic] RequestStat event: 17
[241.523s] [diagnostic] RequestStat event: 17
[241.524s] [diagnostic] RequestStat event: 17
[241.524s] [diagnostic] RequestStat event: 17
[241.525s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[241.525s] [goog.labs.net.webChannel.WebChannelDebug] Maybe retrying, last error: HttpConnection timeout
[241.525s] [goog.labs.net.webChannel.WebChannelDebug] Going to retry GET
[241.527s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[241.528s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 2]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=4dRaL445gtCkFldJXzRnQWKXA1xxpgOFzza3vmx9vIM&VER=8&RID=rpc&SID=-zInVUfSlsZJf4_bWa2cQg&CI=0&AID=0&TYPE=xmlhttp&zx=tj46nnhr1hmb&t=2
null
[241.529s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[271.522s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] TIMED OUT
********************************************************
[271.524s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[271.524s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[271.525s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel closed
[273.521s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] STARTING at 2021-08-18T11:37:13.645Z]
********************************************************
[273.522s] [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)"}})
[273.523s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] open()
[273.523s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[273.524s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[273.524s] [diagnostic] RequestStat event: 0
[273.525s] [diagnostic] RequestStat event: 0
[273.525s] [diagnostic] RequestStat event: 0
[273.526s] [diagnostic] RequestStat event: 0
[273.526s] [diagnostic] RequestStat event: 0
[273.527s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[273.527s] [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
[273.528s] [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}})
[273.529s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[273.529s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[273.529s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[273.531s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (49759) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=49759&CVER=22&X-HTTP-Session-Id=gsessionid&zx=izvkp6gx022y&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
[273.705s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (49759) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=49759&CVER=22&X-HTTP-Session-Id=gsessionid&zx=izvkp6gx022y&t=1
3 200
[273.707s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (49759): [[0,["c","PbYCcHukDEJeq2V_A5xA7w","",8,12,30000]]]
[273.708s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[273.710s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[273.711s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[273.712s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[273.713s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel opened: 191ms (1629286633837)
concurrent request limit : 10
isSpdyEnabled : true
[273.714s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 184ms
[273.714s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=yLWfqLu-5aPpsz2zInGR7DHtIE0UontA25j2Sv2aQO4&VER=8
[273.715s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[273.717s] [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=yLWfqLu-5aPpsz2zInGR7DHtIE0UontA25j2Sv2aQO4&VER=8&RID=rpc&SID=PbYCcHukDEJeq2V_A5xA7w&CI=0&AID=0&TYPE=xmlhttp&zx=u66dnel8i0jt&t=1
null
[273.718s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[273.719s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 368
[273.720s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (49759) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=49759&CVER=22&X-HTTP-Session-Id=gsessionid&zx=izvkp6gx022y&t=1
4 200
[273.721s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[273.721s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[273.721s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[274.521s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timeout reached.
[274.522s] [goog.labs.net.webChannel.WebChannelDebug] Buffering proxy detected and switch to long-polling!
[274.523s] [diagnostic] RequestStat event: 10
[274.523s] [diagnostic] RequestStat event: 10
[274.523s] [diagnostic] RequestStat event: 10
[274.524s] [diagnostic] RequestStat event: 10
[274.524s] [diagnostic] RequestStat event: 10
[274.525s] [diagnostic] RequestStat event: 10
[274.525s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[274.526s] [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=yLWfqLu-5aPpsz2zInGR7DHtIE0UontA25j2Sv2aQO4&VER=8&RID=rpc&SID=PbYCcHukDEJeq2V_A5xA7w&CI=1&AID=0&TYPE=xmlhttp&zx=9y529h7j6lbm&t=1
null
[274.527s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[304.696s] [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=yLWfqLu-5aPpsz2zInGR7DHtIE0UontA25j2Sv2aQO4&VER=8&RID=rpc&SID=PbYCcHukDEJeq2V_A5xA7w&CI=1&AID=0&TYPE=xmlhttp&zx=9y529h7j6lbm&t=1
3 200
[304.697s] [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": "CgkI2Jas2L268gI=",
"readTime": "2021-08-18T11:37:13.769816Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkI2Jas2L268gI=",
"readTime": "2021-08-18T11:37:13.769816Z"
}
}
]],[9,["noop"]]]
[304.698s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[304.699s] [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]}}]
[304.700s] [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]}}]
[304.700s] [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]}}]
[304.700s] [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]}}]
[304.700s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[304.701s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[304.702s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[304.702s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel closed
[306.203s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] STARTING at 2021-08-18T11:37:46.327Z]
********************************************************
[306.203s] [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)"}})
[306.204s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] open()
[306.204s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[306.204s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[306.204s] [diagnostic] RequestStat event: 0
[306.205s] [diagnostic] RequestStat event: 0
[306.205s] [diagnostic] RequestStat event: 0
[306.205s] [diagnostic] RequestStat event: 0
[306.205s] [diagnostic] RequestStat event: 0
[306.205s] [diagnostic] RequestStat event: 0
[306.206s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[306.206s] [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
[306.206s] [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}})
[306.207s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[306.207s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[306.207s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[306.208s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (55970) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=55970&CVER=22&X-HTTP-Session-Id=gsessionid&zx=nk40p119103q&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
[306.378s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (55970) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=55970&CVER=22&X-HTTP-Session-Id=gsessionid&zx=nk40p119103q&t=1
3 200
[306.379s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (55970): [[0,["c","w4e7zNvgiKvTbt7C4AHRxQ","",8,12,30000]]]
[306.380s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[306.381s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[306.382s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[306.382s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[306.382s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel opened: 179ms (1629286666506)
concurrent request limit : 10
isSpdyEnabled : true
[306.383s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=VfTBby5L8FAkwoZ57PP1SZhiK3wYcJZbHBsnZ46g1lM&VER=8
[306.383s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[306.384s] [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=VfTBby5L8FAkwoZ57PP1SZhiK3wYcJZbHBsnZ46g1lM&VER=8&RID=rpc&SID=w4e7zNvgiKvTbt7C4AHRxQ&CI=1&AID=0&TYPE=xmlhttp&zx=5ac5vrnc3l&t=1
null
[306.384s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[306.385s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (55970) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=55970&CVER=22&X-HTTP-Session-Id=gsessionid&zx=nk40p119103q&t=1
4 200
[306.386s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[306.386s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[306.386s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[306.553s] [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=VfTBby5L8FAkwoZ57PP1SZhiK3wYcJZbHBsnZ46g1lM&VER=8&RID=rpc&SID=w4e7zNvgiKvTbt7C4AHRxQ&CI=1&AID=0&TYPE=xmlhttp&zx=5ac5vrnc3l&t=1
3 200
[306.553s] [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": "CgkI5Pn257268gI=",
"readTime": "2021-08-18T11:37:46.452196Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkI5Pn257268gI=",
"readTime": "2021-08-18T11:37:46.452196Z"
}
}
]]]
[306.554s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[306.555s] [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]}}]
[306.555s] [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]}}]
[306.556s] [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]}}]
[306.556s] [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]}}]
[306.556s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[306.557s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[306.557s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[306.557s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel closed
[308.058s] [diagnostic]
********************************************************
All tests done.
1: webchannel.googleapis.com with default options: FAILED (75521ms)
2: webchannel.sandbox.google.com with default options: FAILED (75999ms)
3: webchannel.googleapis.com with detectBufferingProxy: SUCCEEDED (33188ms)
4: webchannel.googleapis.com with forceLongPolling: SUCCEEDED (2727ms)
5: Firestore listen test with default options: FAILED (76001ms)
6: Firestore listen test with forceLongPolling: SUCCEEDED (31179ms)
7: Firestore listen test with forceLongPolling: SUCCEEDED (353ms)
********************************************************
[316.526s] [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