Skip to content

Instantly share code, notes, and snippets.

@titusfortner

titusfortner/geckodriver_0_32_0.log Secret

Last active February 21, 2023 17:26
Show Gist options
  • Save titusfortner/440a1fc0d461ae12c611a09fb3b91637 to your computer and use it in GitHub Desktop.
Save titusfortner/440a1fc0d461ae12c611a09fb3b91637 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
geckodriver_0_32_0.log
/usr/local/bin/bash -c "env RBENV_VERSION=3.2.0 /usr/local/Cellar/rbenv/1.2.0/libexec/rbenv exec ruby -x /Users/titusfortner/code/selenium/build/third_party/rb/vendor/bundle/ruby/3.2.0/bin/bundle exec ruby /Users/titusfortner/code/selenium/build/third_party/rb/vendor/bundle/ruby/3.2.0/bin/rspec /Users/titusfortner/code/selenium/rb/spec/integration/selenium/webdriver/devtools_spec.rb --require teamcity/spec/runner/formatter/teamcity/formatter --format 'Spec::Runner::Formatter::TeamcityFormatter' --example 'DevTools supports events'"
Testing started at 9:25 AM ...
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
Run options: include {:full_description=>/DevTools\ supports\ events/}
Running Ruby specs:
2023-02-21 09:25:24 DEBUG Selenium Executing Process ["/Users/titusfortner/.webdrivers/geckodriver", "--port=4445"]
2023-02-21 09:25:24 DEBUG Selenium Starting process: ["/Users/titusfortner/.webdrivers/geckodriver", "--port=4445"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-02-21 09:25:24 DEBUG Selenium -> pid: 64797
2023-02-21 09:25:24 DEBUG Selenium polling for socket on ["127.0.0.1", 4445]
2023-02-21 09:25:24 DEBUG Selenium polling for socket on ["127.0.0.1", 4445]
2023-02-21 09:25:24 DEBUG Selenium polling for socket on ["127.0.0.1", 4445]
1676993124694 geckodriver INFO Listening on 127.0.0.1:4445
2023-02-21 09:25:25 INFO Selenium -> POST session
2023-02-21 09:25:25 INFO Selenium >>> http://127.0.0.1:4445/session | {"capabilities":{"alwaysMatch":{"acceptInsecureCerts":true,"browserName":"firefox","moz:firefoxOptions":{},"moz:debuggerAddress":true}}}
2023-02-21 09:25:25 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.1 (ruby macosx)", "Content-Length"=>"136"}
1676993125026 mozrunner::runner INFO Running command: "/Applications/Firefox.app/Contents/MacOS/firefox-bin" "--marionette" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-foreground" "-no-remote" "-profile" "/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileJ1Lskh"
console.warn: services.settings: Ignoring preference override of remote settings server
console.warn: services.settings: Allow by setting MOZ_REMOTE_SETTINGS_DEVTOOLS=1 in the environment
1676993125499 Marionette INFO Marionette enabled
1676993125583 Marionette INFO Listening on port 61477
console.error: "Warning: unrecognized command line flag -remote-allow-hosts\n"
Read port: 61477
WebDriver BiDi listening on ws://127.0.0.1:9222
1676993126360 RemoteAgent WARN TLS certificate errors will be ignored for this session
console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileJ1Lskh/search.json.mozlz4", (void 0)))
DevTools listening on ws://127.0.0.1:9222/devtools/browser/549e6820-9984-460b-b698-a6f0eb121587
2023-02-21 09:25:27 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["795"], "date"=>["Tue, 21 Feb 2023 15:25:24 GMT"]}
2023-02-21 09:25:27 INFO Selenium <- {"value":{"sessionId":"405bbe01-8688-4020-bd08-e0f5cec2eeba","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"110.0","moz:accessibilityChecks":false,"moz:buildID":"20230214051806","moz:debuggerAddress":"127.0.0.1:9222","moz:geckodriverVersion":"0.32.0","moz:headless":false,"moz:platformVersion":"20.6.0","moz:processID":64798,"moz:profile":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileJ1Lskh","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"mac","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
browser: firefox
driver: firefox
version: 110.0
platform: macosx
ci:
ruby: ruby 3.2.0 (2022-12-25 revision a528908271) [x86_64-darwin20]
1676993128118 RemoteAgent INFO Perform WebSocket upgrade for incoming connection from 127.0.0.1:61494
2023-02-21 09:25:28 DEBUG Selenium WebSocket -> {:method=>"Target.getTargets", :params=>{}, :id=>1}
1676993128122 CDP ERROR Invalid browser preferences for CDP. Set "fission.webContentIsolationStrategy"to 0 and "fission.bfcacheInParent" to false before Firefox starts.
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"id"=>1, "result"=>{"targetInfos"=>[{"targetId"=>"63243d30-62a4-440a-b0f7-83f9e3557e3c", "type"=>"page", "title"=>"", "url"=>"about:blank", "attached"=>false, "browserContextId"=>nil}]}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket -> {:method=>"Target.attachToTarget", :params=>{:targetId=>"63243d30-62a4-440a-b0f7-83f9e3557e3c", :flatten=>true}, :id=>2}
1676993128238 CDP ERROR Invalid browser preferences for CDP. Set "fission.webContentIsolationStrategy"to 0 and "fission.bfcacheInParent" to false before Firefox starts.
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.attachedToTarget", "params"=>{"targetInfo"=>{"targetId"=>"63243d30-62a4-440a-b0f7-83f9e3557e3c", "type"=>"page", "title"=>"", "url"=>"about:blank", "attached"=>false, "browserContextId"=>nil}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "waitingForDebugger"=>false}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"id"=>2, "result"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket -> {:method=>"Page.enable", :params=>{}, :sessionId=>"07595c55-5534-4836-aab0-b12471fe5ea4", :id=>3}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"id"=>3, "result"=>{}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "message"=>"{\"id\":3,\"result\":{}}"}}
2023-02-21 09:25:28 DEBUG Selenium polling for socket on ["localhost", 61505]
2023-02-21 09:25:28 INFO Selenium -> POST session/405bbe01-8688-4020-bd08-e0f5cec2eeba/url
2023-02-21 09:25:28 INFO Selenium >>> http://127.0.0.1:4445/session/405bbe01-8688-4020-bd08-e0f5cec2eeba/url | {"url":"http://localhost:61505/xhtmlTest.html"}
2023-02-21 09:25:28 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.1 (ruby macosx)", "Content-Length"=>"47"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Page.frameStartedLoading", "params"=>{"frameId"=>"9"}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "message"=>"{\"method\":\"Page.frameStartedLoading\",\"params\":{\"frameId\":\"9\"}}"}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Page.frameNavigated", "params"=>{"frame"=>{"id"=>"9", "loaderId"=>"139152645423206", "url"=>"http://localhost:61505/xhtmlTest.html", "securityOrigin"=>nil, "mimeType"=>nil}}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "message"=>"{\"method\":\"Page.frameNavigated\",\"params\":{\"frame\":{\"id\":\"9\",\"loaderId\":\"139152645423206\",\"url\":\"http://localhost:61505/xhtmlTest.html\",\"securityOrigin\":null,\"mimeType\":null}}}"}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Page.domContentEventFired", "params"=>{"timestamp"=>1676993128.755}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "message"=>"{\"method\":\"Page.domContentEventFired\",\"params\":{\"timestamp\":1676993128.755}}"}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Page.loadEventFired", "params"=>{"timestamp"=>1676993128.755}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "message"=>"{\"method\":\"Page.loadEventFired\",\"params\":{\"timestamp\":1676993128.755}}"}}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Page.frameStoppedLoading", "params"=>{"frameId"=>"9"}, "sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4"}
2023-02-21 09:25:28 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"07595c55-5534-4836-aab0-b12471fe5ea4", "message"=>"{\"method\":\"Page.frameStoppedLoading\",\"params\":{\"frameId\":\"9\"}}"}}
2023-02-21 09:25:28 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Tue, 21 Feb 2023 15:25:27 GMT"]}
2023-02-21 09:25:28 INFO Selenium <- {"value":null}
2023-02-21 09:25:30 INFO Selenium -> DELETE session/405bbe01-8688-4020-bd08-e0f5cec2eeba
1676993130776 Marionette INFO Stopped listening on port 61477
JavaScript error: chrome://remote/content/cdp/sessions/TabSession.sys.mjs, line 74: TypeError: this.mm is null
2023-02-21 09:25:31 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Tue, 21 Feb 2023 15:25:30 GMT"]}
2023-02-21 09:25:31 INFO Selenium <- {"value":null}
2023-02-21 09:25:31 DEBUG Selenium Checking if 64797 is exited:
2023-02-21 09:25:31 DEBUG Selenium Checking if 64797 is exited:
2023-02-21 09:25:31 DEBUG Selenium Sending TERM to process: 64797
2023-02-21 09:25:31 DEBUG Selenium Polling 20 seconds for exit of 64797
2023-02-21 09:25:31 DEBUG Selenium Checking if 64797 is exited:
2023-02-21 09:25:31 DEBUG Selenium Checking if 64797 is exited:
2023-02-21 09:25:31 DEBUG Selenium -> exit code is 15
2023-02-21 09:25:31 DEBUG Selenium Checking if 64797 is exited:
2023-02-21 09:25:31 DEBUG Selenium -> exit code is 15
2023-02-21 09:25:31 DEBUG Selenium -> stopped 64797
2023-02-21 09:25:31 DEBUG Selenium Executing Process ["/Users/titusfortner/.webdrivers/geckodriver", "--port=4445"]
2023-02-21 09:25:31 DEBUG Selenium Starting process: ["/Users/titusfortner/.webdrivers/geckodriver", "--port=4445"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-02-21 09:25:31 DEBUG Selenium -> pid: 64804
2023-02-21 09:25:31 DEBUG Selenium polling for socket on ["127.0.0.1", 4445]
1676993131440 geckodriver INFO Listening on 127.0.0.1:4445
2023-02-21 09:25:31 INFO Selenium -> POST session
2023-02-21 09:25:31 INFO Selenium >>> http://127.0.0.1:4445/session | {"capabilities":{"alwaysMatch":{"acceptInsecureCerts":true,"browserName":"firefox","moz:firefoxOptions":{},"moz:debuggerAddress":true}}}
2023-02-21 09:25:31 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.1 (ruby macosx)", "Content-Length"=>"136"}
1676993131695 mozrunner::runner INFO Running command: "/Applications/Firefox.app/Contents/MacOS/firefox-bin" "--marionette" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-foreground" "-no-remote" "-profile" "/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofilenLPC0n"
console.warn: services.settings: Ignoring preference override of remote settings server
console.warn: services.settings: Allow by setting MOZ_REMOTE_SETTINGS_DEVTOOLS=1 in the environment
1676993132176 Marionette INFO Marionette enabled
1676993132261 Marionette INFO Listening on port 61523
console.error: "Warning: unrecognized command line flag -remote-allow-hosts\n"
Read port: 61523
WebDriver BiDi listening on ws://127.0.0.1:9222
1676993132747 RemoteAgent WARN TLS certificate errors will be ignored for this session
console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofilenLPC0n/search.json.mozlz4", (void 0)))
DevTools listening on ws://127.0.0.1:9222/devtools/browser/2503610b-266e-43d8-b5a8-0240ba676807
2023-02-21 09:25:34 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["795"], "date"=>["Tue, 21 Feb 2023 15:25:31 GMT"]}
2023-02-21 09:25:34 INFO Selenium <- {"value":{"sessionId":"13986003-72c0-4f11-91cd-586ae7ab30ba","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"110.0","moz:accessibilityChecks":false,"moz:buildID":"20230214051806","moz:debuggerAddress":"127.0.0.1:9222","moz:geckodriverVersion":"0.32.0","moz:headless":false,"moz:platformVersion":"20.6.0","moz:processID":64805,"moz:profile":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofilenLPC0n","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"mac","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
2023-02-21 09:25:34 INFO Selenium -> DELETE session/13986003-72c0-4f11-91cd-586ae7ab30ba
1676993134296 Marionette INFO Stopped listening on port 61523
console.warn: services.settings: main/query-stripping sync interrupted by shutdown
console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
WARNING: A blocker encountered an error while we were waiting.
Blocker: Waiting for ping task
Phase: TelemetryController: Waiting for pending ping activity
State: (none)
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: A blocker encountered an error while we were waiting.
Blocker: Waiting for ping task
Phase: TelemetryController: Waiting for pending ping activity
State: (none)
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: A blocker encountered an error while we were waiting.
Blocker: Waiting for ping task
Phase: TelemetryController: Waiting for pending ping activity
State: (none)
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: A blocker encountered an error while we were waiting.
Blocker: TelemetryController: shutting down
Phase: profile-before-change-telemetry
State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
2023-02-21 09:25:35 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Tue, 21 Feb 2023 15:25:34 GMT"]}
2023-02-21 09:25:35 INFO Selenium <- {"value":null}
2023-02-21 09:25:35 DEBUG Selenium Checking if 64804 is exited:
2023-02-21 09:25:35 DEBUG Selenium Checking if 64804 is exited:
2023-02-21 09:25:35 DEBUG Selenium Sending TERM to process: 64804
2023-02-21 09:25:35 DEBUG Selenium Polling 20 seconds for exit of 64804
2023-02-21 09:25:35 DEBUG Selenium Checking if 64804 is exited:
2023-02-21 09:25:35 DEBUG Selenium Checking if 64804 is exited:
2023-02-21 09:25:35 DEBUG Selenium -> exit code is 15
2023-02-21 09:25:35 DEBUG Selenium Checking if 64804 is exited:
2023-02-21 09:25:35 DEBUG Selenium -> exit code is 15
2023-02-21 09:25:35 DEBUG Selenium -> stopped 64804
1 example, 0 failures, 1 passed
Finished in 11.370108 seconds
2023-02-21 09:25:35 DEBUG Selenium Checking if 64804 is exited:
2023-02-21 09:25:35 DEBUG Selenium -> exit code is 15
2023-02-21 09:25:35 DEBUG Selenium Checking if 64797 is exited:
2023-02-21 09:25:35 DEBUG Selenium -> exit code is 15
Process finished with exit code 0
Raw
geckodriver_0_32_2.log
/usr/local/bin/bash -c "env RBENV_VERSION=3.2.0 /usr/local/Cellar/rbenv/1.2.0/libexec/rbenv exec ruby -x /Users/titusfortner/code/selenium/build/third_party/rb/vendor/bundle/ruby/3.2.0/bin/bundle exec ruby /Users/titusfortner/code/selenium/build/third_party/rb/vendor/bundle/ruby/3.2.0/bin/rspec /Users/titusfortner/code/selenium/rb/spec/integration/selenium/webdriver/devtools_spec.rb --require teamcity/spec/runner/formatter/teamcity/formatter --format 'Spec::Runner::Formatter::TeamcityFormatter' --example 'DevTools supports events'"
Testing started at 9:23 AM ...
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
Calling `DidYouMean::SPELL_CHECKERS.merge!(error_name => spell_checker)' has been deprecated. Please call `DidYouMean.correct_error(error_name, spell_checker)' instead.
Run options: include {:full_description=>/DevTools\ supports\ events/}
Running Ruby specs:
2023-02-21 09:23:05 DEBUG Selenium Selenium Manager found at /Users/titusfortner/code/selenium/rb/bin/macos/selenium-manager
2023-02-21 09:23:05 DEBUG Selenium Executing Process /Users/titusfortner/code/selenium/rb/bin/macos/selenium-manager --driver geckodriver
2023-02-21 09:23:05 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver
2023-02-21 09:23:05 DEBUG Selenium Executing Process /Users/titusfortner/code/selenium/rb/bin/macos/selenium-manager --driver geckodriver
2023-02-21 09:23:05 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver
2023-02-21 09:23:05 DEBUG Selenium Executing Process ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver", "--port=4445"]
2023-02-21 09:23:05 DEBUG Selenium Starting process: ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver", "--port=4445"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-02-21 09:23:05 DEBUG Selenium -> pid: 64422
2023-02-21 09:23:05 DEBUG Selenium polling for socket on ["127.0.0.1", 4445]
1676992985804 geckodriver INFO Listening on 127.0.0.1:4445
2023-02-21 09:23:06 INFO Selenium -> POST session
2023-02-21 09:23:06 INFO Selenium >>> http://127.0.0.1:4445/session | {"capabilities":{"alwaysMatch":{"acceptInsecureCerts":true,"browserName":"firefox","moz:firefoxOptions":{},"moz:debuggerAddress":true}}}
2023-02-21 09:23:06 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.1 (ruby macosx)", "Content-Length"=>"136"}
1676992986186 mozrunner::runner INFO Running command: "/Applications/Firefox.app/Contents/MacOS/firefox-bin" "--marionette" "--remote-debugging-port" "9222" "--re ... s" "localhost" "-foreground" "-no-remote" "-profile" "/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileIdcPyX"
console.warn: services.settings: Ignoring preference override of remote settings server
console.warn: services.settings: Allow by setting MOZ_REMOTE_SETTINGS_DEVTOOLS=1 in the environment
1676992986855 Marionette INFO Marionette enabled
1676992986962 Marionette INFO Listening on port 61371
console.error: "Warning: unrecognized command line flag -remote-allow-hosts\n"
Read port: 61371
WebDriver BiDi listening on ws://127.0.0.1:9222
1676992987636 RemoteAgent WARN TLS certificate errors will be ignored for this session
console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileIdcPyX/search.json.mozlz4", (void 0)))
DevTools listening on ws://127.0.0.1:9222/devtools/browser/a3172884-c111-494b-a804-b46aba121fad
2023-02-21 09:23:09 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["795"], "date"=>["Tue, 21 Feb 2023 15:23:06 GMT"]}
2023-02-21 09:23:09 INFO Selenium <- {"value":{"sessionId":"405b8e4c-0216-452b-83e4-cabe833ebb3d","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"110.0","moz:accessibilityChecks":false,"moz:buildID":"20230214051806","moz:debuggerAddress":"127.0.0.1:9222","moz:geckodriverVersion":"0.32.2","moz:headless":false,"moz:platformVersion":"20.6.0","moz:processID":64423,"moz:profile":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileIdcPyX","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"mac","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
browser: firefox
driver: firefox
version: 110.0
platform: macosx
ci:
ruby: ruby 3.2.0 (2022-12-25 revision a528908271) [x86_64-darwin20]
1676992989377 RemoteAgent INFO Perform WebSocket upgrade for incoming connection from 127.0.0.1:61389
2023-02-21 09:23:09 DEBUG Selenium WebSocket -> {:method=>"Target.getTargets", :params=>{}, :id=>1}
1676992989379 CDP ERROR Invalid browser preferences for CDP. Set "fission.webContentIsolationStrategy"to 0 and "fission.bfcacheInParent" to false before Firefox starts.
2023-02-21 09:23:09 DEBUG Selenium WebSocket <- {"id"=>1, "result"=>{"targetInfos"=>[{"targetId"=>"786487e1-34de-4396-b9f4-d74776b29cb8", "type"=>"page", "title"=>"", "url"=>"about:blank", "attached"=>false, "browserContextId"=>nil}]}}
2023-02-21 09:23:09 DEBUG Selenium WebSocket -> {:method=>"Target.attachToTarget", :params=>{:targetId=>"786487e1-34de-4396-b9f4-d74776b29cb8", :flatten=>true}, :id=>2}
1676992989489 CDP ERROR Invalid browser preferences for CDP. Set "fission.webContentIsolationStrategy"to 0 and "fission.bfcacheInParent" to false before Firefox starts.
2023-02-21 09:23:09 DEBUG Selenium WebSocket <- {"method"=>"Target.attachedToTarget", "params"=>{"targetInfo"=>{"targetId"=>"786487e1-34de-4396-b9f4-d74776b29cb8", "type"=>"page", "title"=>"", "url"=>"about:blank", "attached"=>false, "browserContextId"=>nil}, "sessionId"=>"e2d9c942-1b46-4add-81e5-e6dc9ccf7881", "waitingForDebugger"=>false}}
2023-02-21 09:23:09 DEBUG Selenium WebSocket <- {"id"=>2, "result"=>{"sessionId"=>"e2d9c942-1b46-4add-81e5-e6dc9ccf7881"}}
2023-02-21 09:23:09 DEBUG Selenium WebSocket -> {:method=>"Page.enable", :params=>{}, :sessionId=>"e2d9c942-1b46-4add-81e5-e6dc9ccf7881", :id=>3}
2023-02-21 09:23:09 DEBUG Selenium WebSocket <- {"id"=>3, "result"=>{}, "sessionId"=>"e2d9c942-1b46-4add-81e5-e6dc9ccf7881"}
2023-02-21 09:23:09 DEBUG Selenium WebSocket <- {"method"=>"Target.receivedMessageFromTarget", "params"=>{"sessionId"=>"e2d9c942-1b46-4add-81e5-e6dc9ccf7881", "message"=>"{\"id\":3,\"result\":{}}"}}
2023-02-21 09:23:09 DEBUG Selenium polling for socket on ["localhost", 61401]
2023-02-21 09:23:09 INFO Selenium -> POST session/405b8e4c-0216-452b-83e4-cabe833ebb3d/url
2023-02-21 09:23:09 INFO Selenium >>> http://127.0.0.1:4445/session/405b8e4c-0216-452b-83e4-cabe833ebb3d/url | {"url":"http://localhost:61401/xhtmlTest.html"}
2023-02-21 09:23:09 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.1 (ruby macosx)", "Content-Length"=>"47"}
2023-02-21 09:23:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Tue, 21 Feb 2023 15:23:09 GMT"]}
2023-02-21 09:23:10 INFO Selenium <- {"value":null}
2023-02-21 09:23:12 INFO Selenium -> DELETE session/405b8e4c-0216-452b-83e4-cabe833ebb3d
1676992992080 Marionette INFO Stopped listening on port 61371
JavaScript error: chrome://remote/content/cdp/sessions/TabSession.sys.mjs, line 74: TypeError: this.mm is null
2023-02-21 09:23:12 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Tue, 21 Feb 2023 15:23:12 GMT"]}
2023-02-21 09:23:12 INFO Selenium <- {"value":null}
2023-02-21 09:23:12 DEBUG Selenium Checking if 64422 is exited:
2023-02-21 09:23:12 DEBUG Selenium Checking if 64422 is exited:
2023-02-21 09:23:12 DEBUG Selenium Sending TERM to process: 64422
2023-02-21 09:23:12 DEBUG Selenium Polling 20 seconds for exit of 64422
2023-02-21 09:23:12 DEBUG Selenium Checking if 64422 is exited:
2023-02-21 09:23:12 DEBUG Selenium Checking if 64422 is exited:
2023-02-21 09:23:12 DEBUG Selenium -> exit code is 15
2023-02-21 09:23:12 DEBUG Selenium Checking if 64422 is exited:
2023-02-21 09:23:12 DEBUG Selenium -> exit code is 15
2023-02-21 09:23:12 DEBUG Selenium -> stopped 64422
2023-02-21 09:23:12 DEBUG Selenium Executing Process /Users/titusfortner/code/selenium/rb/bin/macos/selenium-manager --driver geckodriver
2023-02-21 09:23:12 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver
2023-02-21 09:23:12 DEBUG Selenium Executing Process /Users/titusfortner/code/selenium/rb/bin/macos/selenium-manager --driver geckodriver
2023-02-21 09:23:13 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver
2023-02-21 09:23:13 DEBUG Selenium Executing Process ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver", "--port=4445"]
2023-02-21 09:23:13 DEBUG Selenium Starting process: ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.32.2/geckodriver", "--port=4445"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-02-21 09:23:13 DEBUG Selenium -> pid: 64476
2023-02-21 09:23:13 DEBUG Selenium polling for socket on ["127.0.0.1", 4445]
1676992993034 geckodriver INFO Listening on 127.0.0.1:4445
2023-02-21 09:23:13 INFO Selenium -> POST session
2023-02-21 09:23:13 INFO Selenium >>> http://127.0.0.1:4445/session | {"capabilities":{"alwaysMatch":{"acceptInsecureCerts":true,"browserName":"firefox","moz:firefoxOptions":{},"moz:debuggerAddress":true}}}
2023-02-21 09:23:13 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.1 (ruby macosx)", "Content-Length"=>"136"}
1676992993285 mozrunner::runner INFO Running command: "/Applications/Firefox.app/Contents/MacOS/firefox-bin" "--marionette" "--remote-debugging-port" "9222" "--re ... s" "localhost" "-foreground" "-no-remote" "-profile" "/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileTviZE8"
console.warn: services.settings: Ignoring preference override of remote settings server
console.warn: services.settings: Allow by setting MOZ_REMOTE_SETTINGS_DEVTOOLS=1 in the environment
1676992993769 Marionette INFO Marionette enabled
1676992993843 Marionette INFO Listening on port 61421
console.error: "Warning: unrecognized command line flag -remote-allow-hosts\n"
Read port: 61421
WebDriver BiDi listening on ws://127.0.0.1:9222
1676992994307 RemoteAgent WARN TLS certificate errors will be ignored for this session
console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileTviZE8/search.json.mozlz4", (void 0)))
DevTools listening on ws://127.0.0.1:9222/devtools/browser/e5b963c3-c729-4c8a-9826-9e7d974277fe
2023-02-21 09:23:16 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["795"], "date"=>["Tue, 21 Feb 2023 15:23:13 GMT"]}
2023-02-21 09:23:16 INFO Selenium <- {"value":{"sessionId":"f0af39c4-d588-4f40-ad4f-ed2fe2dddc01","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"110.0","moz:accessibilityChecks":false,"moz:buildID":"20230214051806","moz:debuggerAddress":"127.0.0.1:9222","moz:geckodriverVersion":"0.32.2","moz:headless":false,"moz:platformVersion":"20.6.0","moz:processID":64477,"moz:profile":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileTviZE8","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"mac","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
expected given block to yield control but did not yield
0) Selenium::WebDriver::DevTools supports events
Failure/Error:
expect { |block|
driver.devtools.page.enable
driver.devtools.page.on(:load_event_fired, &block)
driver.navigate.to url_for('xhtmlTest.html')
sleep 2
}.to yield_control
expected given block to yield control but did not yield
# ./spec/integration/selenium/webdriver/devtools_spec.rb:33:in `block (2 levels) in <module:WebDriver>'
2023-02-21 09:23:16 INFO Selenium -> DELETE session/f0af39c4-d588-4f40-ad4f-ed2fe2dddc01
1676992996142 Marionette INFO Stopped listening on port 61421
console.error: services.settings:
main/quicksuggest Signature failed Error: Shutdown, aborting read-only worker requests.
console.error: (new Error("Shutdown, aborting read-only worker requests.", "resource://services-settings/RemoteSettingsWorker.jsm", 143))
console.error: (new Error("Shutdown, aborting read-only worker requests.", "resource://services-settings/RemoteSettingsWorker.jsm", 143))
console.error: (new Error("Shutdown, aborting read-only worker requests.", "resource://services-settings/RemoteSettingsWorker.jsm", 143))
WARNING: A blocker encountered an error while we were waiting.
Blocker: Waiting for ping task
Phase: TelemetryController: Waiting for pending ping activity
State: (none)
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: A blocker encountered an error while we were waiting.
Blocker: Waiting for ping task
Phase: TelemetryController: Waiting for pending ping activity
State: (none)
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: A blocker encountered an error while we were waiting.
Blocker: Waiting for ping task
Phase: TelemetryController: Waiting for pending ping activity
State: (none)
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: A blocker encountered an error while we were waiting.
Blocker: TelemetryController: shutting down
Phase: profile-before-change-telemetry
State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
@resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
@resource://gre/modules/osfile.jsm:12:30
@resource://gre/modules/TelemetryStorage.sys.mjs:10:28
_checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
2023-02-21 09:23:16 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Tue, 21 Feb 2023 15:23:16 GMT"]}
2023-02-21 09:23:16 INFO Selenium <- {"value":null}
2023-02-21 09:23:16 DEBUG Selenium Checking if 64476 is exited:
2023-02-21 09:23:16 DEBUG Selenium Checking if 64476 is exited:
2023-02-21 09:23:16 DEBUG Selenium Sending TERM to process: 64476
2023-02-21 09:23:16 DEBUG Selenium Polling 20 seconds for exit of 64476
2023-02-21 09:23:16 DEBUG Selenium Checking if 64476 is exited:
2023-02-21 09:23:17 DEBUG Selenium Checking if 64476 is exited:
2023-02-21 09:23:17 DEBUG Selenium -> exit code is 15
2023-02-21 09:23:17 DEBUG Selenium Checking if 64476 is exited:
2023-02-21 09:23:17 DEBUG Selenium -> exit code is 15
2023-02-21 09:23:17 DEBUG Selenium -> stopped 64476
1 example, 1 failure, 0 passed
Finished in 11.618948 seconds
2023-02-21 09:23:17 DEBUG Selenium Checking if 64476 is exited:
2023-02-21 09:23:17 DEBUG Selenium -> exit code is 15
2023-02-21 09:23:17 DEBUG Selenium Checking if 64422 is exited:
2023-02-21 09:23:17 DEBUG Selenium -> exit code is 15
Process finished with exit code 1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment