Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

K6_BROWSER_WS_URL not making it past opening any page. #892

Open
tmc opened this issue May 17, 2023 · 3 comments
Open

K6_BROWSER_WS_URL not making it past opening any page. #892

tmc opened this issue May 17, 2023 · 3 comments
Labels
bug Something isn't working

Comments

@tmc
Copy link
Contributor

tmc commented May 17, 2023

Brief summary

The following script fails to navigate to google.com if K6_BROWSER_WS_URL is set.

test.js

 import { chromium } from 'k6/experimental/browser';
//import { chromium } from 'k6/x/browser';

export default async function () {
  const browser = chromium.launch({
    headless: false,
    debug: true,
  });
  const page = browser.newPage();
  await page.goto('https://www.google.com');
}

xk6-browser version

version in 0.44.1 as well as current latest main

OS

macos

Chrome version

113.0.5672.126, canary version 115.0.5776.0

Docker version and image (if applicable)

No response

Steps to reproduce the problem

run-tests.sh:

#!/bin/bash
WSURL=$(curl -s localhost:9222/json/version |jq -r .webSocketDebuggerUrl)

# if we get a ws url, set K6_BROWSER_WS_URL:
if [ -n "$WSURL" ]; then
	echo "Setting K6_BROWSER_WS_URL to $WSURL"
	export K6_BROWSER_WS_URL=$WSURL
fi

export K6_BROWSER_ENABLED=true
# run k6:
k6 run test.js

and running chrome via:
/Applications/Google\ Chrome.app/Contents/MacOS/Google\ Chrome --remote-debugging-port=9222

Expected behaviour

Navigating to www.google.com

Actual behaviour

Hangs until timeout:

Screenshot 2023-05-17 at 12 39 21 PM

Logs:

Setting K6_BROWSER_WS_URL to ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f

          /\      |‾‾| /‾‾/   /‾‾/
     /\  /  \     |  |/  /   /  /
    /  \/    \    |     (   /   ‾‾\
   /          \   |  |\  \ |  (‾)  |
  / __________ \  |__| \__\ \_____/ .io

  execution: local
     script: test.js
     output: -

  scenarios: (100.00%) 1 scenario, 1 max VUs, 10m30s max duration (incl. graceful stop):
           * default: 1 iterations for each of 1 VUs (maxDuration: 10m0s, gracefulStop: 30s)

WARN[0000] setting headless option is disallowed when browser is remote  category=LaunchOptions elapsed="0 ms" goroutine=74
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Browser:connect" elapsed="3 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f", starts  category="Connection:sendLoop" elapsed="2 ms" goroutine=85 iteration_id=a3ce747c7e404afb
DEBU[0000] bctxid:                                       category="BrowserContext:AddInitScript" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] bctxid:                                       category="BrowserContext:AddInitScript" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f" method:"Target.setAutoAttach"  category="connection:Execute" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="Session:NewSession" elapsed="3 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:"Target.attachedToTarget"         category="Connection:recvLoop:msg.Method:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419  category="Session:NewSession" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:"Target.attachedToTarget"         category="Connection:recvLoop:msg.Method:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:740E3712A2687446D5E2B6A20A03D1DB tid:FD13472557FDEE51260C46AF7C1DC097  category="Session:NewSession" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:740E3712A2687446D5E2B6A20A03D1DB tid:FD13472557FDEE51260C46AF7C1DC097 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:"Target.attachedToTarget"         category="Connection:recvLoop:msg.Method:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="Browser:initEvents:onAttachedToTarget" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 bctxid:8BCBADC1719B6A6C9A6C9DC50970B3A5  category="Browser:onAttachedToTarget" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:29CA8A1D95D4CEE74746A2F54EE93A64 tid:B8D054F931D90E965DFBFB66786BF4C6  category="Session:NewSession" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:29CA8A1D95D4CEE74746A2F54EE93A64 tid:B8D054F931D90E965DFBFB66786BF4C6 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:"Target.attachedToTarget"         category="Connection:recvLoop:msg.Method:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 backgroundPage:true  category="Page:NewPage" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:6ADFB49E2E00D6B42D0A80719F5CEC2E tid:243837E3670EBB126FDD133EDD924B5B  category="Session:NewSession" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:6ADFB49E2E00D6B42D0A80719F5CEC2E tid:243837E3670EBB126FDD133EDD924B5B wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:"Target.attachedToTarget"         category="Connection:recvLoop:msg.Method:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1                                        category="FrameManager:New" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:""                                category="Connection:recvLoop:msg.ID:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category=NewFrameSession elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f" method:"Target.getTargetInfo"  category="connection:Execute" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.enable"  category="Session:Execute" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:""                                category="Connection:recvLoop:msg.ID:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.enable"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f" method:"Target.createBrowserContext"  category="connection:Execute" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:""                                category="Connection:recvLoop:msg.ID:emit" elapsed="4 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] bctxid:FF0ACF5EBE35366A71A3CDCD9A372473       category="Browser:NewContext" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] bctxid:FF0ACF5EBE35366A71A3CDCD9A372473       category="BrowserContext:AddInitScript" elapsed="1 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] bctxid:FF0ACF5EBE35366A71A3CDCD9A372473       category="BrowserContext:AddInitScript" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] bctxid:FF0ACF5EBE35366A71A3CDCD9A372473       category="BrowserContext:NewPage" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f" method:"Target.createTarget"  category="connection:Execute" elapsed="0 ms" goroutine=74 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:D8D48AB8EEB9A38F20CA1C9BF63539EA tid:D220831798E2AC07D88A8E5298C97CB4  category="Session:NewSession" elapsed="24 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:D8D48AB8EEB9A38F20CA1C9BF63539EA tid:D220831798E2AC07D88A8E5298C97CB4 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:"Target.attachedToTarget"         category="Connection:recvLoop:msg.Method:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:""                                category="Connection:recvLoop:msg.ID:emit" elapsed="5 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Browser.getWindowForTarget"  category="Session:Execute" elapsed="17 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Browser.getWindowForTarget"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initEvents" elapsed="2 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initFrameTree" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.enable"  category="Session:Execute" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.enable"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initEvents:go" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.getFrameTree"  category="Session:Execute" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.getFrameTree"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="FrameSession:handleFrameTree" elapsed="2 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3  category="FrameSession:onFrameNavigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 pfid: docid:61AF734E774DD7BBE692E161EB928BD3 fname: furl:chrome://new-tab-page/ initial:true  category="FrameManager:frameNavigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 pfid: docid:61AF734E774DD7BBE692E161EB928BD3 fname: furl:chrome://new-tab-page/ initial:true  category="FrameManager:frameNavigated:removeFrames" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 pfid: docid:61AF734E774DD7BBE692E161EB928BD3 fname: furl:chrome://new-tab-page/ initial:true  category="FrameManager:frameNavigated:MainFrame:initialMainFrameNavigation" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 fid:396A10991BD62C55026A1AD294F0B4A3 pfid:  category=NewFrame elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 furl:  category="FrameManager:setMainFrame" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"" lid:61AF734E774DD7BBE692E161EB928BD3 name:"" url:"chrome://new-tab-page/"  category="Frame:navigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 pfid: docid:61AF734E774DD7BBE692E161EB928BD3 fname: furl:chrome://new-tab-page/ initial:true pdoc:nil - fcurdoc:61AF734E774DD7BBE692E161EB928BD3  category="FrameManager:frameNavigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/"  category="Frame:clearLifecycle" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="FrameSession:handleFrameTree" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3  category="FrameSession:onFrameAttached" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3  category="FrameManager:frameAttached" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3  category=NewFrame elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 cfid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"chrome://new-tab-page/" cfurl:""  category="Frame:addChildFrame" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3  category="FrameManager:frameAttached:emit:EventPageFrameAttached" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226  category="FrameSession:onFrameNavigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3 docid:79E2C18A379133383D892F01D91819C9 fname:backgroundImage furl:about:blank initial:true  category="FrameManager:frameNavigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3 docid:79E2C18A379133383D892F01D91819C9 fname:backgroundImage furl:about:blank initial:true  category="FrameManager:frameNavigated:removeFrames" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"" lid:79E2C18A379133383D892F01D91819C9 name:"backgroundImage" url:"about:blank"  category="Frame:navigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 pfid:396A10991BD62C55026A1AD294F0B4A3 docid:79E2C18A379133383D892F01D91819C9 fname:backgroundImage furl:about:blank initial:true pdoc:nil - fcurdoc:79E2C18A379133383D892F01D91819C9  category="FrameManager:frameNavigated" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank"  category="Frame:clearLifecycle" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initEvents:initRendererEvents" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initIsolatedWorld" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.setLifecycleEventsEnabled"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.setLifecycleEventsEnabled"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 event:commit eventTime:"2023-05-13 17:52:21.802736 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="1 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 event:DOMContentLoaded eventTime:"2023-05-13 17:52:21.882833 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 event:domcontentloaded  category="FrameManager:frameLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" event:domcontentloaded  category="Frame:onLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 event:load eventTime:"2023-05-13 17:52:21.891293 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="1 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 event:load  category="FrameManager:frameLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" event:load  category="Frame:onLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 event:networkAlmostIdle eventTime:"2023-05-13 17:52:21.945441 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 event:networkIdle eventTime:"2023-05-13 17:52:22.057984 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:396A10991BD62C55026A1AD294F0B4A3 event:networkidle  category="FrameManager:frameLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" event:networkidle  category="Frame:onLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:commit eventTime:"2023-05-13 17:52:21.814483 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.createIsolatedWorld"  category="Session:ExecuteWithoutExpectationOnReply" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.createIsolatedWorld"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:DOMContentLoaded eventTime:"2023-05-13 17:52:21.81451 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:domcontentloaded  category="FrameManager:frameLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" event:domcontentloaded  category="Frame:onLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:load eventTime:"2023-05-13 17:52:21.814831 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:load  category="FrameManager:frameLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" event:load  category="Frame:onLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:networkAlmostIdle eventTime:"2023-05-13 17:52:21.815021 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:networkIdle eventTime:"2023-05-13 17:52:21.815021 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 fid:6CFB9DFF6B53E80231BB8B72EC92D226 event:networkidle  category="FrameManager:frameLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" event:networkidle  category="Frame:onLifecycleEvent" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.createIsolatedWorld"  category="Session:ExecuteWithoutExpectationOnReply" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.createIsolatedWorld"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initIsolatedWorld:AddScriptToEvaluateOnNewDocument" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.addScriptToEvaluateOnNewDocument"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.addScriptToEvaluateOnNewDocument"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initOptions" elapsed="2 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:updateViewport" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setDeviceMetricsOverride"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setDeviceMetricsOverride"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Browser.setWindowBounds"  category="Session:Execute" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Browser.setWindowBounds"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setLocaleOverride"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setLocaleOverride"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:updateGeolocation" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:updateExtraHTTPHeaders" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 on:false  category="NewFrameSession:updateRequestInterception" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:updateOffline" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:updateHttpCredentials" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:updateEmulateMedia" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setEmulatedMedia"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setEmulatedMedia"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setFocusEmulationEnabled"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setFocusEmulationEnabled"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setUserAgentOverride"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Emulation.setUserAgentOverride"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fvirta--partial.sandbox.lightning.force.com%2F  category="FrameManager:requestStarted" elapsed="3 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" rid:23077.52  category="Frame:addRequest" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fvirta--partial.sandbox.lightning.force.com%2F pdoc:nil  category="FrameManager:requestStarted" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Ftwitter.com%2F  category="FrameManager:requestStarted" elapsed="1 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" rid:23077.53  category="Frame:addRequest" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Ftwitter.com%2F pdoc:nil  category="FrameManager:requestStarted" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fchrome.google.com%2Fwebstore%3Fhl%3Den  category="FrameManager:requestStarted" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" rid:23077.54  category="Frame:addRequest" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fchrome.google.com%2Fwebstore%3Fhl%3Den pdoc:nil  category="FrameManager:requestStarted" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fchrome.google.com%2Fwebstore%3Fhl%3Den  category="FrameManager:requestReceivedResponse" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.getResponseBody"  category="Session:Execute" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.getResponseBody"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Runtime.runIfWaitingForDebugger"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Runtime.runIfWaitingForDebugger"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fchrome.google.com%2Fwebstore%3Fhl%3Den  category="FrameManager:requestFinished" elapsed="1 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" rid:23077.54  category="Frame:deleteRequest" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fvirta--partial.sandbox.lightning.force.com%2F  category="FrameManager:requestReceivedResponse" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"DOM.enable"  category="Session:Execute" elapsed="4 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"DOM.enable"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.getResponseBody"  category="Session:Execute" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.getResponseBody"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Fvirta--partial.sandbox.lightning.force.com%2F  category="FrameManager:requestFinished" elapsed="1 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" rid:23077.52  category="Frame:deleteRequest" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Ftwitter.com%2F  category="FrameManager:requestReceivedResponse" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.getResponseBody"  category="Session:Execute" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Network.getResponseBody"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:1 rurl:chrome://favicon2/?size=24&scaleFactor=1x&showFallbackMonogram=&pageUrl=https%3A%2F%2Ftwitter.com%2F  category="FrameManager:requestFinished" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" rid:23077.53  category="Frame:deleteRequest" elapsed="0 ms" goroutine=103 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Log.enable"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Log.enable"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Runtime.enable"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Runtime.enable"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 ectxid:5  category="FrameSession:onExecutionContextCreated" elapsed="1 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Target.setAutoAttach"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Target.setAutoAttach"  category="Session:Execute:s.conn.send" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 stid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 ectxid:5 furl:"about:blank"  category=NewExecutionContext elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 fid:6CFB9DFF6B53E80231BB8B72EC92D226 ectxid:5  category="FrameSession:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" ectxid:5 world:utility  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" ectxid:5 world:utility, world set  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 ectxid:4  category="FrameSession:onExecutionContextCreated" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 stid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 ectxid:4 furl:"chrome://new-tab-page/"  category=NewExecutionContext elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 fid:396A10991BD62C55026A1AD294F0B4A3 ectxid:4  category="FrameSession:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" ectxid:4 world:utility  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" ectxid:4 world:utility, world set  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 ectxid:3  category="FrameSession:onExecutionContextCreated" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 stid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 ectxid:3 furl:"chrome://new-tab-page/"  category=NewExecutionContext elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 ectxid:2  category="FrameSession:onExecutionContextCreated" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 stid:396A10991BD62C55026A1AD294F0B4A3 fid:6CFB9DFF6B53E80231BB8B72EC92D226 ectxid:2 furl:"about:blank"  category=NewExecutionContext elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 fid:6CFB9DFF6B53E80231BB8B72EC92D226 ectxid:2  category="FrameSession:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" ectxid:2 world:main  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:6CFB9DFF6B53E80231BB8B72EC92D226 furl:"about:blank" ectxid:2 world:main, world set  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 ectxid:1  category="FrameSession:onExecutionContextCreated" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 stid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 ectxid:1 furl:"chrome://new-tab-page/"  category=NewExecutionContext elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 fid:396A10991BD62C55026A1AD294F0B4A3 ectxid:1  category="FrameSession:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" ectxid:1 world:main  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] fid:396A10991BD62C55026A1AD294F0B4A3 furl:"chrome://new-tab-page/" ectxid:1 world:main, world set  category="Frame:setContext" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:708978CE6E3EDBB451CDBDE818DEAA77 tid:687F6F3BE2E179CB30168295CEF74AF1  category="Session:NewSession" elapsed="2 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:708978CE6E3EDBB451CDBDE818DEAA77 tid:687F6F3BE2E179CB30168295CEF74AF1 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f"  category="Connection:recvLoop:EventAttachedToTarget" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 esid:708978CE6E3EDBB451CDBDE818DEAA77 etid:687F6F3BE2E179CB30168295CEF74AF1 ebctxid:8BCBADC1719B6A6C9A6C9DC50970B3A5 type:"iframe"  category="FrameSession:onAttachedToTarget" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 esid:708978CE6E3EDBB451CDBDE818DEAA77 etid:687F6F3BE2E179CB30168295CEF74AF1 ebctxid:8BCBADC1719B6A6C9A6C9DC50970B3A5 type:"iframe", nil frame  category="FrameSession:attachIFrameToTarget:return" elapsed="0 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Target.setAutoAttach"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Target.setAutoAttach"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Runtime.addBinding"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Runtime.addBinding"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114          category="Page:evaluateOnNewDocument" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.addScriptToEvaluateOnNewDocument"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.addScriptToEvaluateOnNewDocument"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114          category="Page:evaluateOnNewDocument" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.addScriptToEvaluateOnNewDocument"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 method:"Page.addScriptToEvaluateOnNewDocument"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 pageType:page  category="Browser:attachNewPage:addTarget" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 pageType:page  category="Browser:attachNewPage:addSession" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419  category="Browser:initEvents:onAttachedToTarget" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 bctxid:8BCBADC1719B6A6C9A6C9DC50970B3A5  category="Browser:onAttachedToTarget" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 backgroundPage:false  category="Page:NewPage" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] fmid:2                                        category="FrameManager:New" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419  category=NewFrameSession elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 method:"Network.enable"  category="Session:Execute" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 method:"Network.enable"  category="Session:Execute:s.conn.send" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0000] sid: method:""                                category="Connection:recvLoop:msg.ID:emit" elapsed="0 ms" goroutine=84 iteration_id=a3ce747c7e404afb
DEBU[0005] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3 fid:396A10991BD62C55026A1AD294F0B4A3 event:InteractiveTime eventTime:"2023-05-13 17:52:29.333809 -0700 PDT"  category="FrameSession:onPageLifecycle" elapsed="4992 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0030] tid:D220831798E2AC07D88A8E5298C97CB4 bctxid:FF0ACF5EBE35366A71A3CDCD9A372473 err:timed out after 30s  category="Browser:newPageInContext:<-ctx.Done" elapsed="24927 ms" goroutine=74 iteration_id=a3ce747c7e404afb
ERRO[0030] Uncaught (in promise) GoError: creating new page in browser context: timed out after 30s
running at github.com/grafana/xk6-browser/browser.mapBrowser.func3 (native)
default at file:///Users/tmc/code/system-tests/tests/sfdc/test.js:9:15(13)  executor=per-vu-iterations scenario=default
DEBU[0030] Regular duration is done, waiting for iterations to gracefully finish  executor=per-vu-iterations gracefulStop=30s scenario=default
DEBU[0030] Executor finished successfully                executor=default startTime=0s type=per-vu-iterations
DEBU[0030] teardown() is not defined or not exported, skipping!
DEBU[0030] Test finished cleanly
DEBU[0030] Stopping vus and vux_max metrics emission...  phase=execution-scheduler-init
DEBU[0030] Metrics emission of VUs and VUsMax metrics stopped
DEBU[0030] Releasing signal trap...
DEBU[0030] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="FrameSession:initEvents:go:ctx.Done" elapsed="2 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0030] sid:CF071CF9F69A01680CAEDA026B8DB114 tid:396A10991BD62C55026A1AD294F0B4A3  category="NewFrameSession:initEvents:go:return" elapsed="1 ms" goroutine=136 iteration_id=a3ce747c7e404afb
DEBU[0030] returning, ctx.Err: "context canceled"        category="connection:sendLoop" elapsed="0 ms" goroutine=85 iteration_id=a3ce747c7e404afb
DEBU[0030] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 wsURL:"ws://localhost:9222/devtools/browser/d4c0fabe-2e76-4e78-944c-e1b687f93b0f" err:context canceled  category="Connection:send:<-c.ctx.Done()" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0030] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 err:initializing networking *network.EnableParams: context canceled  category="NewFrameSession:NewNetworkManager" elapsed="0 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0030] Sending usage report...
DEBU[0030] Waiting for metric processing to finish...
DEBU[0030] Metrics processing finished!
DEBU[0030] Stopping outputs...
DEBU[0030] Stopping 1 outputs...                         component=output-manager
DEBU[0030] Stopping...                                   component=metrics-engine-ingester
DEBU[0030] Stopped!                                      component=metrics-engine-ingester
DEBU[0030] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 method:"Network.enable"  category="Session:Execute:<-evCancelCtx.Done():return" elapsed="0 ms" goroutine=255 iteration_id=a3ce747c7e404afb
DEBU[0030] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 err:initializing networking *network.EnableParams: context canceled  category="Page:NewPage:NewFrameSession:return" elapsed="10 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0030] sid:413D03C40D981525B3564AFA02EE9FCB tid:FAC0A4F34FA5D8B7AD127532458F0419 pageType:background_page err:context canceled  category="Browser:isPageAttachmentErrorIgnorable:return:<-ctx.Done" elapsed="3 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0030] ctx err: context canceled                     category="Browser:initEvents:defer" elapsed="1 ms" goroutine=113 iteration_id=a3ce747c7e404afb
DEBU[0030] Generating the end-of-test summary...

     data_received........: 3.7 kB 124 B/s
     data_sent............: 474 B  16 B/s
     http_req_duration....: avg=1.83ms min=1.22ms med=2.03ms max=2.24ms p(90)=2.2ms  p(95)=2.22ms
     http_reqs............: 3      0.099937/s
     iteration_duration...: avg=30.01s min=30.01s med=30.01s max=30.01s p(90)=30.01s p(95)=30.01s
     iterations...........: 1      0.033312/s
     vus..................: 1      min=1      max=1
     vus_max..............: 1      min=1      max=1


running (00m30.0s), 0/1 VUs, 1 complete and 0 interrupted iterations
default ✓ [======================================] 1 VUs  00m30.0s/10m0s  1/1 iters, 1 per VU
DEBU[0030] Usage report sent successfully
DEBU[0030] Everything has finished, exiting k6 normally!
@tmc tmc added the bug Something isn't working label May 17, 2023
@tmc
Copy link
Contributor Author

tmc commented May 18, 2023

After launching with more options I can get through this -> I think there should be some docs suggesting how to launch chrome/chromium.

My invocation:

# This script launches Chrome Canary with appropriate flags to aid in tighter iteration loops.
/Applications/Google\ Chrome\ Canary.app/Contents/MacOS/Google\ Chrome\ Canary \
  --remote-debugging-port=9222 \
  --enable-automation \
  --no-first-run \
  --no-startup-window \
  --no-default-browser-check \
  --user-data-dir=.profile \
  --disable-component-extensions-with-background-pages \
  --disable-background-networking

@ka3de
Copy link
Collaborator

ka3de commented May 22, 2023 •
edited

I think there should be some docs suggesting how to launch chrome/chromium.

Hi @tmc ,
I think this is a good suggestion, not that we support connect, it would be useful to have cmdline examples to launch chrome with our default flags for the different O.S. I have created an issue for this in our docs repo here.

In the meantime, you can see here the default chrome arguments we are currently using. Or you can see them here in our implementation.

@tmc tmc closed this as completed Jun 5, 2023
@tmc
Copy link
Contributor Author

tmc commented Jun 5, 2023

If in debug mode I think it should show how chrome was launched.

@tmc tmc reopened this Jun 5, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@tmc @ka3de