Skip to content

Instantly share code, notes, and snippets.

@titusfortner

titusfortner/chrome_click.log Secret

Last active April 13, 2023 14:55
Show Gist options
  • Save titusfortner/0b7ce400f00488dd6cc80bb006974451 to your computer and use it in GitHub Desktop.
Save titusfortner/0b7ce400f00488dd6cc80bb006974451 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
chrome_click.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/.rbenv/versions/3.2.0/bin/bundle exec ruby /Users/titusfortner/.rbenv/versions/3.2.0/bin/rspec /Users/titusfortner/code/seleniumhq.github.io/examples/ruby/spec/waits/waits_spec.rb --require teamcity/spec/runner/formatter/teamcity/formatter --format 'Spec::Runner::Formatter::TeamcityFormatter' --example 'Waits implicit for click'"
Testing started at 9:05 AM ...
Run options: include {:full_description=>/Waits\ implicit\ for\ click/}
2023-04-13 09:06:00 WARN Selenium [:logger_info] Details on how to use and modify Selenium logger:
https://selenium.dev/documentation/webdriver/troubleshooting/logging#ruby
2023-04-13 09:06:00 WARN Selenium applicable driver not found; attempting to install with Selenium Manager
2023-04-13 09:06:00 DEBUG Selenium Selenium Manager found at /Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager
2023-04-13 09:06:00 DEBUG Selenium Executing Process ["/Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager", "--browser", "chrome", "--output", "json", "--debug"]
2023-04-13 09:06:00 DEBUG Selenium Using shell command to find out chrome version
2023-04-13 09:06:00 DEBUG Selenium Running command: "/Applications/Google\\ Chrome.app/Contents/MacOS/Google\\ Chrome --version"
2023-04-13 09:06:00 DEBUG Selenium Output: "Google Chrome 112.0.5615.49 "
2023-04-13 09:06:00 DEBUG Selenium The version of chrome is 112.0.5615.49
2023-04-13 09:06:00 DEBUG Selenium Detected browser: chrome 112
2023-04-13 09:06:00 DEBUG Selenium Required driver: chromedriver 112.0.5615.49
2023-04-13 09:06:00 DEBUG Selenium Running command: "chromedriver --version"
2023-04-13 09:06:00 DEBUG Selenium Output: ""
2023-04-13 09:06:00 DEBUG Selenium chromedriver 112.0.5615.49 already in the cache
2023-04-13 09:06:00 INFO Selenium /Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver
2023-04-13 09:06:00 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver
2023-04-13 09:06:00 DEBUG Selenium Executing Process ["/Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver", "--port=9515"]
2023-04-13 09:06:00 DEBUG Selenium Starting process: ["/Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver", "--port=9515"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-04-13 09:06:00 DEBUG Selenium -> pid: 92221
2023-04-13 09:06:00 DEBUG Selenium polling for socket on ["127.0.0.1", 9515]
Starting ChromeDriver 112.0.5615.49 (bd2a7bcb881c11e8cfe3078709382934e3916914-refs/branch-heads/5615@{#936}) on port 9515
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
2023-04-13 09:06:01 INFO Selenium -> POST session
2023-04-13 09:06:01 INFO Selenium >>> http://127.0.0.1:9515/session | {"capabilities":{"alwaysMatch":{"browserName":"chrome","goog:chromeOptions":{}}}}
2023-04-13 09:06:01 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"81"}
2023-04-13 09:06:03 DEBUG Selenium <<< {"content-length"=>["868"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:03 INFO Selenium <- {"value":{"capabilities":{"acceptInsecureCerts":false,"browserName":"chrome","browserVersion":"112.0.5615.49","chrome":{"chromedriverVersion":"112.0.5615.49 (bd2a7bcb881c11e8cfe3078709382934e3916914-refs/branch-heads/5615@{#936})","userDataDir":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/.com.google.Chrome.Ku0zO5"},"goog:chromeOptions":{"debuggerAddress":"localhost:49840"},"networkConnectionEnabled":false,"pageLoadStrategy":"normal","platformName":"mac os x","proxy":{},"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},"sessionId":"3a4dbe151403e8bd1c22ded2459e49ab"}}
2023-04-13 09:06:03 INFO Selenium -> POST session/3a4dbe151403e8bd1c22ded2459e49ab/timeouts
2023-04-13 09:06:03 INFO Selenium >>> http://127.0.0.1:9515/session/3a4dbe151403e8bd1c22ded2459e49ab/timeouts | {"implicit":2000}
2023-04-13 09:06:03 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"17"}
2023-04-13 09:06:03 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:03 INFO Selenium <- {"value":null}
2023-04-13 09:06:03 INFO Selenium -> POST session/3a4dbe151403e8bd1c22ded2459e49ab/url
2023-04-13 09:06:03 INFO Selenium >>> http://127.0.0.1:9515/session/3a4dbe151403e8bd1c22ded2459e49ab/url | {"url":"https://www.selenium.dev/selenium/web/dynamic.html"}
2023-04-13 09:06:03 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"60"}
2023-04-13 09:06:03 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:03 INFO Selenium <- {"value":null}
2023-04-13 09:06:03 INFO Selenium -> POST session/3a4dbe151403e8bd1c22ded2459e49ab/element
2023-04-13 09:06:03 INFO Selenium >>> http://127.0.0.1:9515/session/3a4dbe151403e8bd1c22ded2459e49ab/element | {"using":"css selector","value":"#reveal"}
2023-04-13 09:06:03 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"42"}
2023-04-13 09:06:03 DEBUG Selenium <<< {"content-length"=>["88"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:03 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"a7afe212-4ed0-4971-91bb-5dd9b5d3f9cb"}}
2023-04-13 09:06:03 INFO Selenium -> POST session/3a4dbe151403e8bd1c22ded2459e49ab/element/a7afe212-4ed0-4971-91bb-5dd9b5d3f9cb/click
2023-04-13 09:06:03 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:03 INFO Selenium <- {"value":null}
2023-04-13 09:06:03 INFO Selenium -> POST session/3a4dbe151403e8bd1c22ded2459e49ab/element
2023-04-13 09:06:03 INFO Selenium >>> http://127.0.0.1:9515/session/3a4dbe151403e8bd1c22ded2459e49ab/element | {"using":"css selector","value":"#revealed"}
2023-04-13 09:06:03 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"44"}
2023-04-13 09:06:03 DEBUG Selenium <<< {"content-length"=>["88"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:03 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"8112d2d6-cd74-4fd1-89e9-f21e27612707"}}
2023-04-13 09:06:03 INFO Selenium -> POST session/3a4dbe151403e8bd1c22ded2459e49ab/element/8112d2d6-cd74-4fd1-89e9-f21e27612707/click
2023-04-13 09:06:04 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:04 INFO Selenium <- {"value":null}
2023-04-13 09:06:04 INFO Selenium -> DELETE session/3a4dbe151403e8bd1c22ded2459e49ab
2023-04-13 09:06:04 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:04 INFO Selenium <- {"value":null}
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium Polling 20 seconds for exit of 92221
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium -> exit code is 0
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium -> exit code is 0
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium -> exit code is 0
1 example, 0 failures, 1 passed
Finished in 4.190002 seconds
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium -> exit code is 0
2023-04-13 09:06:04 DEBUG Selenium Checking if 92221 is exited:
2023-04-13 09:06:04 DEBUG Selenium -> exit code is 0
Process finished with exit code 0
Raw
chrome_send_keys.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/.rbenv/versions/3.2.0/bin/bundle exec ruby /Users/titusfortner/.rbenv/versions/3.2.0/bin/rspec /Users/titusfortner/code/seleniumhq.github.io/examples/ruby/spec/waits/waits_spec.rb --require teamcity/spec/runner/formatter/teamcity/formatter --format 'Spec::Runner::Formatter::TeamcityFormatter' --example 'Waits implicit for send keys'"
Testing started at 9:06 AM ...
Run options: include {:full_description=>/Waits\ implicit\ for\ send\ keys/}
2023-04-13 09:06:26 WARN Selenium [:logger_info] Details on how to use and modify Selenium logger:
https://selenium.dev/documentation/webdriver/troubleshooting/logging#ruby
2023-04-13 09:06:26 WARN Selenium applicable driver not found; attempting to install with Selenium Manager
2023-04-13 09:06:26 DEBUG Selenium Selenium Manager found at /Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager
2023-04-13 09:06:26 DEBUG Selenium Executing Process ["/Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager", "--browser", "chrome", "--output", "json", "--debug"]
2023-04-13 09:06:26 DEBUG Selenium Using shell command to find out chrome version
2023-04-13 09:06:26 DEBUG Selenium Running command: "/Applications/Google\\ Chrome.app/Contents/MacOS/Google\\ Chrome --version"
2023-04-13 09:06:26 DEBUG Selenium Output: "Google Chrome 112.0.5615.49 "
2023-04-13 09:06:26 DEBUG Selenium The version of chrome is 112.0.5615.49
2023-04-13 09:06:26 DEBUG Selenium Detected browser: chrome 112
2023-04-13 09:06:26 DEBUG Selenium Required driver: chromedriver 112.0.5615.49
2023-04-13 09:06:26 DEBUG Selenium Running command: "chromedriver --version"
2023-04-13 09:06:26 DEBUG Selenium Output: ""
2023-04-13 09:06:26 DEBUG Selenium chromedriver 112.0.5615.49 already in the cache
2023-04-13 09:06:26 INFO Selenium /Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver
2023-04-13 09:06:26 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver
2023-04-13 09:06:26 DEBUG Selenium Executing Process ["/Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver", "--port=9515"]
2023-04-13 09:06:26 DEBUG Selenium Starting process: ["/Users/titusfortner/.cache/selenium/chromedriver/mac64/112.0.5615.49/chromedriver", "--port=9515"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-04-13 09:06:26 DEBUG Selenium -> pid: 92371
2023-04-13 09:06:26 DEBUG Selenium polling for socket on ["127.0.0.1", 9515]
Starting ChromeDriver 112.0.5615.49 (bd2a7bcb881c11e8cfe3078709382934e3916914-refs/branch-heads/5615@{#936}) on port 9515
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
2023-04-13 09:06:27 INFO Selenium -> POST session
2023-04-13 09:06:27 INFO Selenium >>> http://127.0.0.1:9515/session | {"capabilities":{"alwaysMatch":{"browserName":"chrome","goog:chromeOptions":{}}}}
2023-04-13 09:06:27 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"81"}
2023-04-13 09:06:28 DEBUG Selenium <<< {"content-length"=>["868"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:28 INFO Selenium <- {"value":{"capabilities":{"acceptInsecureCerts":false,"browserName":"chrome","browserVersion":"112.0.5615.49","chrome":{"chromedriverVersion":"112.0.5615.49 (bd2a7bcb881c11e8cfe3078709382934e3916914-refs/branch-heads/5615@{#936})","userDataDir":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/.com.google.Chrome.dEshl1"},"goog:chromeOptions":{"debuggerAddress":"localhost:49855"},"networkConnectionEnabled":false,"pageLoadStrategy":"normal","platformName":"mac os x","proxy":{},"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},"sessionId":"fcb9992c6b8bcdb2d724bb24ddc7b18b"}}
2023-04-13 09:06:28 INFO Selenium -> POST session/fcb9992c6b8bcdb2d724bb24ddc7b18b/timeouts
2023-04-13 09:06:28 INFO Selenium >>> http://127.0.0.1:9515/session/fcb9992c6b8bcdb2d724bb24ddc7b18b/timeouts | {"implicit":2000}
2023-04-13 09:06:28 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"17"}
2023-04-13 09:06:28 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:28 INFO Selenium <- {"value":null}
2023-04-13 09:06:28 INFO Selenium -> POST session/fcb9992c6b8bcdb2d724bb24ddc7b18b/url
2023-04-13 09:06:28 INFO Selenium >>> http://127.0.0.1:9515/session/fcb9992c6b8bcdb2d724bb24ddc7b18b/url | {"url":"https://www.selenium.dev/selenium/web/dynamic.html"}
2023-04-13 09:06:28 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"60"}
2023-04-13 09:06:29 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:29 INFO Selenium <- {"value":null}
2023-04-13 09:06:29 INFO Selenium -> POST session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element
2023-04-13 09:06:29 INFO Selenium >>> http://127.0.0.1:9515/session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element | {"using":"css selector","value":"#reveal"}
2023-04-13 09:06:29 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"42"}
2023-04-13 09:06:29 DEBUG Selenium <<< {"content-length"=>["88"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:29 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"af441d43-5cf0-449a-a7d5-25f5d4341ccf"}}
2023-04-13 09:06:29 INFO Selenium -> POST session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element/af441d43-5cf0-449a-a7d5-25f5d4341ccf/click
2023-04-13 09:06:29 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:29 INFO Selenium <- {"value":null}
2023-04-13 09:06:29 INFO Selenium -> POST session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element
2023-04-13 09:06:29 INFO Selenium >>> http://127.0.0.1:9515/session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element | {"using":"css selector","value":"#revealed"}
2023-04-13 09:06:29 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"44"}
2023-04-13 09:06:29 DEBUG Selenium <<< {"content-length"=>["88"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:29 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"3364d0f9-d62e-495f-ae48-496afd214b73"}}
2023-04-13 09:06:29 INFO Selenium -> POST session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element/3364d0f9-d62e-495f-ae48-496afd214b73/value
2023-04-13 09:06:29 INFO Selenium >>> http://127.0.0.1:9515/session/fcb9992c6b8bcdb2d724bb24ddc7b18b/element/3364d0f9-d62e-495f-ae48-496afd214b73/value | {"value":["D","i","s","p","l","a","y","e","d"],"text":"Displayed"}
2023-04-13 09:06:29 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"66"}
2023-04-13 09:06:30 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:30 INFO Selenium <- {"value":null}
2023-04-13 09:06:30 INFO Selenium -> DELETE session/fcb9992c6b8bcdb2d724bb24ddc7b18b
2023-04-13 09:06:30 DEBUG Selenium <<< {"content-length"=>["14"], "content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"]}
2023-04-13 09:06:30 INFO Selenium <- {"value":null}
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium Polling 20 seconds for exit of 92371
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium -> exit code is 0
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium -> exit code is 0
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium -> exit code is 0
1 example, 0 failures, 1 passed
Finished in 4.095443 seconds
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium -> exit code is 0
2023-04-13 09:06:30 DEBUG Selenium Checking if 92371 is exited:
2023-04-13 09:06:30 DEBUG Selenium -> exit code is 0
Process finished with exit code 0
Raw
firefox_click.log
2023-04-13 09:07:20 WARN Selenium [:logger_info] Details on how to use and modify Selenium logger:
https://selenium.dev/documentation/webdriver/troubleshooting/logging#ruby
2023-04-13 09:07:20 WARN Selenium applicable driver not found; attempting to install with Selenium Manager
2023-04-13 09:07:20 DEBUG Selenium Selenium Manager found at /Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager
2023-04-13 09:07:20 DEBUG Selenium Executing Process ["/Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager", "--browser", "firefox", "--output", "json", "--debug"]
2023-04-13 09:07:20 DEBUG Selenium Using shell command to find out firefox version
2023-04-13 09:07:20 DEBUG Selenium Running command: "/Applications/Firefox.app/Contents/MacOS/firefox -v"
2023-04-13 09:07:20 DEBUG Selenium Output: "Mozilla Firefox 111.0.1"
2023-04-13 09:07:20 DEBUG Selenium The version of firefox is 111.0.1
2023-04-13 09:07:20 DEBUG Selenium Detected browser: firefox 111
2023-04-13 09:07:20 DEBUG Selenium Required driver: geckodriver 0.33.0
2023-04-13 09:07:20 DEBUG Selenium Running command: "geckodriver --version"
2023-04-13 09:07:20 DEBUG Selenium Output: ""
2023-04-13 09:07:20 DEBUG Selenium geckodriver 0.33.0 already in the cache
2023-04-13 09:07:20 INFO Selenium /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver
2023-04-13 09:07:20 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver
2023-04-13 09:07:20 DEBUG Selenium Executing Process ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver", "--port=4444"]
2023-04-13 09:07:20 DEBUG Selenium Starting process: ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver", "--port=4444"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-04-13 09:07:20 DEBUG Selenium -> pid: 92649
2023-04-13 09:07:20 DEBUG Selenium polling for socket on ["127.0.0.1", 4444]
1681394840260 geckodriver INFO Listening on 127.0.0.1:4444
2023-04-13 09:07:20 INFO Selenium -> POST session
2023-04-13 09:07:20 INFO Selenium >>> http://127.0.0.1:4444/session | {"capabilities":{"alwaysMatch":{"timeouts":{"implicit":1500},"acceptInsecureCerts":true,"browserName":"firefox","moz:firefoxOptions":{},"moz:debuggerAddress":true}}}
2023-04-13 09:07:20 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"165"}
1681394840677 mozrunner::runner INFO Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" MOZ_NO_REMOTE="1" "/App ... s" "localhost" "-foreground" "-no-remote" "-profile" "/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileJ41o6r"
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
1681394841437 Marionette INFO Marionette enabled
1681394841585 Marionette INFO Listening on port 49901
console.error: "Warning: unrecognized command line flag -remote-allow-hosts\n"
Read port: 49901
WebDriver BiDi listening on ws://127.0.0.1:9222
1681394842616 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_mozprofileJ41o6r/search.json.mozlz4", (void 0)))
DevTools listening on ws://127.0.0.1:9222/devtools/browser/311bf473-b03e-4567-b7f6-913e5c4bf3f9
2023-04-13 09:07:24 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["800"], "date"=>["Thu, 13 Apr 2023 14:07:20 GMT"]}
2023-04-13 09:07:24 INFO Selenium <- {"value":{"sessionId":"21f183e6-307f-45b3-903e-78406ed7a91b","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"111.0.1","moz:accessibilityChecks":false,"moz:buildID":"20230321111920","moz:debuggerAddress":"127.0.0.1:9222","moz:geckodriverVersion":"0.33.0","moz:headless":false,"moz:platformVersion":"20.6.0","moz:processID":92650,"moz:profile":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileJ41o6r","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"mac","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":1500,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
2023-04-13 09:07:24 INFO Selenium -> POST session/21f183e6-307f-45b3-903e-78406ed7a91b/timeouts
2023-04-13 09:07:24 INFO Selenium >>> http://127.0.0.1:4444/session/21f183e6-307f-45b3-903e-78406ed7a91b/timeouts | {"implicit":2000}
2023-04-13 09:07:24 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"17"}
2023-04-13 09:07:24 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:24 INFO Selenium <- {"value":null}
2023-04-13 09:07:24 INFO Selenium -> POST session/21f183e6-307f-45b3-903e-78406ed7a91b/url
2023-04-13 09:07:24 INFO Selenium >>> http://127.0.0.1:4444/session/21f183e6-307f-45b3-903e-78406ed7a91b/url | {"url":"https://www.selenium.dev/selenium/web/dynamic.html"}
2023-04-13 09:07:24 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"60"}
2023-04-13 09:07:24 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:24 INFO Selenium <- {"value":null}
2023-04-13 09:07:24 INFO Selenium -> POST session/21f183e6-307f-45b3-903e-78406ed7a91b/element
2023-04-13 09:07:24 INFO Selenium >>> http://127.0.0.1:4444/session/21f183e6-307f-45b3-903e-78406ed7a91b/element | {"using":"css selector","value":"#reveal"}
2023-04-13 09:07:24 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"42"}
2023-04-13 09:07:24 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["88"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:24 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"64bcec25-8153-4035-b4c7-080cf8ac6a1d"}}
2023-04-13 09:07:24 INFO Selenium -> POST session/21f183e6-307f-45b3-903e-78406ed7a91b/element/64bcec25-8153-4035-b4c7-080cf8ac6a1d/click
2023-04-13 09:07:25 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:25 INFO Selenium <- {"value":null}
2023-04-13 09:07:25 INFO Selenium -> POST session/21f183e6-307f-45b3-903e-78406ed7a91b/element
2023-04-13 09:07:25 INFO Selenium >>> http://127.0.0.1:4444/session/21f183e6-307f-45b3-903e-78406ed7a91b/element | {"using":"css selector","value":"#revealed"}
2023-04-13 09:07:25 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"44"}
2023-04-13 09:07:25 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["88"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:25 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"7c31503d-cb9b-461e-a0db-194410baadf1"}}
2023-04-13 09:07:25 INFO Selenium -> POST session/21f183e6-307f-45b3-903e-78406ed7a91b/element/7c31503d-cb9b-461e-a0db-194410baadf1/click
2023-04-13 09:07:25 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["739"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:25 INFO Selenium <- {"value":{"error":"element not interactable","message":"Element <input id=\"revealed\"> could not be scrolled into view","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:180:5\nElementNotInteractableError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:290:5\nwebdriverClickElement@chrome://remote/content/marionette/interaction.sys.mjs:150:11\ninteraction.clickElement@chrome://remote/content/marionette/interaction.sys.mjs:119:11\nclickElement@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:208:29\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:97:31\n"}}
2023-04-13 09:07:25 INFO Selenium -> DELETE session/21f183e6-307f-45b3-903e-78406ed7a91b
1681394845125 Marionette INFO Stopped listening on port 49901
console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
JavaScript error: resource://services-settings/Utils.jsm, line 237: TypeError: NetworkError: Network request failed
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:828:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
console.error: "TelemetryScheduler.shutdown - Already shut down"
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:828: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:828: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:828: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:828: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-04-13 09:07:25 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:24 GMT"]}
2023-04-13 09:07:25 INFO Selenium <- {"value":null}
2023-04-13 09:07:25 DEBUG Selenium Checking if 92649 is exited:
2023-04-13 09:07:25 DEBUG Selenium Checking if 92649 is exited:
2023-04-13 09:07:25 DEBUG Selenium Sending TERM to process: 92649
2023-04-13 09:07:25 DEBUG Selenium Polling 20 seconds for exit of 92649
2023-04-13 09:07:25 DEBUG Selenium Checking if 92649 is exited:
2023-04-13 09:07:25 DEBUG Selenium Checking if 92649 is exited:
2023-04-13 09:07:25 DEBUG Selenium -> exit code is 15
2023-04-13 09:07:25 DEBUG Selenium Checking if 92649 is exited:
2023-04-13 09:07:25 DEBUG Selenium -> exit code is 15
2023-04-13 09:07:25 DEBUG Selenium -> stopped 92649
Selenium::WebDriver::Error::ElementNotInteractableError: Element <input id="revealed"> could not be scrolled into view
0) Waits implicit for click
Failure/Error: driver.find_element(id: 'revealed').click
Selenium::WebDriver::Error::ElementNotInteractableError:
Element <input id="revealed"> could not be scrolled into view
# RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
# WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:180:5
# ElementNotInteractableError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:290:5
# webdriverClickElement@chrome://remote/content/marionette/interaction.sys.mjs:150:11
# interaction.clickElement@chrome://remote/content/marionette/interaction.sys.mjs:119:11
# clickElement@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:208:29
# receiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:97:31
# ./spec/waits/waits_spec.rb:42:in `block (2 levels) in <top (required)>'
Raw
firefox_send_keys.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/.rbenv/versions/3.2.0/bin/bundle exec ruby /Users/titusfortner/.rbenv/versions/3.2.0/bin/rspec /Users/titusfortner/code/seleniumhq.github.io/examples/ruby/spec/waits/waits_spec.rb --require teamcity/spec/runner/formatter/teamcity/formatter --format 'Spec::Runner::Formatter::TeamcityFormatter' --example 'Waits implicit for send keys'"
Testing started at 9:07 AM ...
Run options: include {:full_description=>/Waits\ implicit\ for\ send\ keys/}
2023-04-13 09:07:05 WARN Selenium [:logger_info] Details on how to use and modify Selenium logger:
https://selenium.dev/documentation/webdriver/troubleshooting/logging#ruby
2023-04-13 09:07:05 WARN Selenium applicable driver not found; attempting to install with Selenium Manager
2023-04-13 09:07:05 DEBUG Selenium Selenium Manager found at /Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager
2023-04-13 09:07:05 DEBUG Selenium Executing Process ["/Users/titusfortner/.rbenv/versions/3.2.0/lib/ruby/gems/3.2.0/gems/selenium-webdriver-4.8.6/bin/macos/selenium-manager", "--browser", "firefox", "--output", "json", "--debug"]
2023-04-13 09:07:05 DEBUG Selenium Using shell command to find out firefox version
2023-04-13 09:07:05 DEBUG Selenium Running command: "/Applications/Firefox.app/Contents/MacOS/firefox -v"
2023-04-13 09:07:05 DEBUG Selenium Output: "Mozilla Firefox 111.0.1"
2023-04-13 09:07:05 DEBUG Selenium The version of firefox is 111.0.1
2023-04-13 09:07:05 DEBUG Selenium Detected browser: firefox 111
2023-04-13 09:07:05 DEBUG Selenium Required driver: geckodriver 0.33.0
2023-04-13 09:07:05 DEBUG Selenium Running command: "geckodriver --version"
2023-04-13 09:07:05 DEBUG Selenium Output: ""
2023-04-13 09:07:05 DEBUG Selenium geckodriver 0.33.0 already in the cache
2023-04-13 09:07:05 INFO Selenium /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver
2023-04-13 09:07:05 DEBUG Selenium Driver found at /Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver
2023-04-13 09:07:05 DEBUG Selenium Executing Process ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver", "--port=4444"]
2023-04-13 09:07:05 DEBUG Selenium Starting process: ["/Users/titusfortner/.cache/selenium/geckodriver/mac64/0.33.0/geckodriver", "--port=4444"] with {[:out, :err]=>#<IO:<STDOUT>>, :pgroup=>true}
2023-04-13 09:07:05 DEBUG Selenium -> pid: 92542
2023-04-13 09:07:05 DEBUG Selenium polling for socket on ["127.0.0.1", 4444]
1681394825845 geckodriver INFO Listening on 127.0.0.1:4444
2023-04-13 09:07:06 INFO Selenium -> POST session
2023-04-13 09:07:06 INFO Selenium >>> http://127.0.0.1:4444/session | {"capabilities":{"alwaysMatch":{"timeouts":{"implicit":1500},"acceptInsecureCerts":true,"browserName":"firefox","moz:firefoxOptions":{},"moz:debuggerAddress":true}}}
2023-04-13 09:07:06 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"165"}
1681394826254 mozrunner::runner INFO Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" MOZ_NO_REMOTE="1" "/App ... s" "localhost" "-foreground" "-no-remote" "-profile" "/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileXYmge8"
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
1681394827054 Marionette INFO Marionette enabled
1681394827219 Marionette INFO Listening on port 49867
console.error: "Warning: unrecognized command line flag -remote-allow-hosts\n"
Read port: 49867
WebDriver BiDi listening on ws://127.0.0.1:9222
1681394828266 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_mozprofileXYmge8/search.json.mozlz4", (void 0)))
DevTools listening on ws://127.0.0.1:9222/devtools/browser/8641c49c-5a46-42c2-b4a9-c7f2dc3c3bee
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["800"], "date"=>["Thu, 13 Apr 2023 14:07:06 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":{"sessionId":"54df369e-47f2-440c-9db8-224d5e4b9702","capabilities":{"acceptInsecureCerts":true,"browserName":"firefox","browserVersion":"111.0.1","moz:accessibilityChecks":false,"moz:buildID":"20230321111920","moz:debuggerAddress":"127.0.0.1:9222","moz:geckodriverVersion":"0.33.0","moz:headless":false,"moz:platformVersion":"20.6.0","moz:processID":92543,"moz:profile":"/var/folders/fx/nlx77ccs72g808pr1994_xtw0000gn/T/rust_mozprofileXYmge8","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"pageLoadStrategy":"normal","platformName":"mac","proxy":{},"setWindowRect":true,"strictFileInteractability":false,"timeouts":{"implicit":1500,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
2023-04-13 09:07:10 INFO Selenium -> POST session/54df369e-47f2-440c-9db8-224d5e4b9702/timeouts
2023-04-13 09:07:10 INFO Selenium >>> http://127.0.0.1:4444/session/54df369e-47f2-440c-9db8-224d5e4b9702/timeouts | {"implicit":2000}
2023-04-13 09:07:10 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"17"}
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":null}
2023-04-13 09:07:10 INFO Selenium -> POST session/54df369e-47f2-440c-9db8-224d5e4b9702/url
2023-04-13 09:07:10 INFO Selenium >>> http://127.0.0.1:4444/session/54df369e-47f2-440c-9db8-224d5e4b9702/url | {"url":"https://www.selenium.dev/selenium/web/dynamic.html"}
2023-04-13 09:07:10 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"60"}
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":null}
2023-04-13 09:07:10 INFO Selenium -> POST session/54df369e-47f2-440c-9db8-224d5e4b9702/element
2023-04-13 09:07:10 INFO Selenium >>> http://127.0.0.1:4444/session/54df369e-47f2-440c-9db8-224d5e4b9702/element | {"using":"css selector","value":"#reveal"}
2023-04-13 09:07:10 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"42"}
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["88"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"3150ef6b-41e5-4444-9144-d71cf3298bd0"}}
2023-04-13 09:07:10 INFO Selenium -> POST session/54df369e-47f2-440c-9db8-224d5e4b9702/element/3150ef6b-41e5-4444-9144-d71cf3298bd0/click
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":null}
2023-04-13 09:07:10 INFO Selenium -> POST session/54df369e-47f2-440c-9db8-224d5e4b9702/element
2023-04-13 09:07:10 INFO Selenium >>> http://127.0.0.1:4444/session/54df369e-47f2-440c-9db8-224d5e4b9702/element | {"using":"css selector","value":"#revealed"}
2023-04-13 09:07:10 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"44"}
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["88"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":{"element-6066-11e4-a52e-4f735466cecf":"88760728-7a1f-4a21-944c-6386e240f399"}}
2023-04-13 09:07:10 INFO Selenium -> POST session/54df369e-47f2-440c-9db8-224d5e4b9702/element/88760728-7a1f-4a21-944c-6386e240f399/value
2023-04-13 09:07:10 INFO Selenium >>> http://127.0.0.1:4444/session/54df369e-47f2-440c-9db8-224d5e4b9702/element/88760728-7a1f-4a21-944c-6386e240f399/value | {"value":["D","i","s","p","l","a","y","e","d"],"text":"Displayed"}
2023-04-13 09:07:10 DEBUG Selenium > {"Accept"=>"application/json", "Content-Type"=>"application/json; charset=UTF-8", "User-Agent"=>"selenium/4.8.6 (ruby macosx)", "Content-Length"=>"66"}
2023-04-13 09:07:10 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["752"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:10 INFO Selenium <- {"value":{"error":"element not interactable","message":"Element <input id=\"revealed\"> is not reachable by keyboard","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:180:5\nElementNotInteractableError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:290:5\nwebdriverSendKeysToElement@chrome://remote/content/marionette/interaction.sys.mjs:624:13\ninteraction.sendKeysToElement@chrome://remote/content/marionette/interaction.sys.mjs:598:11\nsendKeysToElement@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:516:29\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:157:31\n"}}
2023-04-13 09:07:10 INFO Selenium -> DELETE session/54df369e-47f2-440c-9db8-224d5e4b9702
1681394830815 Marionette INFO Stopped listening on port 49867
console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
JavaScript error: resource://services-settings/Utils.jsm, line 237: TypeError: NetworkError: Network request failed
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:828:36
observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
console.error: "TelemetryScheduler.shutdown - Already shut down"
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:828: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:828: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:828: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:828: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-04-13 09:07:11 DEBUG Selenium <<< {"content-type"=>["application/json; charset=utf-8"], "cache-control"=>["no-cache"], "content-length"=>["14"], "date"=>["Thu, 13 Apr 2023 14:07:10 GMT"]}
2023-04-13 09:07:11 INFO Selenium <- {"value":null}
2023-04-13 09:07:11 DEBUG Selenium Checking if 92542 is exited:
2023-04-13 09:07:11 DEBUG Selenium Checking if 92542 is exited:
2023-04-13 09:07:11 DEBUG Selenium Sending TERM to process: 92542
2023-04-13 09:07:11 DEBUG Selenium Polling 20 seconds for exit of 92542
2023-04-13 09:07:11 DEBUG Selenium Checking if 92542 is exited:
2023-04-13 09:07:11 DEBUG Selenium Checking if 92542 is exited:
2023-04-13 09:07:11 DEBUG Selenium -> exit code is 15
2023-04-13 09:07:11 DEBUG Selenium Checking if 92542 is exited:
2023-04-13 09:07:11 DEBUG Selenium -> exit code is 15
2023-04-13 09:07:11 DEBUG Selenium -> stopped 92542
Selenium::WebDriver::Error::ElementNotInteractableError: Element <input id="revealed"> is not reachable by keyboard
0) Waits implicit for send keys
Failure/Error: driver.find_element(id: 'revealed').send_keys('Displayed')
Selenium::WebDriver::Error::ElementNotInteractableError:
Element <input id="revealed"> is not reachable by keyboard
# RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
# WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:180:5
# ElementNotInteractableError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:290:5
# webdriverSendKeysToElement@chrome://remote/content/marionette/interaction.sys.mjs:624:13
# interaction.sendKeysToElement@chrome://remote/content/marionette/interaction.sys.mjs:598:11
# sendKeysToElement@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:516:29
# receiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.sys.mjs:157:31
# ./spec/waits/waits_spec.rb:49:in `block (2 levels) in <top (required)>'
1 example, 1 failure, 0 passed
Finished in 6.308527 seconds
2023-04-13 09:07:11 DEBUG Selenium Checking if 92542 is exited:
2023-04-13 09:07:11 DEBUG Selenium -> exit code is 15
Process finished with exit code 1
Raw
test_code.rb
# Click
driver.manage.timeouts.implicit_wait = 2
driver.get 'https://www.selenium.dev/selenium/web/dynamic.html'
driver.find_element(id: 'reveal').click
driver.find_element(id: 'revealed').click
# Send Keys
driver.manage.timeouts.implicit_wait = 2
driver.get 'https://www.selenium.dev/selenium/web/dynamic.html'
driver.find_element(id: 'reveal').click
driver.find_element(id: 'revealed').send_keys('Displayed')
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment