Skip to content

Instantly share code, notes, and snippets.

@fitterman
Created July 8, 2021 16:16
Show Gist options
  • Save fitterman/6e3c7949f2875405e1035491dff0399f to your computer and use it in GitHub Desktop.
Save fitterman/6e3c7949f2875405e1035491dff0399f to your computer and use it in GitHub Desktop.
[ 0.002s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] STARTING at 2021-07-08T16:14:47.018Z]
********************************************************
[ 0.006s] [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.007s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] open()
[ 0.007s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 0.008s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 0.009s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 0.011s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 0.011s] [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.012s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 0.013s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.013s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 0.017s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (93607) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=93607&CVER=22&X-HTTP-Session-Id=gsessionid&zx=efanzw6hhfrq&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.338s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (93607) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=93607&CVER=22&X-HTTP-Session-Id=gsessionid&zx=efanzw6hhfrq&t=1
3 200
[ 0.339s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (93607): [[0,["c","xTSFjVCWWoABwUCuDT5OIg","",8,12,30000]]]
[ 0.339s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 0.340s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 0.340s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 0.341s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 0.341s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel opened: 335ms (1625760887357)
concurrent request limit : 10
isSpdyEnabled : true
[ 0.341s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8
[ 0.342s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 0.344s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8&RID=rpc&SID=xTSFjVCWWoABwUCuDT5OIg&CI=0&AID=0&TYPE=xmlhttp&zx=fqm6339gp1ag&t=1
null
[ 0.344s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 0.345s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (93607) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=93607&CVER=22&X-HTTP-Session-Id=gsessionid&zx=efanzw6hhfrq&t=1
4 200
[ 0.345s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 0.346s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 0.346s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 0.602s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8&RID=rpc&SID=xTSFjVCWWoABwUCuDT5OIg&CI=0&AID=0&TYPE=xmlhttp&zx=fqm6339gp1ag&t=1
3 200
[ 0.603s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 0.603s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 0.889s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8&RID=rpc&SID=xTSFjVCWWoABwUCuDT5OIg&CI=0&AID=0&TYPE=xmlhttp&zx=fqm6339gp1ag&t=1
3 200
[ 0.890s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 0.891s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 1.392s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8&RID=rpc&SID=xTSFjVCWWoABwUCuDT5OIg&CI=0&AID=0&TYPE=xmlhttp&zx=fqm6339gp1ag&t=1
3 200
[ 1.393s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 1.394s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 1.906s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8&RID=rpc&SID=xTSFjVCWWoABwUCuDT5OIg&CI=0&AID=0&TYPE=xmlhttp&zx=fqm6339gp1ag&t=1
3 200
[ 1.908s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 1.909s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 2.390s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=3qSke0cQzs81OuEpo67WrltvHL1Bb27DzLrx0W5J6ec&VER=8&RID=rpc&SID=xTSFjVCWWoABwUCuDT5OIg&CI=0&AID=0&TYPE=xmlhttp&zx=fqm6339gp1ag&t=1
3 200
[ 2.391s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 2.392s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[ 2.392s] [diagnostic]
********************************************************
[TEST 1/7: webchannel.googleapis.com with default options] completed successfully.
********************************************************
[ 2.393s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 2.394s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 2.395s] [diagnostic] >>> [TEST 1/7: webchannel.googleapis.com with default options] channel closed
[ 3.897s] [diagnostic]
********************************************************
[TEST 2/7: webchannel.sandbox.google.com with default options] STARTING at 2021-07-08T16:14:50.913Z]
********************************************************
[ 3.897s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] createWebChannel("https://webchannel.sandbox.google.com/staging/channel/generator", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true})
[ 3.898s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] open()
[ 3.899s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 3.899s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 3.900s] [diagnostic] RequestStat event: 0
[ 3.900s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 3.902s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[ 3.902s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":500,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 3.903s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[ 3.904s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 3.904s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 3.905s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (29661) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=29661&CVER=22&X-HTTP-Session-Id=gsessionid&zx=vppmlkyqrkf4&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
[ 3.935s] [goog.labs.net.webChannel.WebChannelDebug] No response text for uri https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=29661&CVER=22&X-HTTP-Session-Id=gsessionid&zx=vppmlkyqrkf4&t=1 status 0
[ 3.936s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (29661) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=29661&CVER=22&X-HTTP-Session-Id=gsessionid&zx=vppmlkyqrkf4&t=1
4 0
[ 3.936s] [diagnostic] RequestStat event: 13
[ 3.937s] [diagnostic] RequestStat event: 13
[ 3.937s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP Bad status 0 (29661)
[ 3.938s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 3.939s] [goog.labs.net.webChannel.WebChannelDebug] Maybe retrying, last error: Non-200 return code (0)
[ 3.939s] [goog.labs.net.webChannel.WebChannelDebug] Exceeded max number of retries
[ 3.939s] [goog.labs.net.webChannel.WebChannelDebug] Error: HTTP request failed
[ 3.940s] [goog.labs.net.webChannel.WebChannelDebug] Error code 2
[ 3.940s] [goog.labs.net.webChannel.WebChannelDebug] TestLoadImage: loading https://www.google.com/images/cleardot.gif?zx=hho2fubuxbhc
[ 3.941s] [goog.labs.net.webChannel.WebChannelDebug] HttpChannel: error - 2
[ 3.942s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel aborted on https://webchannel.sandbox.google.com/staging/channel/generator due to channel error: 2
[ 3.942s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel error: 1
[ 3.942s] [goog.labs.net.webChannel.WebChannelDebug] Number of undelivered maps, pending: 1, outgoing: 0
[ 3.943s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[ 3.943s] [diagnostic] >>> [TEST 2/7: webchannel.sandbox.google.com with default options] channel closed
[ 3.943s] [diagnostic]
********************************************************
Channel closed before test finished.
********************************************************
[ 4.102s] [goog.labs.net.webChannel.WebChannelDebug] TestLoadImage: loaded
[ 4.103s] [goog.labs.net.webChannel.WebChannelDebug] Successfully pinged google.com
[ 4.103s] [diagnostic] RequestStat event: 2
[ 4.103s] [diagnostic] RequestStat event: 2
[ 5.445s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] STARTING at 2021-07-08T16:14:52.461Z]
********************************************************
[ 5.446s] [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})
[ 5.446s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] open()
[ 5.447s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 5.447s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 5.447s] [diagnostic] RequestStat event: 0
[ 5.448s] [diagnostic] RequestStat event: 0
[ 5.448s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 5.449s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 5.449s] [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})
[ 5.450s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 5.450s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 5.450s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 5.451s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (48361) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=48361&CVER=22&X-HTTP-Session-Id=gsessionid&zx=xmfovfbb78ev&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
[ 5.685s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (48361) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=48361&CVER=22&X-HTTP-Session-Id=gsessionid&zx=xmfovfbb78ev&t=1
3 200
[ 5.686s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (48361): [[0,["c","TFWblTDw-5U997UEe2NHZA","",8,12,30000]]]
[ 5.686s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 5.686s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 5.688s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 5.688s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 5.688s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel opened: 242ms (1625760892704)
concurrent request limit : 10
isSpdyEnabled : true
[ 5.689s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 238ms
[ 5.689s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8
[ 5.689s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 5.690s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8&RID=rpc&SID=TFWblTDw-5U997UEe2NHZA&CI=0&AID=0&TYPE=xmlhttp&zx=1228z373pey2&t=1
null
[ 5.690s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 5.690s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 476
[ 5.691s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (48361) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=48361&CVER=22&X-HTTP-Session-Id=gsessionid&zx=xmfovfbb78ev&t=1
4 200
[ 5.692s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 5.692s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 5.693s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 5.929s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8&RID=rpc&SID=TFWblTDw-5U997UEe2NHZA&CI=0&AID=0&TYPE=xmlhttp&zx=1228z373pey2&t=1
3 200
[ 5.929s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 5.930s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 5.930s] [goog.labs.net.webChannel.WebChannelDebug] Great, no buffering proxy detected. Bytes received: 28
[ 5.931s] [goog.labs.net.webChannel.WebChannelDebug] Cancel the BP detection timer.
[ 5.931s] [diagnostic] RequestStat event: 11
[ 5.932s] [diagnostic] RequestStat event: 11
[ 5.932s] [diagnostic] RequestStat event: 11
[ 6.310s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8&RID=rpc&SID=TFWblTDw-5U997UEe2NHZA&CI=0&AID=0&TYPE=xmlhttp&zx=1228z373pey2&t=1
3 200
[ 6.311s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,{"message":"Hello"}]]
[ 6.312s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 6.822s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8&RID=rpc&SID=TFWblTDw-5U997UEe2NHZA&CI=0&AID=0&TYPE=xmlhttp&zx=1228z373pey2&t=1
3 200
[ 6.824s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 6.824s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 7.334s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8&RID=rpc&SID=TFWblTDw-5U997UEe2NHZA&CI=0&AID=0&TYPE=xmlhttp&zx=1228z373pey2&t=1
3 200
[ 7.335s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,{"message":"Hello"}]]
[ 7.335s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 7.856s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=RVHe20TxroMIzK8Z7kPf6TdP4oiYG3MkEmE1_xbQOc0&VER=8&RID=rpc&SID=TFWblTDw-5U997UEe2NHZA&CI=0&AID=0&TYPE=xmlhttp&zx=1228z373pey2&t=1
3 200
[ 7.857s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 7.858s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 7.858s] [diagnostic]
********************************************************
[TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] completed successfully.
********************************************************
[ 7.859s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 7.860s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 7.861s] [diagnostic] >>> [TEST 3/7: webchannel.googleapis.com with detectBufferingProxy] channel closed
[ 9.362s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] STARTING at 2021-07-08T16:14:56.377Z]
********************************************************
[ 9.363s] [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})
[ 9.364s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] open()
[ 9.365s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 9.365s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 9.366s] [diagnostic] RequestStat event: 0
[ 9.366s] [diagnostic] RequestStat event: 0
[ 9.367s] [diagnostic] RequestStat event: 0
[ 9.367s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 9.368s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[ 9.368s] [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})
[ 9.369s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[ 9.369s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 9.370s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 9.371s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (61638) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=61638&CVER=22&X-HTTP-Session-Id=gsessionid&zx=lw3ckgoihkd3&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
[ 9.605s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (61638) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=61638&CVER=22&X-HTTP-Session-Id=gsessionid&zx=lw3ckgoihkd3&t=1
3 200
[ 9.606s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (61638): [[0,["c","AFACzNxr6P-j4v-Q-t_ZiA","",8,12,30000]]]
[ 9.606s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 9.606s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 9.607s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 9.607s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[ 9.607s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel opened: 244ms (1625760896623)
concurrent request limit : 10
isSpdyEnabled : true
[ 9.607s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8
[ 9.609s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 9.609s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=0&TYPE=xmlhttp&zx=8tqs6un55q0d&t=1
null
[ 9.609s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 9.610s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (61638) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=61638&CVER=22&X-HTTP-Session-Id=gsessionid&zx=lw3ckgoihkd3&t=1
4 200
[ 9.610s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 9.612s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 9.612s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 9.847s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=0&TYPE=xmlhttp&zx=8tqs6un55q0d&t=1
3 200
[ 9.847s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}]]
[ 9.848s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 9.848s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=0&TYPE=xmlhttp&zx=8tqs6un55q0d&t=1
4 200
[ 9.849s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 9.849s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 9.850s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=1&TYPE=xmlhttp&zx=7zs5ahxeujgj&t=1
null
[ 9.851s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 10.086s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=1&TYPE=xmlhttp&zx=7zs5ahxeujgj&t=1
3 200
[ 10.087s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[2,["noop"]]]
[ 10.107s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=1&TYPE=xmlhttp&zx=7zs5ahxeujgj&t=1
4 200
[ 10.107s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[3,{"message":"Hello"}]]
[ 10.108s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 10.108s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 10.109s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 10.110s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=3&TYPE=xmlhttp&zx=kb9ofyurjfiy&t=1
null
[ 10.111s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 10.347s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=3&TYPE=xmlhttp&zx=kb9ofyurjfiy&t=1
3 200
[ 10.348s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[4,["noop"]]]
[ 10.654s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=3&TYPE=xmlhttp&zx=kb9ofyurjfiy&t=1
4 200
[ 10.655s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 10.656s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 10.657s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 10.657s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 10.658s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=5&TYPE=xmlhttp&zx=lwy75d8x89wf&t=1
null
[ 10.658s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 10.757s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=5&TYPE=xmlhttp&zx=lwy75d8x89wf&t=1
3 200
[ 10.757s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[6,["noop"]]]
[ 11.172s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=5&TYPE=xmlhttp&zx=lwy75d8x89wf&t=1
4 200
[ 11.172s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[7,{"message":"Hello"}]]
[ 11.173s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 11.173s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 11.174s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 11.175s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=7&TYPE=xmlhttp&zx=9r1byyspdi7c&t=1
null
[ 11.176s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 11.413s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=7&TYPE=xmlhttp&zx=9r1byyspdi7c&t=1
3 200
[ 11.414s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[8,["noop"]]]
[ 11.609s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=wtwJNPbgR-FhOjudV6sFeKvHGk14AqUv8R8mCtn3bw8&VER=8&RID=rpc&SID=AFACzNxr6P-j4v-Q-t_ZiA&CI=1&AID=7&TYPE=xmlhttp&zx=9r1byyspdi7c&t=1
4 200
[ 11.610s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[9,{"message":"Hello"}]]
[ 11.610s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[ 11.610s] [diagnostic]
********************************************************
[TEST 4/7: webchannel.googleapis.com with forceLongPolling] completed successfully.
********************************************************
[ 11.611s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 11.612s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[ 11.612s] [diagnostic] >>> [TEST 4/7: webchannel.googleapis.com with forceLongPolling] channel closed
[ 13.114s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] STARTING at 2021-07-08T16:15:00.130Z]
********************************************************
[ 13.118s] [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)"}})
[ 13.119s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] open()
[ 13.120s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 13.121s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 13.121s] [diagnostic] RequestStat event: 0
[ 13.122s] [diagnostic] RequestStat event: 0
[ 13.122s] [diagnostic] RequestStat event: 0
[ 13.123s] [diagnostic] RequestStat event: 0
[ 13.123s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 13.124s] [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
[ 13.125s] [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}})
[ 13.126s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 13.126s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 13.127s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 13.129s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (97648) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=97648&CVER=22&X-HTTP-Session-Id=gsessionid&zx=dugm37aq0fm&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
[ 13.267s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (97648) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=97648&CVER=22&X-HTTP-Session-Id=gsessionid&zx=dugm37aq0fm&t=1
3 200
[ 13.268s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (97648): [[0,["c","E38wf13AN61qAzvP_Kiejw","",8,12,30000]]]
[ 13.268s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 13.269s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 13.269s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 13.269s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 13.269s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel opened: 151ms (1625760900285)
concurrent request limit : 10
isSpdyEnabled : true
[ 13.270s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=uC1Mk-xbbFo0ataQyJYlrtqtb0qMa32NlzUzWohnLmE&VER=8
[ 13.271s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 13.272s] [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=uC1Mk-xbbFo0ataQyJYlrtqtb0qMa32NlzUzWohnLmE&VER=8&RID=rpc&SID=E38wf13AN61qAzvP_Kiejw&CI=0&AID=0&TYPE=xmlhttp&zx=lof44dz0mr1s&t=1
null
[ 13.273s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 13.274s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (97648) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=97648&CVER=22&X-HTTP-Session-Id=gsessionid&zx=dugm37aq0fm&t=1
4 200
[ 13.275s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 13.275s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 13.275s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 13.442s] [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=uC1Mk-xbbFo0ataQyJYlrtqtb0qMa32NlzUzWohnLmE&VER=8&RID=rpc&SID=E38wf13AN61qAzvP_Kiejw&CI=0&AID=0&TYPE=xmlhttp&zx=lof44dz0mr1s&t=1
3 200
[ 13.443s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): null [Incomplete Response]
[ 13.495s] [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=uC1Mk-xbbFo0ataQyJYlrtqtb0qMa32NlzUzWohnLmE&VER=8&RID=rpc&SID=E38wf13AN61qAzvP_Kiejw&CI=0&AID=0&TYPE=xmlhttp&zx=lof44dz0mr1s&t=1
3 200
[ 13.496s] [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": "CgkIkYrXp+/T8QI=",
"readTime": "2021-07-08T16:15:00.433169Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkIkYrXp+/T8QI=",
"readTime": "2021-07-08T16:15:00.433169Z"
}
}
]]]
[ 13.499s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 13.499s] [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]}}]
[ 13.500s] [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]}}]
[ 13.500s] [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]}}]
[ 13.501s] [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]}}]
[ 13.501s] [diagnostic]
********************************************************
[TEST 5/7: Firestore listen test with default options] completed successfully.
********************************************************
[ 13.502s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 13.503s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 13.503s] [diagnostic] >>> [TEST 5/7: Firestore listen test with default options] channel closed
[ 15.008s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] STARTING at 2021-07-08T16:15:02.023Z]
********************************************************
[ 15.010s] [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)"}})
[ 15.011s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] open()
[ 15.011s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 15.012s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 15.013s] [diagnostic] RequestStat event: 0
[ 15.013s] [diagnostic] RequestStat event: 0
[ 15.014s] [diagnostic] RequestStat event: 0
[ 15.015s] [diagnostic] RequestStat event: 0
[ 15.015s] [diagnostic] RequestStat event: 0
[ 15.016s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 15.017s] [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.017s] [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}})
[ 15.018s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.018s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 15.019s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 15.020s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (15412) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=15412&CVER=22&X-HTTP-Session-Id=gsessionid&zx=3s56cj4dl65q&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.089s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (15412) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=15412&CVER=22&X-HTTP-Session-Id=gsessionid&zx=3s56cj4dl65q&t=1
3 200
[ 15.090s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (15412): [[0,["c","VA41vHxXx2mMUE4nekqDiw","",8,12,30000]]]
[ 15.090s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 15.091s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 15.091s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 15.092s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 15.093s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel opened: 83ms (1625760902109)
concurrent request limit : 10
isSpdyEnabled : true
[ 15.093s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 74ms
[ 15.094s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8
[ 15.094s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 15.095s] [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=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=0&AID=0&TYPE=xmlhttp&zx=6b6votrsf8nw&t=1
null
[ 15.095s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 15.096s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 148
[ 15.097s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (15412) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=15412&CVER=22&X-HTTP-Session-Id=gsessionid&zx=3s56cj4dl65q&t=1
4 200
[ 15.097s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 15.098s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 15.099s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 15.245s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timeout reached.
[ 15.245s] [goog.labs.net.webChannel.WebChannelDebug] Buffering proxy detected and switch to long-polling!
[ 15.247s] [diagnostic] RequestStat event: 10
[ 15.247s] [diagnostic] RequestStat event: 10
[ 15.248s] [diagnostic] RequestStat event: 10
[ 15.248s] [diagnostic] RequestStat event: 10
[ 15.248s] [diagnostic] RequestStat event: 10
[ 15.249s] [diagnostic] RequestStat event: 10
[ 15.250s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 15.251s] [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=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=1&AID=0&TYPE=xmlhttp&zx=3mo5knfebedm&t=1
null
[ 15.251s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 45.292s] [goog.labs.net.webChannel.WebChannelDebug] No response text for uri https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=1&AID=0&TYPE=xmlhttp&zx=3mo5knfebedm&t=1 status 0
[ 45.293s] [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=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=1&AID=0&TYPE=xmlhttp&zx=3mo5knfebedm&t=1
4 0
[ 45.294s] [diagnostic] RequestStat event: 13
[ 45.294s] [diagnostic] RequestStat event: 13
[ 45.294s] [diagnostic] RequestStat event: 13
[ 45.295s] [diagnostic] RequestStat event: 13
[ 45.295s] [diagnostic] RequestStat event: 13
[ 45.295s] [diagnostic] RequestStat event: 13
[ 45.296s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP Bad status 0 (rpc)
[ 45.296s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 45.297s] [goog.labs.net.webChannel.WebChannelDebug] Maybe retrying, last error: Non-200 return code (0)
[ 45.298s] [goog.labs.net.webChannel.WebChannelDebug] Going to retry GET
[ 45.300s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 45.302s] [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=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=1&AID=0&TYPE=xmlhttp&zx=25kqndwecqo8&t=2
null
[ 45.302s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 45.565s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 2]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=1&AID=0&TYPE=xmlhttp&zx=25kqndwecqo8&t=2
3 200
[ 45.566s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): null [Incomplete Response]
[ 45.567s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 2]: GET
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=kEP913b7B9mvg_7lzAr3yqvKvyAQ6Si_niLuXe0_mk8&VER=8&RID=rpc&SID=VA41vHxXx2mMUE4nekqDiw&CI=1&AID=0&TYPE=xmlhttp&zx=25kqndwecqo8&t=2
4 200
[ 45.567s] [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": "CgkIitvDqO/T8QI=",
"readTime": "2021-07-08T16:15:02.213002Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkIitvDqO/T8QI=",
"readTime": "2021-07-08T16:15:02.213002Z"
}
}
]],[9,["noop"]]]
[ 45.569s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 45.570s] [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]}}]
[ 45.570s] [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]}}]
[ 45.572s] [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]}}]
[ 45.572s] [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]}}]
[ 45.572s] [diagnostic]
********************************************************
[TEST 6/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 45.572s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 45.573s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 45.573s] [diagnostic] >>> [TEST 6/7: Firestore listen test with forceLongPolling] channel closed
[ 47.074s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] STARTING at 2021-07-08T16:15:34.090Z]
********************************************************
[ 47.076s] [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)"}})
[ 47.077s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] open()
[ 47.078s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 47.078s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 47.079s] [diagnostic] RequestStat event: 0
[ 47.079s] [diagnostic] RequestStat event: 0
[ 47.079s] [diagnostic] RequestStat event: 0
[ 47.079s] [diagnostic] RequestStat event: 0
[ 47.079s] [diagnostic] RequestStat event: 0
[ 47.081s] [diagnostic] RequestStat event: 0
[ 47.081s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 47.081s] [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
[ 47.082s] [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}})
[ 47.082s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 47.082s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 47.083s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 47.084s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (15104) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=15104&CVER=22&X-HTTP-Session-Id=gsessionid&zx=owaxral678og&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
[ 47.146s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (15104) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=15104&CVER=22&X-HTTP-Session-Id=gsessionid&zx=owaxral678og&t=1
3 200
[ 47.147s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (15104): [[0,["c","Bj22YwmTlSx8vzK_LKhyrA","",8,12,30000]]]
[ 47.147s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 47.148s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 47.148s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 47.148s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 47.149s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel opened: 73ms (1625760934165)
concurrent request limit : 10
isSpdyEnabled : true
[ 47.149s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=txEXeFI9X6pP0BmvB2d2oT37nebN_23mPpYiZiO2eR8&VER=8
[ 47.150s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 47.151s] [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=txEXeFI9X6pP0BmvB2d2oT37nebN_23mPpYiZiO2eR8&VER=8&RID=rpc&SID=Bj22YwmTlSx8vzK_LKhyrA&CI=1&AID=0&TYPE=xmlhttp&zx=91b761mxnxce&t=1
null
[ 47.152s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 47.152s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (15104) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=15104&CVER=22&X-HTTP-Session-Id=gsessionid&zx=owaxral678og&t=1
4 200
[ 47.153s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 47.154s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 47.154s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 47.335s] [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=txEXeFI9X6pP0BmvB2d2oT37nebN_23mPpYiZiO2eR8&VER=8&RID=rpc&SID=Bj22YwmTlSx8vzK_LKhyrA&CI=1&AID=0&TYPE=xmlhttp&zx=91b761mxnxce&t=1
3 200
[ 47.336s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): null [Incomplete Response]
[ 47.337s] [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=txEXeFI9X6pP0BmvB2d2oT37nebN_23mPpYiZiO2eR8&VER=8&RID=rpc&SID=Bj22YwmTlSx8vzK_LKhyrA&CI=1&AID=0&TYPE=xmlhttp&zx=91b761mxnxce&t=1
4 200
[ 47.338s] [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": "CgkIw4zot+/T8QI=",
"readTime": "2021-07-08T16:15:34.266435Z"
}
}
]],[8,[{
"targetChange": {
"resumeToken": "CgkIw4zot+/T8QI=",
"readTime": "2021-07-08T16:15:34.266435Z"
}
}
]]]
[ 47.339s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 47.340s] [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]}}]
[ 47.340s] [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]}}]
[ 47.341s] [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]}}]
[ 47.341s] [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]}}]
[ 47.342s] [diagnostic]
********************************************************
[TEST 7/7: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 47.342s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 47.343s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 47.344s] [diagnostic] >>> [TEST 7/7: Firestore listen test with forceLongPolling] channel closed
[ 47.399s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 48.847s] [diagnostic]
********************************************************
All tests done.
1: webchannel.googleapis.com with default options: SUCCEEDED (2390ms)
2: webchannel.sandbox.google.com with default options: FAILED (46ms)
3: webchannel.googleapis.com with detectBufferingProxy: SUCCEEDED (2413ms)
4: webchannel.googleapis.com with forceLongPolling: SUCCEEDED (2249ms)
5: Firestore listen test with default options: SUCCEEDED (387ms)
6: Firestore listen test with forceLongPolling: SUCCEEDED (30565ms)
7: Firestore listen test with forceLongPolling: SUCCEEDED (268ms)
********************************************************
[ 52.865s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 56.613s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 58.507s] [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