Add Logging and Debugging to Investigate RBE Failures

[titusfortner]

I thought most of the failures we have in trunk are related to CDP/browser/driver version mismatches, but that doesn't appear to be true

This is WIP trying to make changes to get tests passing before release. Making it a PR for visibility and so it is easier to track.

I've merged CDP and browser updates to trunk, but there are still failures.

This PR is now focused on getting more information about the RBE results so I can better figure out how to address the Ruby failures.

[qodo-merge-pro]

CI Feedback 🧐

(Feedback updated until commit 846ba74)

A test triggered by this PR failed. Here is an AI-generated analysis of the failure:

Action: Retry Failures on GHA / GHA Retries

Failed stage: Run Bazel [❌]

Failure summary: The action failed due to browser window management issues in Selenium tests. The logs show multiple instances of "no such window" errors, indicating that the test is trying to interact with browser windows that have already been closed or are not available. Specific errors include: "no such window: target window already closed from unknown error: web view not found" "unexpected alert open: {Alert text : cheese}" These errors suggest that the test is not properly handling window/alert lifecycle, possibly attempting to access windows after they've been closed or failing to handle alert dialogs properly.

Relevant error logs: 1: ##[group]Operating System 2: Ubuntu ... 36: Download action repository 'actions/setup-dotnet@v4' (SHA:67a3573c9a986a3f9c594539f4ab511d57bb3ce9) 37: Download action repository 'actions/setup-java@v3' (SHA:0ab4596768b603586c0de567f2430c30f5b0d2b0) 38: Download action repository 'actions/setup-node@v4' (SHA:cdca7365b2dadb8aad0a33bc7601856ffabcc48e) 39: Download action repository 'bazel-contrib/[email protected]' (SHA:663f88d97adf17db2523a5b385d9407a562e5551) 40: Download action repository 'actions/download-artifact@v4' (SHA:cc203385981b70ca67e1cc392babf9cc229d5806) 41: Download action repository 'mxschmitt/action-tmate@v3' (SHA:e5c7151931ca95bad1c6f4190c730ecf8c7dde48) 42: Download action repository 'marvinpinto/action-automatic-releases@latest' (SHA:d68defdd11f9dcc7f52f35c1b7c236ee7513bcc1) 43: Download action repository 'actions/upload-artifact@v4' (SHA:4cec3d8aa04e39d1a68397de0c4cd6fb9dce8ec1) 44: Complete job name: Retry Failures on GHA / GHA Retries ... 934: Package 'php-symfony-asset' is not installed, so not removed 935: Package 'php-symfony-asset-mapper' is not installed, so not removed 936: Package 'php-symfony-browser-kit' is not installed, so not removed 937: Package 'php-symfony-clock' is not installed, so not removed 938: Package 'php-symfony-debug-bundle' is not installed, so not removed 939: Package 'php-symfony-doctrine-bridge' is not installed, so not removed 940: Package 'php-symfony-dom-crawler' is not installed, so not removed 941: Package 'php-symfony-dotenv' is not installed, so not removed 942: Package 'php-symfony-error-handler' is not installed, so not removed ... 1128: Package 'php-uopz-all-dev' is not installed, so not removed 1129: Package 'php8.3-uploadprogress' is not installed, so not removed 1130: Package 'php-uploadprogress-all-dev' is not installed, so not removed 1131: Package 'php8.3-uuid' is not installed, so not removed 1132: Package 'php-uuid-all-dev' is not installed, so not removed 1133: Package 'php-validate' is not installed, so not removed 1134: Package 'php-vlucas-phpdotenv' is not installed, so not removed 1135: Package 'php-voku-portable-ascii' is not installed, so not removed 1136: Package 'php-wmerrors' is not installed, so not removed ... 1935: Setting up feh (3.10.1-1ubuntu0.1) ... 1936: Processing triggers for libgdk-pixbuf-2.0-0:amd64 (2.42.10+dfsg-3ubuntu3.1) ... 1937: Processing triggers for install-info (7.1-3build2) ... 1938: Processing triggers for fontconfig (2.15.0-1.1ubuntu2) ... 1939: Processing triggers for hicolor-icon-theme (0.17-2) ... 1940: Processing triggers for libc-bin (2.39-0ubuntu8.4) ... 1941: Processing triggers for man-db (2.12.0-4build2) ... 1942: Processing triggers for menu (2.1.50) ... 1943: /usr/share/menu/dash: 1: Syntax error: word unexpected (expecting ")") 1944: /usr/share/menu/google-chrome.menu: 1: Syntax error: word unexpected (expecting ")") 1945: /usr/share/menu/bc: 1: Syntax error: word unexpected (expecting ")") 1946: /usr/share/menu/microsoft-edge.menu: 1: Syntax error: word unexpected (expecting ")") 1947: /usr/share/menu/bash: 1: Syntax error: word unexpected (expecting ")") 1948: Setting up fluxbox (1.3.7-1build2) ... 1949: update-alternatives: using /usr/bin/startfluxbox to provide /usr/bin/x-window-manager (x-window-manager) in auto mode 1950: Processing triggers for menu (2.1.50) ... 1951: /usr/share/menu/dash: 1: Syntax error: word unexpected (expecting ")") 1952: /usr/share/menu/google-chrome.menu: 1: Syntax error: word unexpected (expecting ")") 1953: /usr/share/menu/bc: 1: Syntax error: word unexpected (expecting ")") 1954: /usr/share/menu/microsoft-edge.menu: 1: Syntax error: word unexpected (expecting ")") 1955: /usr/share/menu/bash: 1: Syntax error: word unexpected (expecting ")") 1956: Running kernel seems to be up-to-date. 1957: No services need to be restarted. 1958: No containers need to be restarted. 1959: No user sessions are running outdated binaries. 1960: No VM guests are running outdated hypervisor (qemu) binaries on this host. 1961: Warning: Failed to open file(fluxbox.cat) ... 1973: > Warning: Could not resolve keysym XF86FishingChart 1974: > Warning: Could not resolve keysym XF86SingleRangeRadar 1975: > Warning: Could not resolve keysym XF86DualRangeRadar 1976: > Warning: Could not resolve keysym XF86RadarOverlay 1977: > Warning: Could not resolve keysym XF86TraditionalSonar 1978: > Warning: Could not resolve keysym XF86ClearvuSonar 1979: > Warning: Could not resolve keysym XF86SidevuSonar 1980: > Warning: Could not resolve keysym XF86NavInfo 1981: Errors from xkbcomp are not fatal to the X server 1982: Failed to read: session.ignoreBorder 1983: Setting default value 1984: Failed to read: session.forcePseudoTransparency 1985: Setting default value 1986: Failed to read: session.colorsPerChannel 1987: Setting default value 1988: Failed to read: session.doubleClickInterval 1989: Setting default value 1990: Failed to read: session.tabPadding 1991: Setting default value 1992: Failed to read: session.styleOverlay 1993: Setting default value 1994: Failed to read: session.slitlistFile 1995: Setting default value 1996: Failed to read: session.appsFile 1997: Setting default value 1998: Failed to read: session.tabsAttachArea 1999: Setting default value 2000: Failed to read: session.menuSearch 2001: Setting default value 2002: Failed to read: session.cacheLife 2003: Setting default value 2004: Failed to read: session.cacheMax 2005: Setting default value 2006: Failed to read: session.autoRaiseDelay 2007: Setting default value 2008: Failed to read: session.ignoreBorder 2009: Setting default value 2010: Failed to read: session.forcePseudoTransparency 2011: Setting default value 2012: Failed to read: session.colorsPerChannel 2013: Setting default value 2014: Failed to read: session.doubleClickInterval 2015: Setting default value 2016: Failed to read: session.tabPadding 2017: Setting default value 2018: Failed to read: session.styleOverlay 2019: Setting default value 2020: Failed to read: session.slitlistFile 2021: Setting default value 2022: Failed to read: session.appsFile 2023: Setting default value 2024: Failed to read: session.tabsAttachArea 2025: Setting default value 2026: Failed to read: session.menuSearch 2027: Setting default value 2028: Failed to read: session.cacheLife 2029: Setting default value 2030: Failed to read: session.cacheMax 2031: Setting default value 2032: Failed to read: session.autoRaiseDelay 2033: Setting default value 2034: Failed to read: session.screen0.opaqueMove 2035: Setting default value 2036: Failed to read: session.screen0.fullMaximization 2037: Setting default value 2038: Failed to read: session.screen0.maxIgnoreIncrement 2039: Setting default value 2040: Failed to read: session.screen0.maxDisableMove 2041: Setting default value 2042: Failed to read: session.screen0.maxDisableResize 2043: Setting default value 2044: Failed to read: session.screen0.workspacewarping 2045: Setting default value 2046: Failed to read: session.screen0.showwindowposition 2047: Setting default value 2048: Failed to read: session.screen0.autoRaise 2049: Setting default value 2050: Failed to read: session.screen0.clickRaises 2051: Setting default value 2052: Failed to read: session.screen0.defaultDeco 2053: Setting default value 2054: Failed to read: session.screen0.tab.placement 2055: Setting default value 2056: Failed to read: session.screen0.windowMenu 2057: Setting default value 2058: Failed to read: session.screen0.noFocusWhileTypingDelay 2059: Setting default value 2060: Failed to read: session.screen0.workspaces 2061: Setting default value 2062: Failed to read: session.screen0.edgeSnapThreshold 2063: Setting default value 2064: Failed to read: session.screen0.window.focus.alpha 2065: Setting default value 2066: Failed to read: session.screen0.window.unfocus.alpha 2067: Setting default value 2068: Failed to read: session.screen0.menu.alpha 2069: Setting default value 2070: Failed to read: session.screen0.menuDelay 2071: Setting default value 2072: Failed to read: session.screen0.tab.width 2073: Setting default value 2074: Failed to read: session.screen0.tooltipDelay 2075: Setting default value 2076: Failed to read: session.screen0.allowRemoteActions 2077: Setting default value 2078: Failed to read: session.screen0.clientMenu.usePixmap 2079: Setting default value 2080: Failed to read: session.screen0.tabs.usePixmap 2081: Setting default value 2082: Failed to read: session.screen0.tabs.maxOver 2083: Setting default value 2084: Failed to read: session.screen0.tabs.intitlebar 2085: Setting default value 2086: Failed to read: session.screen0.focusModel 2087: Setting default value 2088: Failed to read: session.screen0.tabFocusModel 2089: Setting default value 2090: Failed to read: session.screen0.focusNewWindows 2091: Setting default value 2092: Failed to read: session.screen0.focusSameHead 2093: Setting default value 2094: Failed to read: session.screen0.rowPlacementDirection 2095: Setting default value 2096: Failed to read: session.screen0.colPlacementDirection 2097: Setting default value 2098: Failed to read: session.screen0.windowPlacement 2099: Setting default value 2100: Failed to read: session.ignoreBorder 2101: Setting default value 2102: Failed to read: session.forcePseudoTransparency 2103: Setting default value 2104: Failed to read: session.colorsPerChannel 2105: Setting default value 2106: Failed to read: session.doubleClickInterval 2107: Setting default value 2108: Failed to read: session.tabPadding 2109: Setting default value 2110: Failed to read: session.styleOverlay 2111: Setting default value 2112: Failed to read: session.slitlistFile 2113: Setting default value 2114: Failed to read: session.appsFile 2115: Setting default value 2116: Failed to read: session.tabsAttachArea 2117: Setting default value 2118: Failed to read: session.menuSearch 2119: Setting default value 2120: Failed to read: session.cacheLife 2121: Setting default value 2122: Failed to read: session.cacheMax 2123: Setting default value 2124: Failed to read: session.autoRaiseDelay 2125: Setting default value 2126: Failed to read: session.screen0.opaqueMove 2127: Setting default value 2128: Failed to read: session.screen0.fullMaximization 2129: Setting default value 2130: Failed to read: session.screen0.maxIgnoreIncrement 2131: Setting default value 2132: Failed to read: session.screen0.maxDisableMove 2133: Setting default value 2134: Failed to read: session.screen0.maxDisableResize 2135: Setting default value 2136: Failed to read: session.screen0.workspacewarping 2137: Setting default value 2138: Failed to read: session.screen0.showwindowposition 2139: Setting default value 2140: Failed to read: session.screen0.autoRaise 2141: Setting default value 2142: Failed to read: session.screen0.clickRaises 2143: Setting default value 2144: Failed to read: session.screen0.defaultDeco 2145: Setting default value 2146: Failed to read: session.screen0.tab.placement 2147: Setting default value 2148: Failed to read: session.screen0.windowMenu 2149: Setting default value 2150: Failed to read: session.screen0.noFocusWhileTypingDelay 2151: Setting default value 2152: Failed to read: session.screen0.workspaces 2153: Setting default value 2154: Failed to read: session.screen0.edgeSnapThreshold 2155: Setting default value 2156: Failed to read: session.screen0.window.focus.alpha 2157: Setting default value 2158: Failed to read: session.screen0.window.unfocus.alpha 2159: Setting default value 2160: Failed to read: session.screen0.menu.alpha 2161: Setting default value 2162: Failed to read: session.screen0.menuDelay 2163: Setting default value 2164: Failed to read: session.screen0.tab.width 2165: Setting default value 2166: Failed to read: session.screen0.tooltipDelay 2167: Setting default value 2168: Failed to read: session.screen0.allowRemoteActions 2169: Setting default value 2170: Failed to read: session.screen0.clientMenu.usePixmap 2171: Setting default value 2172: Failed to read: session.screen0.tabs.usePixmap 2173: Setting default value 2174: Failed to read: session.screen0.tabs.maxOver 2175: Setting default value 2176: Failed to read: session.screen0.tabs.intitlebar 2177: Setting default value 2178: Failed to read: session.screen0.focusModel 2179: Setting default value 2180: Failed to read: session.screen0.tabFocusModel 2181: Setting default value 2182: Failed to read: session.screen0.focusNewWindows 2183: Setting default value 2184: Failed to read: session.screen0.focusSameHead 2185: Setting default value 2186: Failed to read: session.screen0.rowPlacementDirection 2187: Setting default value 2188: Failed to read: session.screen0.colPlacementDirection 2189: Setting default value 2190: Failed to read: session.screen0.windowPlacement 2191: Setting default value 2192: Failed to read: session.screen0.slit.acceptKdeDockapps 2193: Setting default value 2194: Failed to read: session.screen0.slit.autoHide 2195: Setting default value 2196: Failed to read: session.screen0.slit.maxOver 2197: Setting default value 2198: Failed to read: session.screen0.slit.placement 2199: Setting default value 2200: Failed to read: session.screen0.slit.alpha 2201: Setting default value 2202: Failed to read: session.screen0.slit.onhead 2203: Setting default value 2204: Failed to read: session.screen0.slit.layer 2205: Setting default value 2206: Failed to read: session.screen0.toolbar.autoHide 2207: Setting default value 2208: Failed to read: session.screen0.toolbar.maxOver 2209: Setting default value 2210: Failed to read: session.screen0.toolbar.visible 2211: Setting default value 2212: Failed to read: session.screen0.toolbar.alpha 2213: Setting default value 2214: Failed to read: session.screen0.toolbar.layer 2215: Setting default value 2216: Failed to read: session.screen0.toolbar.onhead 2217: Setting default value 2218: Failed to read: session.screen0.toolbar.placement 2219: Setting default value 2220: Failed to read: session.screen0.toolbar.height 2221: Setting default value 2222: Failed to read: session.screen0.iconbar.mode 2223: Setting default value 2224: Failed to read: session.screen0.iconbar.alignment 2225: Setting default value 2226: Failed to read: session.screen0.iconbar.iconWidth 2227: Setting default value 2228: Failed to read: session.screen0.iconbar.iconTextPadding 2229: Setting default value 2230: Failed to read: session.screen0.iconbar.usePixmap ... 2361: - bazel-logs (ID: 2762066340, Size: 41178) 2362: Redirecting to blob download url: https://productionresultssa5.blob.core.windows.net/actions-results/9492c78e-4f23-40e8-9686-bae5d2a2d3eb/workflow-job-run-c9c9797c-3a2d-56f0-8058-8569fcba4edc/artifacts/75675ec0e7ca6563aea1b167354890ea4a234db7f3ea0b837ded6ad6c79b15c3.zip 2363: Starting download of artifact to: /home/runner/work/selenium/selenium/bazel-logs 2364: (node:19426) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. 2365: (Use `node --trace-deprecation ...` to show where the warning was created) 2366: Artifact download completed successfully. 2367: Total of 1 artifact(s) downloaded 2368: Download artifact has finished successfully 2369: ##[group]Run ./go retry_failed_tests "${BAZEL_LOG_FILE}" false 2370: �[36;1m./go retry_failed_tests "${BAZEL_LOG_FILE}" false�[0m ... 2378: NODE_AUTH_TOKEN: *** 2379: BAZEL_LOG_FILE: bazel-logs/bzl_13891546950.log 2380: SE_AVOID_STATS: true 2381: JAVA_HOME: /opt/hostedtoolcache/Java_Temurin-Hotspot_jdk/17.0.14-7/x64 2382: JAVA_HOME_17_X64: /opt/hostedtoolcache/Java_Temurin-Hotspot_jdk/17.0.14-7/x64 2383: BAZELISK_GITHUB_TOKEN: *** 2384: DISPLAY: :99 2385: ##[endgroup] 2386: Executing rake task: retry_failed_tests[bazel-logs/bzl_13891546950.log,false] 2387: Found 7 failing tests; Retrying ... 2840: "goog:chromeOptions": { 2841: "args": [ "--no-sandbox", "--disable-gpu" ], 2842: "binary": "external/+pin_browsers_extension+linux_chrome/chrome-linux64/chrome" 2843: } 2844: } 2845: } 2846: } 2847: [1742186449.162][INFO]: Populating Preferences file: { 2848: "alternate_error_pages": { ... 2905: "rev_checking": { 2906: "enabled": false 2907: } 2908: } 2909: } 2910: [1742186449.162][INFO]: ChromeDriver supports communication with Chrome via pipes. This is more reliable and more secure. 2911: [1742186449.162][INFO]: Use the --remote-debugging-pipe Chrome switch instead of the default --remote-debugging-port to enable this communication mode. 2912: [1742186449.162][INFO]: Launching chrome: external/+pin_browsers_extension+linux_chrome/chrome-linux64/chrome --allow-pre-commit-input --disable-background-networking --disable-client-side-phishing-detection --disable-default-apps --disable-gpu --disable-hang-monitor --disable-popup-blocking --disable-prompt-on-repost --disable-sync --enable-automation --enable-logging --log-level=0 --no-first-run --no-sandbox --no-service-autorun --password-store=basic --remote-debugging-port=0 --test-type=webdriver --use-mock-keychain --user-data-dir=/tmp/.org.chromium.Chromium.pnJGm5 data:, 2913: [22658:22675:0317/044049.365750:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2914: [22658:22675:0317/044049.365782:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2915: [22658:22675:0317/044049.365800:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2916: [22658:22675:0317/044049.365816:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2917: [22658:22675:0317/044049.381165:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2918: [22658:22675:0317/044049.399314:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2919: DevTools listening on ws://127.0.0.1:46639/devtools/browser/08f85180-fe2f-4f7a-a7bd-611cd5ebe348 2920: [22658:22675:0317/044049.431745:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2921: [22658:22675:0317/044049.431774:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2922: [1742186449.442][DEBUG]: DevTools HTTP Request: http://localhost:46639/json/version 2923: [22658:22671:0317/044049.448739:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2924: [22658:22658:0317/044049.469324:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2925: [22658:22658:0317/044049.471743:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2926: [22658:22675:0317/044049.471832:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2927: [22658:22658:0317/044049.474755:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2928: [22658:22658:0317/044049.479671:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2929: [22658:22670:0317/044049.480263:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 2931: "Browser": "Chrome/134.0.6998.88", 2932: "Protocol-Version": "1.3", 2933: "User-Agent": "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36", 2934: "V8-Version": "13.4.114.19", 2935: "WebKit-Version": "537.36 (@7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0)", 2936: "webSocketDebuggerUrl": "ws://localhost:46639/devtools/browser/08f85180-fe2f-4f7a-a7bd-611cd5ebe348" 2937: } 2938: [1742186449.483][DEBUG]: DevTools HTTP Request: http://localhost:46639/json/list 2939: [22658:22658:0317/044049.491751:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2940: [22658:22741:0317/044049.493929:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.Properties.Get: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files 2941: [22658:22741:0317/044049.494143:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.UPower.GetDisplayDevice: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files 2942: [22658:22741:0317/044049.494745:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.UPower.EnumerateDevices: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files 2943: [22658:22658:0317/044049.496180:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2944: [22658:22673:0317/044049.496240:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 2946: "description": "", 2947: "devtoolsFrontendUrl": "/devtools/inspector.html?ws=localhost:46639/devtools/page/864D5F984D17014A319518FC03CB670B", 2948: "id": "864D5F984D17014A319518FC03CB670B", 2949: "title": "", 2950: "type": "page", 2951: "url": "data:,", 2952: "webSocketDebuggerUrl": "ws://localhost:46639/devtools/page/864D5F984D17014A319518FC03CB670B" 2953: } ] 2954: [22658:22658:0317/044049.502294:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2955: [22658:22671:0317/044049.502386:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 2956: [1742186449.504][INFO]: resolved localhost to ["::1","127.0.0.1"] 2957: [22658:22658:0317/044049.510244:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 2958: [22658:22671:0317/044049.510302:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 2962: "type": "browser" 2963: }, { 2964: "exclude": true, 2965: "type": "page" 2966: }, { 2967: "exclude": false 2968: } ] 2969: } 2970: [22658:22658:0317/044049.513750:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: ... 3495: "navigationType": "differentDocument", 3496: "url": "http://localhost:38221/iframes.html" 3497: } 3498: [1742186450.244][DEBUG]: DevTools WebSocket Event: Page.frameStartedLoading (session_id=79AF897A5844AF6FF9E84B2E440E3CA2) 864D5F984D17014A319518FC03CB670B { 3499: "frameId": "864D5F984D17014A319518FC03CB670B" 3500: } 3501: [1742186450.252][DEBUG]: DevTools WebSocket Event: Log.entryAdded (session_id=79AF897A5844AF6FF9E84B2E440E3CA2) 864D5F984D17014A319518FC03CB670B { 3502: "entry": { 3503: "level": "error", 3504: "networkRequestId": "22742.2", 3505: "source": "network", 3506: "text": "Failed to load resource: the server responded with a status of 404 (Not Found)", ... 9034: } ] 9035: } 9036: [1742186450.960][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetWindows [ "864D5F984D17014A319518FC03CB670B" ] 9037: 2025-03-17 04:40:50 DEBUG Selenium [:header] <<< {"content-length"=>["46"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 9038: 2025-03-17 04:40:50 DEBUG Selenium [:command] <- {"value":["864D5F984D17014A319518FC03CB670B"]} 9039: 2025-03-17 04:40:50 DEBUG Selenium [:command] -> GET session/40962e67f207067567890ec269513d1e/window 9040: [1742186450.963][INFO]: [40962e67f207067567890ec269513d1e] COMMAND GetWindow { 9041: } 9042: [1742186450.971][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetWindow ERROR no such window: target window already closed 9043: from unknown error: web view not found 9044: (Session info: chrome=134.0.6998.88) 9045: 2025-03-17 04:40:50 DEBUG Selenium [:header] <<< {"content-length"=>["809"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 9046: 2025-03-17 04:40:50 DEBUG Selenium [:command] <- {"value":{"error":"no such window","message":"no such window: target window already closed\nfrom unknown error: web view not found\n (Session info: chrome=134.0.6998.88)","stacktrace":"#0 0x55946e878a1a \u003Cunknown>\n#1 0x55946e330390 \u003Cunknown>\n#2 0x55946e304615 \u003Cunknown>\n#3 0x55946e3b03ae \u003Cunknown>\n#4 0x55946e3b8ee8 \u003Cunknown>\n#5 0x55946e3a7b98 \u003Cunknown>\n#6 0x55946e37360e \u003Cunknown>\n#7 0x55946e374dd1 \u003Cunknown>\n#8 0x55946e83eddb \u003Cunknown>\n#9 0x55946e842cbc \u003Cunknown>\n#10 0x55946e826392 \u003Cunknown>\n#11 0x55946e843834 \u003Cunknown>\n#12 0x55946e80a1ef \u003Cunknown>\n#13 0x55946e867038 \u003Cunknown>\n#14 0x55946e867216 \u003Cunknown>\n#15 0x55946e877896 \u003Cunknown>\n#16 0x7fddc389caa4 \u003Cunknown>\n#17 0x7fddc3929c3c \u003Cunknown>\n"}} ... 16815: "type": "browser" 16816: }, { 16817: "exclude": true, 16818: "type": "page" 16819: }, { 16820: "exclude": false 16821: } ] 16822: } 16823: [22687:22712:0317/044052.010375:ERROR:shared_image_manager.cc(509)] SharedImageManager::ProduceMemory: Trying to Produce a Memory representation from a non-existent mailbox. ... 21292: "browserContextId": "1D7D975F1DFB2D348A56035FC4A4BD7D", 21293: "canAccessOpener": false, 21294: "targetId": "C2BA16F6EE0FA4F897A46A9A51589807", 21295: "title": "XHTML Test Page", 21296: "type": "tab", 21297: "url": "http://localhost:38221/xhtmlTest.html" 21298: } ] 21299: } 21300: [1742186452.879][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE CloseWindow ERROR no such window: target window already closed 21301: from unknown error: web view not found 21302: (Session info: chrome=134.0.6998.88) 21303: 2025-03-17 04:40:52 DEBUG Selenium [:header] <<< {"content-length"=>["809"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 21304: 2025-03-17 04:40:52 DEBUG Selenium [:command] <- {"value":{"error":"no such window","message":"no such window: target window already closed\nfrom unknown error: web view not found\n (Session info: chrome=134.0.6998.88)","stacktrace":"#0 0x55946e878a1a \u003Cunknown>\n#1 0x55946e330390 \u003Cunknown>\n#2 0x55946e304615 \u003Cunknown>\n#3 0x55946e3b03ae \u003Cunknown>\n#4 0x55946e3b90b5 \u003Cunknown>\n#5 0x55946e3a7b98 \u003Cunknown>\n#6 0x55946e37360e \u003Cunknown>\n#7 0x55946e374dd1 \u003Cunknown>\n#8 0x55946e83eddb \u003Cunknown>\n#9 0x55946e842cbc \u003Cunknown>\n#10 0x55946e826392 \u003Cunknown>\n#11 0x55946e843834 \u003Cunknown>\n#12 0x55946e80a1ef \u003Cunknown>\n#13 0x55946e867038 \u003Cunknown>\n#14 0x55946e867216 \u003Cunknown>\n#15 0x55946e877896 \u003Cunknown>\n#16 0x7fddc389caa4 \u003Cunknown>\n#17 0x7fddc3929c3c \u003Cunknown>\n"}} ... 21431: 2025-03-17 04:40:52 DEBUG Selenium [:header] <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 21432: 2025-03-17 04:40:52 DEBUG Selenium [:command] <- {"value":null} 21433: 2025-03-17 04:40:52 DEBUG Selenium [:command] -> GET session/40962e67f207067567890ec269513d1e/window 21434: [1742186452.894][INFO]: [40962e67f207067567890ec269513d1e] COMMAND GetWindow { 21435: } 21436: [1742186452.894][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetWindow "864D5F984D17014A319518FC03CB670B" 21437: 2025-03-17 04:40:52 DEBUG Selenium [:header] <<< {"content-length"=>["44"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 21438: 2025-03-17 04:40:52 DEBUG Selenium [:command] <- {"value":"864D5F984D17014A319518FC03CB670B"} 21439: does not error if switching to a new window with a block that closes window ... 25004: "browserContextId": "1D7D975F1DFB2D348A56035FC4A4BD7D", 25005: "canAccessOpener": false, 25006: "targetId": "C2BA16F6EE0FA4F897A46A9A51589807", 25007: "title": "XHTML Test Page", 25008: "type": "tab", 25009: "url": "http://localhost:38221/xhtmlTest.html" 25010: } ] 25011: } 25012: [22687:22712:0317/044053.325059:ERROR:shared_image_manager.cc(509)] SharedImageManager::ProduceMemory: Trying to Produce a Memory representation from a non-existent mailbox. ... 33257: "canAccessOpener": false, 33258: "targetId": "C2BA16F6EE0FA4F897A46A9A51589807", 33259: "title": "XHTML Test Page", 33260: "type": "tab", 33261: "url": "http://localhost:38221/xhtmlTest.html" 33262: } ] 33263: } 33264: [1742186454.456][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE CloseWindow [ "864D5F984D17014A319518FC03CB670B", "2A236A0B51F4DB0C1AF994A2F0AB65D3" ] 33265: [22687:22712:0317/044054.456153:ERROR:shared_image_manager.cc(509)] SharedImageManager::ProduceMemory: Trying to Produce a Memory representation from a non-existent mailbox. 33266: [22687:22712:0317/044054.456400:ERROR:shared_image_manager.cc(509)] SharedImageManager::ProduceMemory: Trying to Produce a Memory representation from a non-existent mailbox. 33267: [22687:22712:0317/044054.456588:ERROR:shared_image_manager.cc(509)] SharedImageManager::ProduceMemory: Trying to Produce a Memory representation from a non-existent mailbox. 33268: [22687:22712:0317/044054.456686:ERROR:shared_image_manager.cc(509)] SharedImageManager::ProduceMemory: Trying to Produce a Memory representation from a non-existent mailbox. ... 36674: } ] 36675: } 36676: [1742186454.942][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetWindows [ "1614963066AEB9385A78A696B4279626", "C60A8058F33FE3F986DB09C44EC0A507" ] 36677: 2025-03-17 04:40:54 DEBUG Selenium [:header] <<< {"content-length"=>["81"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 36678: 2025-03-17 04:40:54 DEBUG Selenium [:command] <- {"value":["1614963066AEB9385A78A696B4279626","C60A8058F33FE3F986DB09C44EC0A507"]} 36679: 2025-03-17 04:40:54 DEBUG Selenium [:command] -> GET session/40962e67f207067567890ec269513d1e/window 36680: [1742186454.946][INFO]: [40962e67f207067567890ec269513d1e] COMMAND GetWindow { 36681: } 36682: [1742186454.955][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetWindow ERROR no such window: target window already closed 36683: from unknown error: web view not found 36684: (Session info: chrome=134.0.6998.88) 36685: 2025-03-17 04:40:54 DEBUG Selenium [:header] <<< {"content-length"=>["809"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 36686: 2025-03-17 04:40:54 DEBUG Selenium [:command] <- {"value":{"error":"no such window","message":"no such window: target window already closed\nfrom unknown error: web view not found\n (Session info: chrome=134.0.6998.88)","stacktrace":"#0 0x55946e878a1a \u003Cunknown>\n#1 0x55946e330390 \u003Cunknown>\n#2 0x55946e304615 \u003Cunknown>\n#3 0x55946e3b03ae \u003Cunknown>\n#4 0x55946e3b8ee8 \u003Cunknown>\n#5 0x55946e3a7b98 \u003Cunknown>\n#6 0x55946e37360e \u003Cunknown>\n#7 0x55946e374dd1 \u003Cunknown>\n#8 0x55946e83eddb \u003Cunknown>\n#9 0x55946e842cbc \u003Cunknown>\n#10 0x55946e826392 \u003Cunknown>\n#11 0x55946e843834 \u003Cunknown>\n#12 0x55946e80a1ef \u003Cunknown>\n#13 0x55946e867038 \u003Cunknown>\n#14 0x55946e867216 \u003Cunknown>\n#15 0x55946e877896 \u003Cunknown>\n#16 0x7fddc389caa4 \u003Cunknown>\n#17 0x7fddc3929c3c \u003Cunknown>\n"}} ... 54375: [1742186456.420][DEBUG]: DevTools WebSocket Response: Runtime.evaluate (id=2111) (session_id=47584085DB938E732320DD4B9B74CC98) C60A8058F33FE3F986DB09C44EC0A507 { 54376: "result": { 54377: "description": "1", 54378: "type": "number", 54379: "value": 1 54380: } 54381: } 54382: [1742186456.420][INFO]: Done waiting for pending navigations. Status: ok 54383: [1742186456.428][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetAlertMessage ERROR no such alert 54384: (Session info: chrome=134.0.6998.88) 54385: 2025-03-17 04:40:56 DEBUG Selenium [:header] <<< {"content-length"=>["807"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 54386: 2025-03-17 04:40:56 DEBUG Selenium [:command] <- {"value":{"error":"no such alert","message":"no such alert\n (Session info: chrome=134.0.6998.88)","stacktrace":"#0 0x55946e878a1a \u003Cunknown>\n#1 0x55946e3301e3 \u003Cunknown>\n#2 0x55946e3198de \u003Cunknown>\n#3 0x55946e343fc2 \u003Cunknown>\n#4 0x55946e356821 \u003Cunknown>\n#5 0x55946e3a7c5b \u003Cunknown>\n#6 0x55946e356638 \u003Cunknown>\n#7 0x55946e3a79a3 \u003Cunknown>\n#8 0x55946e37360e \u003Cunknown>\n#9 0x55946e374dd1 \u003Cunknown>\n#10 0x55946e83eddb \u003Cunknown>\n#11 0x55946e842cbc \u003Cunknown>\n#12 0x55946e826392 \u003Cunknown>\n#13 0x55946e843834 \u003Cunknown>\n#14 0x55946e80a1ef \u003Cunknown>\n#15 0x55946e867038 \u003Cunknown>\n#16 0x55946e867216 \u003Cunknown>\n#17 0x55946e877896 \u003Cunknown>\n#18 0x7fddc389caa4 \u003Cunknown>\n#19 0x7fddc3929c3c \u003Cunknown>\n"}} ... 54473: [1742186456.448][DEBUG]: DevTools WebSocket Response: Runtime.evaluate (id=2115) (session_id=47584085DB938E732320DD4B9B74CC98) C60A8058F33FE3F986DB09C44EC0A507 { 54474: "result": { 54475: "description": "1", 54476: "type": "number", 54477: "value": 1 54478: } 54479: } 54480: [1742186456.448][INFO]: Done waiting for pending navigations. Status: ok 54481: [1742186456.456][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetAlertMessage ERROR no such alert 54482: (Session info: chrome=134.0.6998.88) 54483: 2025-03-17 04:40:56 DEBUG Selenium [:header] <<< {"content-length"=>["807"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 54484: 2025-03-17 04:40:56 DEBUG Selenium [:command] <- {"value":{"error":"no such alert","message":"no such alert\n (Session info: chrome=134.0.6998.88)","stacktrace":"#0 0x55946e878a1a \u003Cunknown>\n#1 0x55946e3301e3 \u003Cunknown>\n#2 0x55946e3198de \u003Cunknown>\n#3 0x55946e343fc2 \u003Cunknown>\n#4 0x55946e356821 \u003Cunknown>\n#5 0x55946e3a7c5b \u003Cunknown>\n#6 0x55946e356638 \u003Cunknown>\n#7 0x55946e3a79a3 \u003Cunknown>\n#8 0x55946e37360e \u003Cunknown>\n#9 0x55946e374dd1 \u003Cunknown>\n#10 0x55946e83eddb \u003Cunknown>\n#11 0x55946e842cbc \u003Cunknown>\n#12 0x55946e826392 \u003Cunknown>\n#13 0x55946e843834 \u003Cunknown>\n#14 0x55946e80a1ef \u003Cunknown>\n#15 0x55946e867038 \u003Cunknown>\n#16 0x55946e867216 \u003Cunknown>\n#17 0x55946e877896 \u003Cunknown>\n#18 0x7fddc389caa4 \u003Cunknown>\n#19 0x7fddc3929c3c \u003Cunknown>\n"}} ... 54549: 2025-03-17 04:40:56 DEBUG Selenium [:header] <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 54550: 2025-03-17 04:40:56 DEBUG Selenium [:command] <- {"value":null} 54551: 2025-03-17 04:40:56 DEBUG Selenium [:command] -> GET session/40962e67f207067567890ec269513d1e/window 54552: [1742186456.467][INFO]: [40962e67f207067567890ec269513d1e] COMMAND GetWindow { 54553: } 54554: [1742186456.468][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetWindow "C60A8058F33FE3F986DB09C44EC0A507" 54555: 2025-03-17 04:40:56 DEBUG Selenium [:header] <<< {"content-length"=>["44"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 54556: 2025-03-17 04:40:56 DEBUG Selenium [:command] <- {"value":"C60A8058F33FE3F986DB09C44EC0A507"} 54557: raises NoAlertOpenError if no alert is present 54558: unhandled alert error ... 57390: "promptText": "" 57391: } 57392: [1742186456.661][DEBUG]: DevTools WebSocket Event: Page.javascriptDialogClosed (session_id=47584085DB938E732320DD4B9B74CC98) C60A8058F33FE3F986DB09C44EC0A507 { 57393: "result": false, 57394: "userInput": "" 57395: } 57396: [1742186456.664][DEBUG]: DevTools WebSocket Response: Page.handleJavaScriptDialog (id=2191) (session_id=47584085DB938E732320DD4B9B74CC98) C60A8058F33FE3F986DB09C44EC0A507 { 57397: } 57398: [1742186456.671][INFO]: [40962e67f207067567890ec269513d1e] RESPONSE GetTitle ERROR unexpected alert open: {Alert text : cheese} 57399: (Session info: chrome=134.0.6998.88) 57400: 2025-03-17 04:40:56 DEBUG Selenium [:header] <<< {"content-length"=>["731"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 57401: 2025-03-17 04:40:56 DEBUG Selenium [:command] <- {"value":{"data":{"text":"cheese"},"error":"unexpected alert open","message":"unexpected alert open: {Alert text : cheese}\n (Session info: chrome=134.0.6998.88)","stacktrace":"#0 0x55946e878a1a \u003Cunknown>\n#1 0x55946e330390 \u003Cunknown>\n#2 0x55946e3cebba \u003Cunknown>\n#3 0x55946e3a79a3 \u003Cunknown>\n#4 0x55946e37360e \u003Cunknown>\n#5 0x55946e374dd1 \u003Cunknown>\n#6 0x55946e83eddb \u003Cunknown>\n#7 0x55946e842cbc \u003Cunknown>\n#8 0x55946e826392 \u003Cunknown>\n#9 0x55946e843834 \u003Cunknown>\n#10 0x55946e80a1ef \u003Cunknown>\n#11 0x55946e867038 \u003Cunknown>\n#12 0x55946e867216 \u003Cunknown>\n#13 0x55946e877896 \u003Cunknown>\n#14 0x7fddc389caa4 \u003Cunknown>\n#15 0x7fddc3929c3c \u003Cunknown>\n"}} ... 57434: "goog:chromeOptions": { 57435: "args": [ "--no-sandbox", "--disable-gpu" ], 57436: "binary": "external/+pin_browsers_extension+linux_chrome/chrome-linux64/chrome" 57437: } 57438: } 57439: } 57440: } 57441: [1742186457.107][INFO]: Populating Preferences file: { 57442: "alternate_error_pages": { ... 57499: "rev_checking": { 57500: "enabled": false 57501: } 57502: } 57503: } 57504: [1742186457.107][INFO]: ChromeDriver supports communication with Chrome via pipes. This is more reliable and more secure. 57505: [1742186457.107][INFO]: Use the --remote-debugging-pipe Chrome switch instead of the default --remote-debugging-port to enable this communication mode. 57506: [1742186457.107][INFO]: Launching chrome: external/+pin_browsers_extension+linux_chrome/chrome-linux64/chrome --allow-pre-commit-input --disable-background-networking --disable-client-side-phishing-detection --disable-default-apps --disable-gpu --disable-hang-monitor --disable-popup-blocking --disable-prompt-on-repost --disable-sync --enable-automation --enable-logging --log-level=0 --no-first-run --no-sandbox --no-service-autorun --password-store=basic --remote-debugging-port=0 --test-type=webdriver --use-mock-keychain --user-data-dir=/tmp/.org.chromium.Chromium.wcMwHe data:, 57507: [22883:22898:0317/044057.371084:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57508: [22883:22898:0317/044057.372877:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57509: [22883:22898:0317/044057.372944:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57510: [22883:22898:0317/044057.372964:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57511: [22883:22898:0317/044057.390023:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57512: [22883:22898:0317/044057.421451:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57513: DevTools listening on ws://127.0.0.1:37877/devtools/browser/9a4e207f-82e5-44f7-af9c-89172063e9ed 57514: [1742186457.455][DEBUG]: DevTools HTTP Request: http://localhost:37877/json/version 57515: [22883:22898:0317/044057.468296:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57516: [22883:22898:0317/044057.468361:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57517: [22883:22895:0317/044057.494454:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57518: [22883:22883:0317/044057.519362:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57519: [22883:22883:0317/044057.521717:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57520: [22883:22898:0317/044057.521814:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") 57521: [22883:22883:0317/044057.522950:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57522: [22883:22883:0317/044057.534478:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57523: [22883:22895:0317/044057.535139:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 57525: "Browser": "Chrome/134.0.6998.88", 57526: "Protocol-Version": "1.3", 57527: "User-Agent": "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36", 57528: "V8-Version": "13.4.114.19", 57529: "WebKit-Version": "537.36 (@7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0)", 57530: "webSocketDebuggerUrl": "ws://localhost:37877/devtools/browser/9a4e207f-82e5-44f7-af9c-89172063e9ed" 57531: } 57532: [1742186457.538][DEBUG]: DevTools HTTP Request: http://localhost:37877/json/list 57533: [22883:22883:0317/044057.549133:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57534: [22883:22962:0317/044057.549339:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.Properties.Get: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files 57535: [22883:22962:0317/044057.549586:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.UPower.GetDisplayDevice: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files 57536: [22883:22962:0317/044057.549836:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.UPower.EnumerateDevices: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files 57537: [22883:22883:0317/044057.555350:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57538: [22883:22903:0317/044057.555815:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 57541: "devtoolsFrontendUrl": "/devtools/inspector.html?ws=localhost:37877/devtools/page/9B74BB3305AABEA1568047A1E0242DD1", 57542: "id": "9B74BB3305AABEA1568047A1E0242DD1", 57543: "title": "", 57544: "type": "page", 57545: "url": "data:,", 57546: "webSocketDebuggerUrl": "ws://localhost:37877/devtools/page/9B74BB3305AABEA1568047A1E0242DD1" 57547: } ] 57548: [1742186457.559][INFO]: resolved localhost to ["::1","127.0.0.1"] 57549: [22883:22883:0317/044057.567026:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: 57550: [22883:22895:0317/044057.567123:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 57554: "type": "browser" 57555: }, { 57556: "exclude": true, 57557: "type": "page" 57558: }, { 57559: "exclude": false 57560: } ] 57561: } 57562: [22883:22883:0317/044057.576937:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: ... 57570: "type": "tab", 57571: "url": "data:," 57572: } ] 57573: } 57574: [1742186457.578][DEBUG]: DevTools WebSocket Command: Target.attachToTarget (id=2) (session_id=) browser { 57575: "flatten": true, 57576: "targetId": "0F376F427D5EEA43A62FA13B48280150" 57577: } 57578: [22883:22895:0317/044057.578210:ERROR:bus.cc(408)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix") ... 57592: [1742186457.587][DEBUG]: DevTools WebSocket Response: Target.attachToTarget (id=2) (session_id=) browser { 57593: "sessionId": "72D45CCCF5A394C56B81603168D7AABD" 57594: } 57595: [1742186457.587][DEBUG]: DevTools WebSocket Command: Target.setAutoAttach (id=3) (session_id=72D45CCCF5A394C56B81603168D7AABD) 0F376F427D5EEA43A62FA13B48280150 { 57596: "autoAttach": true, 57597: "flatten": true, 57598: "waitForDebuggerOnStart": false 57599: } 57600: [22883:22883:0317/044057.586774:ERROR:object_proxy.cc(576)] Failed to call method: org.freedesktop.DBus.NameHasOwner: object_path= /org/freedesktop/DBus: unknown error type: ... 57739: 2025-03-17 04:40:57 DEBUG Selenium [:header] <<< {"content-length"=>["46"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 57740: 2025-03-17 04:40:57 DEBUG Selenium [:command] <- {"value":["9B74BB3305AABEA1568047A1E0242DD1"]} 57741: 2025-03-17 04:40:57 DEBUG Selenium [:command] -> GET session/599f6e589fc0e2a32da22ca03e966c7f/window 57742: [1742186457.665][INFO]: [599f6e589fc0e2a32da22ca03e966c7f] COMMAND GetWindow { 57743: } 57744: [1742186457.665][INFO]: [599f6e589fc0e2a32da22ca03e966c7f] RESPONSE GetWindow "9B74BB3305AABEA1568047A1E0242DD1" 57745: 2025-03-17 04:40:57 DEBUG Selenium [:header] <<< {"content-length"=>["44"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]} 57746: 2025-03-17 04:40:57 DEBUG Selenium [:command] <- {"value":"9B74BB3305AABEA1568047A1E0242DD1"} 57747: raises an UnexpectedAlertOpenError if an alert has not been dealt with ... 57759: 2025-03-17 04:40:57 DEBUG Selenium [:process] Checking if 22877 is exited: 57760: 2025-03-17 04:40:57 DEBUG Selenium [:process] Checking if 22877 is exited: 57761: 2025-03-17 04:40:57 DEBUG Selenium [:process] -> exit code is 0 57762: 2025-03-17 04:40:57 DEBUG Selenium [:process] Checking if 22877 is exited: 57763: 2025-03-17 04:40:57 DEBUG Selenium [:process] -> exit code is 0 57764: 2025-03-17 04:40:57 DEBUG Selenium [:process] Checking if 22877 is exited: 57765: 2025-03-17 04:40:57 DEBUG Selenium [:process] -> exit code is 0 57766: Finished in 8.99 seconds (files took 1.01 seconds to load) 57767: 26 examples, 0 failures ... 57842: �[32m[1,042 / 1,197]�[0m Building Java resource jar; 0s processwrapper-sandbox ... (2 actions, 1 running) 57843: �[32m[1,044 / 1,197]�[0m MergeJars java/src/org/openqa/selenium/manager/manager-project.jar; 0s processwrapper-sandbox ... (2 actions, 1 running) 57844: �[32m[1,059 / 1,197]�[0m MergeJars java/src/org/openqa/selenium/manager/manager-project.jar; 1s processwrapper-sandbox ... (4 actions, 3 running) 57845: �[32m[1,069 / 1,197]�[0m MergeJars java/src/org/openqa/selenium/manager/manager-project.jar; 2s processwrapper-sandbox ... (4 actions, 3 running) 57846: �[32m[1,075 / 1,197]�[0m MergeJars java/src/org/openqa/selenium/manager/manager-project.jar; 3s processwrapper-sandbox ... (2 actions, 1 running) 57847: �[32m[1,078 / 1,197]�[0m Action java/src/org/openqa/selenium/manager/libmanager-module.jar; 0s processwrapper-sandbox 57848: �[32m[1,079 / 1,197]�[0m [Prepa] Extracting interface for jar bazel-out/k8-fastbuild/bin/java/src/org/openqa/selenium/manager/libmanager-module.jar 57849: �[32mINFO: �[0mFrom Building java/src/org/openqa/selenium/remote/libapi-class.jar (70 source files): 57850: java/src/org/openqa/selenium/remote/ErrorHandler.java:46: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57851: private final ErrorCodes errorCodes; 57852: ^ 57853: java/src/org/openqa/selenium/remote/ErrorHandler.java:60: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57854: this.errorCodes = new ErrorCodes(); 57855: ^ 57856: java/src/org/openqa/selenium/remote/ErrorHandler.java:68: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57857: public ErrorHandler(ErrorCodes codes, boolean includeServerErrors) { 57858: ^ 57859: java/src/org/openqa/selenium/remote/Response.java:97: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57860: ErrorCodes errorCodes = new ErrorCodes(); 57861: ^ 57862: java/src/org/openqa/selenium/remote/Response.java:97: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57863: ErrorCodes errorCodes = new ErrorCodes(); 57864: ^ 57865: java/src/org/openqa/selenium/remote/ProtocolHandshake.java:181: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57866: response.setStatus(ErrorCodes.SUCCESS); 57867: ^ 57868: java/src/org/openqa/selenium/remote/ProtocolHandshake.java:182: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57869: response.setState(ErrorCodes.SUCCESS_STRING); 57870: ^ 57871: java/src/org/openqa/selenium/remote/W3CHandshakeResponse.java:53: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57872: new ErrorCodes().toStatus((String) rawError, Optional.of(tuple.getStatusCode()))); 57873: ^ 57874: java/src/org/openqa/selenium/remote/W3CHandshakeResponse.java:56: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57875: new ErrorCodes().getExceptionType((String) rawError); 57876: ^ 57877: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:44: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57878: private final ErrorCodes errorCodes = new ErrorCodes(); 57879: ^ 57880: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:44: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57881: private final ErrorCodes errorCodes = new ErrorCodes(); 57882: ^ 57883: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:55: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57884: int status = response.getStatus() == ErrorCodes.SUCCESS ? HTTP_OK : HTTP_INTERNAL_ERROR; 57885: ^ 57886: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:101: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57887: response.setStatus(ErrorCodes.UNKNOWN_COMMAND); 57888: ^ 57889: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:103: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57890: response.setStatus(ErrorCodes.UNHANDLED_ERROR); 57891: ^ 57892: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:117: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57893: response.setStatus(ErrorCodes.SUCCESS); 57894: ^ 57895: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:118: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57896: response.setState(errorCodes.toState(ErrorCodes.SUCCESS)); 57897: ^ 57898: java/src/org/openqa/selenium/remote/codec/AbstractHttpResponseCodec.java:124: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57899: response.setState(errorCodes.toState(ErrorCodes.SUCCESS)); 57900: ^ 57901: java/src/org/openqa/selenium/remote/codec/w3c/W3CHttpResponseCodec.java:70: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57902: private final ErrorCodes errorCodes = new ErrorCodes(); 57903: ^ 57904: java/src/org/openqa/selenium/remote/codec/w3c/W3CHttpResponseCodec.java:70: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57905: private final ErrorCodes errorCodes = new ErrorCodes(); 57906: ^ 57907: java/src/org/openqa/selenium/remote/codec/w3c/W3CHttpResponseCodec.java:93: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57908: response.setStatus(ErrorCodes.UNKNOWN_COMMAND); 57909: ^ 57910: java/src/org/openqa/selenium/remote/codec/w3c/W3CHttpResponseCodec.java:98: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57911: response.setStatus(ErrorCodes.UNHANDLED_ERROR); 57912: ^ 57913: java/src/org/openqa/selenium/remote/codec/w3c/W3CHttpResponseCodec.java:145: warning: [removal] ErrorCodes in org.openqa.selenium.remote has been deprecated and marked for removal 57914: response.setStatus(ErrorCodes.SUCCESS); ... 58454: 04:42:08.546 DEBUG [HttpClientImpl$SelectorManager.run] - [HttpClient-1-SelectorManager] [624ms] HttpClientImpl(1) next expired: 1199454 58455: 04:42:08.546 DEBUG [HttpClientImpl$SelectorManager.run] - [HttpClient-1-SelectorManager] [624ms] HttpClientImpl(1) Next deadline is 3000 58456: 04:42:08.545 DEBUG [Http1Response$ClientRefCountTracker.tryRelease] - [JdkHttpClient-0-2] [624ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Operation finished: decrementing ref count for jdk.internal.net.http.HttpClientImpl@4d2a767d(1) 58457: 04:42:08.546 DEBUG [SocketTube$InternalWriteSubscriber$WriteSubscription.requestMore] - [JdkHttpClient-0-1] [625ms] SocketTube(1) write: requesting more... 58458: 04:42:08.546 DEBUG [JdkHttpClient.execute0] - Ending request (POST) /session in 415ms 58459: 04:42:08.546 DEBUG [SocketTube.debugState] - [JdkHttpClient-0-1] [625ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=1, stopped=false], Writing: [ops=0, demand=1] 58460: 04:42:08.546 DEBUG [Http1AsyncReceiver.flush] - [JdkHttpClient-0-2] [625ms] Http1AsyncReceiver(SocketTube(1)) Delegate done: 0 58461: 04:42:08.547 DEBUG [Http1AsyncReceiver.flush] - [JdkHttpClient-0-2] [625ms] Http1AsyncReceiver(SocketTube(1)) Got 0 bytes for delegate null 58462: 04:42:08.547 DEBUG [SdkSpan.setStatus] - Ignoring setStatus() description since status is not ERROR. ... 58477: 04:42:08.573 DEBUG [SeleniumSpanExporter$1.lambda$export$4] - SpanData{spanContext=ImmutableSpanContext{traceId=0a6d3160e16a88baa83468f9a2b2856e, spanId=ceb1977f506f9030, traceFlags=01, traceState=ArrayBasedTraceState{entries=[]}, remote=false, valid=true}, parentSpanContext=ImmutableSpanContext{traceId=0a6d3160e16a88baa83468f9a2b2856e, spanId=d6c8f9a255bc83b2, traceFlags=01, traceState=ArrayBasedTraceState{entries=[]}, remote=false, valid=true}, resource=Resource{schemaUrl=null, attributes={service.name="unknown_service:java", telemetry.sdk.language="java", telemetry.sdk.name="opentelemetry", telemetry.sdk.version="1.48.0"}}, instrumentationScopeInfo=InstrumentationScopeInfo{name=default, version=null, schemaUrl=null, attributes={}}, name=distributor.new_session, kind=INTERNAL, startEpochNanos=1742186527886972811, endEpochNanos=1742186528573599377, attributes=AttributesMap{data={session.id=38333b82d49011570d4fc1b38049fd70, session.capabilities={"acceptInsecureCerts": false,"browserName": "chrome","browserVersion": "134.0.6998.88","chrome": {"chromedriverVersion": "134.0.6998.88 (7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0-refs\u002fbranch-heads\u002f6998@{#1898})","userDataDir": "\u002ftmp\u002f.org.chromium.Chromium.dvm17e"},"fedcm:accounts": true,"goog:chromeOptions": {"debuggerAddress": "localhost:41301"},"networkConnectionEnabled": false,"pageLoadStrategy": "normal","platformName": "linux","proxy": {},"se:bidiEnabled": false,"se:cdp": "ws:\u002f\u002f10.1.0.158:50131\u002fsession\u002f38333b82d49011570d4fc1b38049fd70\u002fse\u002fcdp","se:cdpVersion": "134.0.6998.88","setWindowRect": true,"strictFileInteractability": false,"timeouts": {"implicit": 0,"pageLoad": 300000,"script": 30000},"unhandledPromptBehavior": "dismiss and notify","webauthn:extension:credBlob": true,"webauthn:extension:largeBlob": true,"webauthn:extension:minPinLength": true,"webauthn:extension:prf": true,"webauthn:virtualAuthenticators": true} 58478: , session.uri=http://10.1.0.158:50131}, capacity=128, totalAddedValues=3}, totalAttributeCount=3, events=[ImmutableEventData{name=Session request received by the Distributor, attributes={logger="org.openqa.selenium.grid.distributor.local.LocalDistributor", request.payload="[Capabilities {browserName: chrome, goog:chromeOptions: {args: [--no-sandbox, --disable-gpu], binary: external/+pin_browsers_exte...}}]"}, epochNanos=1742186527887149951, totalAttributeCount=2}, ImmutableEventData{name=Session created by the Distributor, attributes={logger="org.openqa.selenium.grid.distributor.local.LocalDistributor", request.payload="[Capabilities {browserName: chrome, goog:chromeOptions: {args: [--no-sandbox, --disable-gpu], binary: external/+pin_browsers_exte...}}]", session.capabilities="{"acceptInsecureCerts": false,"browserName": "chrome","browserVersion": "134.0.6998.88","chrome": {"chromedriverVersion": "134.0.6998.88 (7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0-refs\u002fbranch-heads\u002f6998@{#1898})","userDataDir": "\u002ftmp\u002f.org.chromium.Chromium.dvm17e"},"fedcm:accounts": true,"goog:chromeOptions": {"debuggerAddress": "localhost:41301"},"networkConnectionEnabled": false,"pageLoadStrategy": "normal","platformName": "linux","proxy": {},"se:bidiEnabled": false,"se:cdp": "ws:\u002f\u002f10.1.0.158:50131\u002fsession\u002f38333b82d49011570d4fc1b38049fd70\u002fse\u002fcdp","se:cdpVersion": "134.0.6998.88","setWindowRect": true,"strictFileInteractability": false,"timeouts": {"implicit": 0,"pageLoad": 300000,"script": 30000},"unhandledPromptBehavior": "dismiss and notify","webauthn:extension:credBlob": true,"webauthn:extension:largeBlob": true,"webauthn:extension:minPinLength": true,"webauthn:extension:prf": true,"webauthn:virtualAuthenticators": true} 58479: ", session.id="38333b82d49011570d4fc1b38049fd70", session.uri="http://10.1.0.158:50131"}, epochNanos=1742186528573113973, totalAttributeCount=5}], totalRecordedEvents=2, links=[], totalRecordedLinks=0, status=ImmutableStatusData{statusCode=UNSET, description=}, hasEnded=true} 58480: 04:42:08.574 DEBUG [SeleniumSpanExporter$1.lambda$export$3] - {"traceId": "0a6d3160e16a88baa83468f9a2b2856e","eventTime": 1742186527887149951,"eventName": "Session request received by the Distributor","attributes": {"logger": "org.openqa.selenium.grid.distributor.local.LocalDistributor","request.payload": "[Capabilities {browserName: chrome, goog:chromeOptions: {args: [--no-sandbox, --disable-gpu], binary: external\u002f+pin_browsers_exte...}}]"}} 58481: 04:42:08.574 DEBUG [SeleniumSpanExporter$1.lambda$export$3] - {"traceId": "0a6d3160e16a88baa83468f9a2b2856e","eventTime": 1742186528573113973,"eventName": "Session created by the Distributor","attributes": {"logger": "org.openqa.selenium.grid.distributor.local.LocalDistributor","request.payload": "[Capabilities {browserName: chrome, goog:chromeOptions: {args: [--no-sandbox, --disable-gpu], binary: external\u002f+pin_browsers_exte...}}]","session.capabilities": "{\"acceptInsecureCerts\": false,\"browserName\": \"chrome\",\"browserVersion\": \"134.0.6998.88\",\"chrome\": {\"chromedriverVersion\": \"134.0.6998.88 (7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0-refs\\u002fbranch-heads\\u002f6998@{#1898})\",\"userDataDir\": \"\\u002ftmp\\u002f.org.chromium.Chromium.dvm17e\"},\"fedcm:accounts\": true,\"goog:chromeOptions\": {\"debuggerAddress\": \"localhost:41301\"},\"networkConnectionEnabled\": false,\"pageLoadStrategy\": \"normal\",\"platformName\": \"linux\",\"proxy\": {},\"se:bidiEnabled\": false,\"se:cdp\": \"ws:\\u002f\\u002f10.1.0.158:50131\\u002fsession\\u002f38333b82d49011570d4fc1b38049fd70\\u002fse\\u002fcdp\",\"se:cdpVersion\": \"134.0.6998.88\",\"setWindowRect\": true,\"strictFileInteractability\": false,\"timeouts\": {\"implicit\": 0,\"pageLoad\": 300000,\"script\": 30000},\"unhandledPromptBehavior\": \"dismiss and notify\",\"webauthn:extension:credBlob\": true,\"webauthn:extension:largeBlob\": true,\"webauthn:extension:minPinLength\": true,\"webauthn:extension:prf\": true,\"webauthn:virtualAuthenticators\": true}\n","session.id": "38333b82d49011570d4fc1b38049fd70","session.uri": "http:\u002f\u002f10.1.0.158:50131"}} 58482: 04:42:08.575 DEBUG [SeleniumSpanExporter$1.lambda$export$4] - SpanData{spanContext=ImmutableSpanContext{traceId=0a6d3160e16a88baa83468f9a2b2856e, spanId=8b202f4cded0191f, traceFlags=01, traceState=ArrayBasedTraceState{entries=[]}, remote=false, valid=true}, parentSpanContext=ImmutableSpanContext{traceId=0a6d3160e16a88baa83468f9a2b2856e, spanId=f1600f7d6625d895, traceFlags=01, traceState=ArrayBasedTraceState{entries=[]}, remote=true, valid=true}, resource=Resource{schemaUrl=null, attributes={service.name="unknown_service:java", telemetry.sdk.language="java", telemetry.sdk.name="opentelemetry", telemetry.sdk.version="1.48.0"...

[titusfortner]

I fixed the things that need to get fixed for next release, I'll do a clean PR with the logging and debugging code later