[  0.000s] [diagnostic]
********************************************************
[TEST 1/9: webchannel.googleapis.com with default options] STARTING at 2023-02-05T13:53:43.038Z]
********************************************************
[  0.003s] [diagnostic] >>> [TEST 1/9: 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.004s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] open()
[  0.005s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[  0.005s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[  0.005s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[  0.006s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[  0.006s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":200,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[  0.007s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[  0.007s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  0.007s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[  0.008s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (62588) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=62588&CVER=22&X-HTTP-Session-Id=gsessionid&zx=er6pur5goxew&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%3A200%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[  1.204s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (62588) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=62588&CVER=22&X-HTTP-Session-Id=gsessionid&zx=er6pur5goxew&t=1
3 200
[  1.205s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (62588): [[0,["c","lHAjpj8rMLGouvaqQcAdyA","",8,12,30000]]]

[  1.205s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[  1.205s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[  1.205s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[  1.206s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[  1.206s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] channel opened: 1203ms (1675605224244)
   concurrent request limit : 10
   isSpdyEnabled : true
[  1.207s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=rsAJzqJo22oqGTCRr54napnJJh5pidJKQS2iuheqR-w&VER=8
[  1.207s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[  1.208s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=rsAJzqJo22oqGTCRr54napnJJh5pidJKQS2iuheqR-w&VER=8&RID=rpc&SID=lHAjpj8rMLGouvaqQcAdyA&CI=0&AID=0&TYPE=xmlhttp&zx=i8vtl9xg9zr&t=1
null
[  1.209s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[  1.210s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (62588) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=62588&CVER=22&X-HTTP-Session-Id=gsessionid&zx=er6pur5goxew&t=1
4 200
[  1.210s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[  1.211s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  1.211s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[  2.005s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=rsAJzqJo22oqGTCRr54napnJJh5pidJKQS2iuheqR-w&VER=8&RID=rpc&SID=lHAjpj8rMLGouvaqQcAdyA&CI=0&AID=0&TYPE=xmlhttp&zx=i8vtl9xg9zr&t=1
3 200
[  2.006s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}],[3,{"message":"Hello"}],[4,{"message":"Hello"}]]
[  2.006s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[  2.007s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[  2.007s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[  2.007s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[  2.007s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,["noop"]]]
[  2.059s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=rsAJzqJo22oqGTCRr54napnJJh5pidJKQS2iuheqR-w&VER=8&RID=rpc&SID=lHAjpj8rMLGouvaqQcAdyA&CI=0&AID=0&TYPE=xmlhttp&zx=i8vtl9xg9zr&t=1
3 200
[  2.060s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[6,{"message":"Hello"}]]
[  2.060s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] Received message: {"message":"Hello"}
[  2.061s] [diagnostic]
********************************************************
[TEST 1/9: webchannel.googleapis.com with default options] completed successfully.
********************************************************
[  2.061s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[  2.062s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[  2.062s] [diagnostic] >>> [TEST 1/9: webchannel.googleapis.com with default options] channel closed
[  3.573s] [diagnostic]
********************************************************
[TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] STARTING at 2023-02-05T13:53:46.611Z]
********************************************************
[  3.574s] [diagnostic] >>> [TEST 2/9: 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})
[  3.574s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] open()
[  3.575s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[  3.575s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[  3.575s] [diagnostic] RequestStat event: 0
[  3.576s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[  3.576s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[  3.576s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":200,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[  3.577s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[  3.577s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  3.577s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[  3.578s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (94266) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=94266&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5y6ezyna02tj&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%3A200%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[  3.861s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (94266) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=94266&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5y6ezyna02tj&t=1
3 200
[  3.862s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (94266): [[0,["c","wz7hUshxBKcHyhAmUgCkkg","",8,12,30000]]]

[  3.862s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[  3.863s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[  3.863s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[  3.863s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[  3.864s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] channel opened: 290ms (1675605226902)
   concurrent request limit : 10
   isSpdyEnabled : true
[  3.864s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 286ms
[  3.865s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=lB2RlSbg4CnM05ry-0z0Tf6L0_xoZPIKTwDKY3K8wWQ&VER=8
[  3.865s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[  3.866s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=lB2RlSbg4CnM05ry-0z0Tf6L0_xoZPIKTwDKY3K8wWQ&VER=8&RID=rpc&SID=wz7hUshxBKcHyhAmUgCkkg&CI=0&AID=0&TYPE=xmlhttp&zx=840v78xemj1x&t=1
null
[  3.867s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[  3.867s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 572
[  3.868s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (94266) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=94266&CVER=22&X-HTTP-Session-Id=gsessionid&zx=5y6ezyna02tj&t=1
4 200
[  3.869s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[  3.869s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  3.870s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[  4.452s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timeout reached.
[  4.452s] [goog.labs.net.webChannel.WebChannelDebug] Buffering proxy detected and switch to long-polling!
[  4.453s] [diagnostic] RequestStat event: 10
[  4.453s] [diagnostic] RequestStat event: 10
[  4.453s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[  4.454s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=lB2RlSbg4CnM05ry-0z0Tf6L0_xoZPIKTwDKY3K8wWQ&VER=8&RID=rpc&SID=wz7hUshxBKcHyhAmUgCkkg&CI=1&AID=0&TYPE=xmlhttp&zx=6ctz1gco7plu&t=1
null
[  4.455s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[  5.267s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=lB2RlSbg4CnM05ry-0z0Tf6L0_xoZPIKTwDKY3K8wWQ&VER=8&RID=rpc&SID=wz7hUshxBKcHyhAmUgCkkg&CI=1&AID=0&TYPE=xmlhttp&zx=6ctz1gco7plu&t=1
3 200
[  5.269s] [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"]]]
[  5.269s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[  5.269s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[  5.270s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[  5.270s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[  5.271s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] Received message: {"message":"Hello"}
[  5.271s] [diagnostic]
********************************************************
[TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] completed successfully.
********************************************************
[  5.271s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[  5.272s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[  5.272s] [diagnostic] >>> [TEST 2/9: webchannel.googleapis.com with detectBufferingProxy] channel closed
[  6.782s] [diagnostic]
********************************************************
[TEST 3/9: webchannel.googleapis.com with forceLongPolling] STARTING at 2023-02-05T13:53:49.820Z]
********************************************************
[  6.783s] [diagnostic] >>> [TEST 3/9: 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})
[  6.783s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] open()
[  6.784s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[  6.784s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[  6.784s] [diagnostic] RequestStat event: 0
[  6.785s] [diagnostic] RequestStat event: 0
[  6.785s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[  6.785s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.googleapis.com/staging/channel/generator?VER=8
[  6.786s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":200,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[  6.786s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.googleapis.com/staging/channel/generator
[  6.787s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  6.787s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[  6.788s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (62621) [attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=62621&CVER=22&X-HTTP-Session-Id=gsessionid&zx=ph7l7pcq4zym&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%3A200%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[  7.590s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (62621) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=62621&CVER=22&X-HTTP-Session-Id=gsessionid&zx=ph7l7pcq4zym&t=1
3 200
[  7.590s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (62621): [[0,["c","jrcCZ_Ix29n1bOluE7pUWg","",8,12,30000]]]

[  7.591s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[  7.591s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[  7.592s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[  7.593s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.googleapis.com/staging/channel/generator
[  7.593s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] channel opened: 810ms (1675605230631)
   concurrent request limit : 10
   isSpdyEnabled : true
[  7.594s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.googleapis.com/staging/channel/generator?gsessionid=7GspTMV-3yApRRCD29KERK6A2NSUOAWfessfCFFl9-8&VER=8
[  7.595s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[  7.596s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=7GspTMV-3yApRRCD29KERK6A2NSUOAWfessfCFFl9-8&VER=8&RID=rpc&SID=jrcCZ_Ix29n1bOluE7pUWg&CI=1&AID=0&TYPE=xmlhttp&zx=rqu2y03t49f2&t=1
null
[  7.597s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[  7.598s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (62621) [ attempt 1]: POST
https://webchannel.googleapis.com/staging/channel/generator?VER=8&RID=62621&CVER=22&X-HTTP-Session-Id=gsessionid&zx=ph7l7pcq4zym&t=1
4 200
[  7.599s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[  7.599s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  7.600s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[  8.393s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.googleapis.com/staging/channel/generator?gsessionid=7GspTMV-3yApRRCD29KERK6A2NSUOAWfessfCFFl9-8&VER=8&RID=rpc&SID=jrcCZ_Ix29n1bOluE7pUWg&CI=1&AID=0&TYPE=xmlhttp&zx=rqu2y03t49f2&t=1
3 200
[  8.393s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}],[3,{"message":"Hello"}],[4,{"message":"Hello"}],[5,{"message":"Hello"}]]
[  8.394s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[  8.394s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[  8.395s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[  8.395s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[  8.395s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] Received message: {"message":"Hello"}
[  8.396s] [diagnostic]
********************************************************
[TEST 3/9: webchannel.googleapis.com with forceLongPolling] completed successfully.
********************************************************
[  8.396s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[  8.397s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.googleapis.com/staging/channel/generator
[  8.398s] [diagnostic] >>> [TEST 3/9: webchannel.googleapis.com with forceLongPolling] channel closed
[  9.902s] [diagnostic]
********************************************************
[TEST 4/9: webchannel.sandbox.google.com with default options] STARTING at 2023-02-05T13:53:52.940Z]
********************************************************
[  9.903s] [diagnostic] >>> [TEST 4/9: 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})
[  9.904s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] open()
[  9.904s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[  9.904s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[  9.905s] [diagnostic] RequestStat event: 0
[  9.905s] [diagnostic] RequestStat event: 0
[  9.905s] [diagnostic] RequestStat event: 0
[  9.906s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[  9.906s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[  9.907s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":200,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[  9.907s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[  9.907s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[  9.908s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[  9.909s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (76717) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=76717&CVER=22&X-HTTP-Session-Id=gsessionid&zx=o0on3bkibjpn&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%3A200%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 11.130s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (76717) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=76717&CVER=22&X-HTTP-Session-Id=gsessionid&zx=o0on3bkibjpn&t=1
3 200
[ 11.130s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (76717): [[0,["c","XwQRdF-iOZGOd-w7rA6P8w","",8,12,30000]]]

[ 11.131s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 11.131s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 11.132s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 11.132s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.sandbox.google.com/staging/channel/generator
[ 11.132s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] channel opened: 1229ms (1675605234170)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 11.133s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=BP6jaKhl7-Qb-USydgCwsasmbLvhZs9Z5DiRwZ0hdaA&VER=8
[ 11.133s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 11.134s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=BP6jaKhl7-Qb-USydgCwsasmbLvhZs9Z5DiRwZ0hdaA&VER=8&RID=rpc&SID=XwQRdF-iOZGOd-w7rA6P8w&CI=0&AID=0&TYPE=xmlhttp&zx=fepssy7h95x1&t=1
null
[ 11.135s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 11.136s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (76717) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=76717&CVER=22&X-HTTP-Session-Id=gsessionid&zx=o0on3bkibjpn&t=1
4 200
[ 11.137s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 11.138s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 11.138s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 11.936s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=BP6jaKhl7-Qb-USydgCwsasmbLvhZs9Z5DiRwZ0hdaA&VER=8&RID=rpc&SID=XwQRdF-iOZGOd-w7rA6P8w&CI=0&AID=0&TYPE=xmlhttp&zx=fepssy7h95x1&t=1
3 200
[ 11.937s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}],[3,{"message":"Hello"}],[4,{"message":"Hello"}],[5,{"message":"Hello"}]]
[ 11.938s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 11.938s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 11.939s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 11.939s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 11.939s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] Received message: {"message":"Hello"}
[ 11.940s] [diagnostic]
********************************************************
[TEST 4/9: webchannel.sandbox.google.com with default options] completed successfully.
********************************************************
[ 11.940s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 11.941s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[ 11.942s] [diagnostic] >>> [TEST 4/9: webchannel.sandbox.google.com with default options] channel closed
[ 13.444s] [diagnostic]
********************************************************
[TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] STARTING at 2023-02-05T13:53:56.482Z]
********************************************************
[ 13.445s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] 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,"detectBufferingProxy":true})
[ 13.445s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] open()
[ 13.446s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 13.447s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 13.447s] [diagnostic] RequestStat event: 0
[ 13.448s] [diagnostic] RequestStat event: 0
[ 13.449s] [diagnostic] RequestStat event: 0
[ 13.449s] [diagnostic] RequestStat event: 0
[ 13.450s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 13.451s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[ 13.451s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":200,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 13.452s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[ 13.452s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 13.452s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 13.453s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (31558) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=31558&CVER=22&X-HTTP-Session-Id=gsessionid&zx=1o7n95y68ule&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%3A200%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 14.256s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (31558) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=31558&CVER=22&X-HTTP-Session-Id=gsessionid&zx=1o7n95y68ule&t=1
3 200
[ 14.256s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (31558): [[0,["c","QXPaXCM_PqhLSeSCytDZTw","",8,12,30000]]]

[ 14.257s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 14.257s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 14.257s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 14.258s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.sandbox.google.com/staging/channel/generator
[ 14.259s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] channel opened: 814ms (1675605237297)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 14.259s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 806ms
[ 14.259s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=-NBpV0CiGRQ3pGN4O1xMCdBqkRQ-tOWjLzBmp6kCe58&VER=8
[ 14.260s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 14.260s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=-NBpV0CiGRQ3pGN4O1xMCdBqkRQ-tOWjLzBmp6kCe58&VER=8&RID=rpc&SID=QXPaXCM_PqhLSeSCytDZTw&CI=0&AID=0&TYPE=xmlhttp&zx=c4114e35g7lx&t=1
null
[ 14.261s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 14.262s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 1612
[ 14.263s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (31558) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=31558&CVER=22&X-HTTP-Session-Id=gsessionid&zx=1o7n95y68ule&t=1
4 200
[ 14.263s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 14.264s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 14.265s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 15.060s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=-NBpV0CiGRQ3pGN4O1xMCdBqkRQ-tOWjLzBmp6kCe58&VER=8&RID=rpc&SID=QXPaXCM_PqhLSeSCytDZTw&CI=0&AID=0&TYPE=xmlhttp&zx=c4114e35g7lx&t=1
3 200
[ 15.061s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}],[3,{"message":"Hello"}],[4,{"message":"Hello"}],[5,{"message":"Hello"}]]
[ 15.061s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 15.062s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 15.062s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 15.062s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 15.063s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] Received message: {"message":"Hello"}
[ 15.063s] [diagnostic]
********************************************************
[TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] completed successfully.
********************************************************
[ 15.064s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 15.064s] [goog.labs.net.webChannel.WebChannelDebug] Cancel the BP detection timer.
[ 15.065s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[ 15.066s] [diagnostic] >>> [TEST 5/9: webchannel.sandbox.google.com with detectBufferingProxy] channel closed
[ 16.576s] [diagnostic]
********************************************************
[TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] STARTING at 2023-02-05T13:53:59.614Z]
********************************************************
[ 16.577s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] 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,"forceLongPolling":true})
[ 16.578s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] open()
[ 16.579s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 16.580s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 16.580s] [diagnostic] RequestStat event: 0
[ 16.581s] [diagnostic] RequestStat event: 0
[ 16.581s] [diagnostic] RequestStat event: 0
[ 16.582s] [diagnostic] RequestStat event: 0
[ 16.583s] [diagnostic] RequestStat event: 0
[ 16.584s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 16.584s] [goog.labs.net.webChannel.WebChannelDebug] GetForwardChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?VER=8
[ 16.585s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] channel.send({"message":"Hello","message_size":0,"delay_ms":0,"num_messages":5,"message_interval":200,"server_send_raw":0,"server_close":0,"server_half_close":0,"shutdown_server":0})
[ 16.585s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://webchannel.sandbox.google.com/staging/channel/generator
[ 16.586s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 16.586s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 16.587s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (87614) [attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=87614&CVER=22&X-HTTP-Session-Id=gsessionid&zx=b0fyua4b4bsr&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%3A200%2C%22server_send_raw%22%3A0%2C%22server_close%22%3A0%2C%22server_half_close%22%3A0%2C%22shutdown_server%22%3A0%7D
[ 17.381s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (87614) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=87614&CVER=22&X-HTTP-Session-Id=gsessionid&zx=b0fyua4b4bsr&t=1
3 200
[ 17.382s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (87614): [[0,["c","QQbrJZZ7J4bdE2XGs52N_g","",8,12,30000]]]

[ 17.383s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 17.383s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 17.383s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 17.384s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://webchannel.sandbox.google.com/staging/channel/generator
[ 17.384s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] channel opened: 807ms (1675605240422)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 17.385s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=0mHZoJ1U9wd2WvRi1P2waYBKGIieSyxxoVDhJO9Lb2o&VER=8
[ 17.385s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 17.387s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=0mHZoJ1U9wd2WvRi1P2waYBKGIieSyxxoVDhJO9Lb2o&VER=8&RID=rpc&SID=QQbrJZZ7J4bdE2XGs52N_g&CI=1&AID=0&TYPE=xmlhttp&zx=r7lbyvu4g1nu&t=1
null
[ 17.387s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 17.388s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (87614) [ attempt 1]: POST
https://webchannel.sandbox.google.com/staging/channel/generator?VER=8&RID=87614&CVER=22&X-HTTP-Session-Id=gsessionid&zx=b0fyua4b4bsr&t=1
4 200
[ 17.389s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 17.389s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 17.390s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 18.188s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=0mHZoJ1U9wd2WvRi1P2waYBKGIieSyxxoVDhJO9Lb2o&VER=8&RID=rpc&SID=QQbrJZZ7J4bdE2XGs52N_g&CI=1&AID=0&TYPE=xmlhttp&zx=r7lbyvu4g1nu&t=1
3 200
[ 18.189s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[1,{"message":"Hello"}],[2,{"message":"Hello"}],[3,{"message":"Hello"}],[4,{"message":"Hello"}]]
[ 18.190s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] Received message: {"message":"Hello"}
[ 18.190s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] Received message: {"message":"Hello"}
[ 18.191s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] Received message: {"message":"Hello"}
[ 18.191s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] Received message: {"message":"Hello"}
[ 18.192s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=0mHZoJ1U9wd2WvRi1P2waYBKGIieSyxxoVDhJO9Lb2o&VER=8&RID=rpc&SID=QQbrJZZ7J4bdE2XGs52N_g&CI=1&AID=0&TYPE=xmlhttp&zx=r7lbyvu4g1nu&t=1
4 200
[ 18.193s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 18.193s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 18.195s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (rpc) [attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=0mHZoJ1U9wd2WvRi1P2waYBKGIieSyxxoVDhJO9Lb2o&VER=8&RID=rpc&SID=QQbrJZZ7J4bdE2XGs52N_g&CI=1&AID=4&TYPE=xmlhttp&zx=gvl8vbgpy63b&t=1
null
[ 18.196s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 18.993s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (rpc) [ attempt 1]: GET
https://webchannel.sandbox.google.com/staging/channel/generator?gsessionid=0mHZoJ1U9wd2WvRi1P2waYBKGIieSyxxoVDhJO9Lb2o&VER=8&RID=rpc&SID=QQbrJZZ7J4bdE2XGs52N_g&CI=1&AID=4&TYPE=xmlhttp&zx=gvl8vbgpy63b&t=1
3 200
[ 18.993s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (rpc): [[5,{"message":"Hello"}]]
[ 18.994s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] Received message: {"message":"Hello"}
[ 18.995s] [diagnostic]
********************************************************
[TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] completed successfully.
********************************************************
[ 18.995s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 18.996s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://webchannel.sandbox.google.com/staging/channel/generator
[ 18.997s] [diagnostic] >>> [TEST 6/9: webchannel.sandbox.google.com with forceLongPolling] channel closed
[ 20.504s] [diagnostic]
********************************************************
[TEST 7/9: Firestore listen test with default options] STARTING at 2023-02-05T13:54:03.542Z]
********************************************************
[ 20.505s] [diagnostic] >>> [TEST 7/9: 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)"}})
[ 20.506s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] open()
[ 20.506s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 20.507s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 20.507s] [diagnostic] RequestStat event: 0
[ 20.508s] [diagnostic] RequestStat event: 0
[ 20.508s] [diagnostic] RequestStat event: 0
[ 20.509s] [diagnostic] RequestStat event: 0
[ 20.509s] [diagnostic] RequestStat event: 0
[ 20.510s] [diagnostic] RequestStat event: 0
[ 20.510s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 20.511s] [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
[ 20.511s] [diagnostic] >>> [TEST 7/9: 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}})
[ 20.512s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 20.512s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 20.513s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 20.514s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (67750) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=67750&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gw5aw7josi9x&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
[ 21.028s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (67750) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=67750&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gw5aw7josi9x&t=1
3 200
[ 21.029s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (67750): [[0,["c","KtzbrBdXXqq0sWlxd09_uQ","",8,12,30000]]]

[ 21.029s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 21.030s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 21.030s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 21.031s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 21.031s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] channel opened: 526ms (1675605244069)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 21.032s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=xUBqzpdPoTCdWHQiYtGRFnAI7AOgSIoko5_oVw2oCFI&VER=8
[ 21.033s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 21.034s] [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=xUBqzpdPoTCdWHQiYtGRFnAI7AOgSIoko5_oVw2oCFI&VER=8&RID=rpc&SID=KtzbrBdXXqq0sWlxd09_uQ&CI=0&AID=0&TYPE=xmlhttp&zx=mjt0r9vklqwb&t=1
null
[ 21.035s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 21.036s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (67750) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=67750&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gw5aw7josi9x&t=1
4 200
[ 21.036s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 21.037s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 21.037s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 21.327s] [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=xUBqzpdPoTCdWHQiYtGRFnAI7AOgSIoko5_oVw2oCFI&VER=8&RID=rpc&SID=KtzbrBdXXqq0sWlxd09_uQ&CI=0&AID=0&TYPE=xmlhttp&zx=mjt0r9vklqwb&t=1
3 200
[ 21.328s] [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": "CgkIsdzk+sP+/AI=",
   "readTime": "2023-02-05T13:54:03.932209Z"
 }
}
]],[8,[{
 "targetChange": {
   "resumeToken": "CgkIsdzk+sP+/AI=",
   "readTime": "2023-02-05T13:54:03.932209Z"
 }
}
]]]
[ 21.330s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 21.331s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/000","fields":{"desc":{"stringValue":"Diagnostic Document 0"}},"createTime":"2019-12-28T19:50:18.256728Z","updateTime":"2019-12-28T19:50:18.256728Z"},"targetIds":[2]}}]
[ 21.332s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/001","fields":{"desc":{"stringValue":"Diagnostic Document 1"}},"createTime":"2019-12-28T19:50:31.803733Z","updateTime":"2019-12-28T19:50:31.803733Z"},"targetIds":[2]}}]
[ 21.333s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/002","fields":{"desc":{"stringValue":"Diagnostic Document 2"}},"createTime":"2019-12-28T19:50:37.682905Z","updateTime":"2019-12-28T19:50:43.797778Z"},"targetIds":[2]}}]
[ 21.333s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/003","fields":{"desc":{"stringValue":"Diagnostic Document 3"}},"createTime":"2019-12-28T19:50:52.602052Z","updateTime":"2019-12-28T19:50:52.602052Z"},"targetIds":[2]}}]
[ 21.334s] [diagnostic]
********************************************************
[TEST 7/9: Firestore listen test with default options] completed successfully.
********************************************************
[ 21.335s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 21.336s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 21.338s] [diagnostic] >>> [TEST 7/9: Firestore listen test with default options] channel closed
[ 22.855s] [diagnostic]
********************************************************
[TEST 8/9: Firestore listen test with detectBufferingProxy] STARTING at 2023-02-05T13:54:05.893Z]
********************************************************
[ 22.856s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] createWebChannel("https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true,"detectBufferingProxy":true,"messageUrlParams":{"database":"projects/debug-my/databases/(default)"}})
[ 22.857s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] open()
[ 22.858s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 22.858s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 22.860s] [diagnostic] RequestStat event: 0
[ 22.861s] [diagnostic] RequestStat event: 0
[ 22.862s] [diagnostic] RequestStat event: 0
[ 22.862s] [diagnostic] RequestStat event: 0
[ 22.863s] [diagnostic] RequestStat event: 0
[ 22.864s] [diagnostic] RequestStat event: 0
[ 22.865s] [diagnostic] RequestStat event: 0
[ 22.866s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 22.867s] [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
[ 22.868s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] channel.send({"database":"projects/debug-my/databases/(default)","addTarget":{"query":{"structuredQuery":{"from":[{"collectionId":"debug"}],"orderBy":[{"field":{"fieldPath":"__name__"},"direction":"ASCENDING"}]},"parent":"projects/debug-my/databases/(default)/documents"},"targetId":2}})
[ 22.869s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 22.869s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 22.870s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 22.871s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (44984) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=44984&CVER=22&X-HTTP-Session-Id=gsessionid&zx=l36x6inghqz9&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
[ 23.172s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (44984) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=44984&CVER=22&X-HTTP-Session-Id=gsessionid&zx=l36x6inghqz9&t=1
3 200
[ 23.173s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (44984): [[0,["c","vitHYrOE273Eue6BmTgRLA","",8,12,30000]]]

[ 23.174s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 23.174s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 23.175s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 23.175s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 23.176s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] channel opened: 320ms (1675605246214)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 23.176s] [goog.labs.net.webChannel.WebChannelDebug] Handshake RTT: 306ms
[ 23.177s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=QT7HZKTAQ3SnIGb-vwNow1X8y8LtF46CXB9vGf_wQEQ&VER=8
[ 23.178s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 23.179s] [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=QT7HZKTAQ3SnIGb-vwNow1X8y8LtF46CXB9vGf_wQEQ&VER=8&RID=rpc&SID=vitHYrOE273Eue6BmTgRLA&CI=0&AID=0&TYPE=xmlhttp&zx=k1wtlutcwb1e&t=1
null
[ 23.180s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 23.180s] [goog.labs.net.webChannel.WebChannelDebug] BP detection timer enabled: 612
[ 23.181s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (44984) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=44984&CVER=22&X-HTTP-Session-Id=gsessionid&zx=l36x6inghqz9&t=1
4 200
[ 23.182s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 23.183s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 23.183s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 23.470s] [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=QT7HZKTAQ3SnIGb-vwNow1X8y8LtF46CXB9vGf_wQEQ&VER=8&RID=rpc&SID=vitHYrOE273Eue6BmTgRLA&CI=0&AID=0&TYPE=xmlhttp&zx=k1wtlutcwb1e&t=1
3 200
[ 23.471s] [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": "CgkItfPm+8P+/AI=",
   "readTime": "2023-02-05T13:54:06.065077Z"
 }
}
]],[8,[{
 "targetChange": {
   "resumeToken": "CgkItfPm+8P+/AI=",
   "readTime": "2023-02-05T13:54:06.065077Z"
 }
}
]]]
[ 23.473s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 23.474s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/000","fields":{"desc":{"stringValue":"Diagnostic Document 0"}},"createTime":"2019-12-28T19:50:18.256728Z","updateTime":"2019-12-28T19:50:18.256728Z"},"targetIds":[2]}}]
[ 23.475s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/001","fields":{"desc":{"stringValue":"Diagnostic Document 1"}},"createTime":"2019-12-28T19:50:31.803733Z","updateTime":"2019-12-28T19:50:31.803733Z"},"targetIds":[2]}}]
[ 23.475s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/002","fields":{"desc":{"stringValue":"Diagnostic Document 2"}},"createTime":"2019-12-28T19:50:37.682905Z","updateTime":"2019-12-28T19:50:43.797778Z"},"targetIds":[2]}}]
[ 23.476s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/003","fields":{"desc":{"stringValue":"Diagnostic Document 3"}},"createTime":"2019-12-28T19:50:52.602052Z","updateTime":"2019-12-28T19:50:52.602052Z"},"targetIds":[2]}}]
[ 23.477s] [diagnostic]
********************************************************
[TEST 8/9: Firestore listen test with detectBufferingProxy] completed successfully.
********************************************************
[ 23.477s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 23.478s] [goog.labs.net.webChannel.WebChannelDebug] Cancel the BP detection timer.
[ 23.480s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 23.481s] [diagnostic] >>> [TEST 8/9: Firestore listen test with detectBufferingProxy] channel closed
[ 24.990s] [diagnostic]
********************************************************
[TEST 9/9: Firestore listen test with forceLongPolling] STARTING at 2023-02-05T13:54:08.028Z]
********************************************************
[ 24.991s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] createWebChannel("https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel", {"backgroundChannelTest":true,"httpSessionIdParam":"gsessionid","initMessageHeaders":{},"sendRawJson":true,"supportsCrossDomainXhr":true,"internalChannelParams":{"forwardChannelRequestTimeoutMs":600000},"httpHeadersOverwriteParam":"$httpHeaders","disableRedact":true,"forceLongPolling":true,"messageUrlParams":{"database":"projects/debug-my/databases/(default)"}})
[ 24.992s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] open()
[ 24.993s] [goog.labs.net.webChannel.WebChannelDebug] connect()
[ 24.994s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials enabled.
[ 24.994s] [diagnostic] RequestStat event: 0
[ 24.995s] [diagnostic] RequestStat event: 0
[ 24.995s] [diagnostic] RequestStat event: 0
[ 24.996s] [diagnostic] RequestStat event: 0
[ 24.996s] [diagnostic] RequestStat event: 0
[ 24.997s] [diagnostic] RequestStat event: 0
[ 24.997s] [diagnostic] RequestStat event: 0
[ 24.998s] [diagnostic] RequestStat event: 0
[ 24.999s] [goog.labs.net.webChannel.WebChannelDebug] connectChannel_()
[ 25.000s] [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
[ 25.001s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] channel.send({"database":"projects/debug-my/databases/(default)","addTarget":{"query":{"structuredQuery":{"from":[{"collectionId":"debug"}],"orderBy":[{"field":{"fieldPath":"__name__"},"direction":"ASCENDING"}]},"parent":"projects/debug-my/databases/(default)/documents"},"targetId":2}})
[ 25.001s] [goog.labs.net.webChannel.WebChannelDebug] Origin Trials invoked: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 25.002s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 25.002s] [goog.labs.net.webChannel.WebChannelDebug] open_()
[ 25.003s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP REQ (8860) [attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=8860&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gl3wtcki3yxk&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
[ 25.300s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (8860) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=8860&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gl3wtcki3yxk&t=1
3 200
[ 25.301s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP TEXT (8860): [[0,["c","HGuf97QeqKxHdDhyj1HuiA","",8,12,30000]]]

[ 25.302s] [goog.labs.net.webChannel.WebChannelDebug] VER=8
[ 25.303s] [goog.labs.net.webChannel.WebChannelDebug] SVER=12
[ 25.304s] [goog.labs.net.webChannel.WebChannelDebug] backChannelRequestTimeoutMs_=45000
[ 25.304s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel opened on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 25.305s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] channel opened: 314ms (1675605248343)
   concurrent request limit : 10
   isSpdyEnabled : true
[ 25.306s] [goog.labs.net.webChannel.WebChannelDebug] GetBackChannelUri: https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&gsessionid=iVlgnYBB8CCt4r1o5hlInqGPFjFtJJ4hq6OC8CdJCWQ&VER=8
[ 25.307s] [goog.labs.net.webChannel.WebChannelDebug] Creating new HttpRequest
[ 25.308s] [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=iVlgnYBB8CCt4r1o5hlInqGPFjFtJJ4hq6OC8CdJCWQ&VER=8&RID=rpc&SID=HGuf97QeqKxHdDhyj1HuiA&CI=1&AID=0&TYPE=xmlhttp&zx=bcbjzqp0hjqn&t=1
null
[ 25.309s] [goog.labs.net.webChannel.WebChannelDebug] New Request created
[ 25.310s] [goog.labs.net.webChannel.WebChannelDebug] XMLHTTP RESP (8860) [ attempt 1]: POST
https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel?database=projects%2Fdebug-my%2Fdatabases%2F(default)&VER=8&RID=8860&CVER=22&X-HTTP-Session-Id=gsessionid&zx=gl3wtcki3yxk&t=1
4 200
[ 25.311s] [goog.labs.net.webChannel.WebChannelDebug] Request complete
[ 25.311s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_
[ 25.312s] [goog.labs.net.webChannel.WebChannelDebug] startForwardChannel_ returned: nothing to send
[ 25.599s] [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=iVlgnYBB8CCt4r1o5hlInqGPFjFtJJ4hq6OC8CdJCWQ&VER=8&RID=rpc&SID=HGuf97QeqKxHdDhyj1HuiA&CI=1&AID=0&TYPE=xmlhttp&zx=bcbjzqp0hjqn&t=1
3 200
[ 25.600s] [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": "CgkI8Y/p/MP+/AI=",
   "readTime": "2023-02-05T13:54:08.198641Z"
 }
}
]],[8,[{
 "targetChange": {
   "resumeToken": "CgkI8Y/p/MP+/AI=",
   "readTime": "2023-02-05T13:54:08.198641Z"
 }
}
]]]
[ 25.602s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"targetChange":{"targetChangeType":"ADD","targetIds":[2]}}]
[ 25.603s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/000","fields":{"desc":{"stringValue":"Diagnostic Document 0"}},"createTime":"2019-12-28T19:50:18.256728Z","updateTime":"2019-12-28T19:50:18.256728Z"},"targetIds":[2]}}]
[ 25.604s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/001","fields":{"desc":{"stringValue":"Diagnostic Document 1"}},"createTime":"2019-12-28T19:50:31.803733Z","updateTime":"2019-12-28T19:50:31.803733Z"},"targetIds":[2]}}]
[ 25.605s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/002","fields":{"desc":{"stringValue":"Diagnostic Document 2"}},"createTime":"2019-12-28T19:50:37.682905Z","updateTime":"2019-12-28T19:50:43.797778Z"},"targetIds":[2]}}]
[ 25.606s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] Received message: [{"documentChange":{"document":{"name":"projects/debug-my/databases/(default)/documents/debug/003","fields":{"desc":{"stringValue":"Diagnostic Document 3"}},"createTime":"2019-12-28T19:50:52.602052Z","updateTime":"2019-12-28T19:50:52.602052Z"},"targetIds":[2]}}]
[ 25.607s] [diagnostic]
********************************************************
[TEST 9/9: Firestore listen test with forceLongPolling] completed successfully.
********************************************************
[ 25.608s] [goog.labs.net.webChannel.WebChannelDebug] disconnect()
[ 25.609s] [goog.labs.net.webChannel.WebChannelBaseTransport] WebChannel closed on https://firestore.googleapis.com/google.firestore.v1.Firestore/Listen/channel
[ 25.611s] [diagnostic] >>> [TEST 9/9: Firestore listen test with forceLongPolling] channel closed
[ 27.118s] [diagnostic]
********************************************************
All tests done.
1: webchannel.googleapis.com with default options: SUCCEEDED (2060ms)
2: webchannel.googleapis.com with detectBufferingProxy: SUCCEEDED (1698ms)
3: webchannel.googleapis.com with forceLongPolling: SUCCEEDED (1614ms)
4: webchannel.sandbox.google.com with default options: SUCCEEDED (2038ms)
5: webchannel.sandbox.google.com with detectBufferingProxy: SUCCEEDED (1619ms)
6: webchannel.sandbox.google.com with forceLongPolling: SUCCEEDED (2419ms)
7: Firestore listen test with default options: SUCCEEDED (830ms)
8: Firestore listen test with detectBufferingProxy: SUCCEEDED (622ms)
9: Firestore listen test with forceLongPolling: SUCCEEDED (617ms)

********************************************************
[ 47.078s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 50.279s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 53.404s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 56.950s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 60.067s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 64.016s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 66.342s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 68.493s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null
[ 70.620s] [goog.labs.net.webChannel.WebChannelDebug] TIMEOUT: null