Skip to content

Instantly share code, notes, and snippets.

@stephendonner

stephendonner/gist:81a78cfcb46717b0c75e106847be2fe8

Created November 27, 2019 17:37
Show Gist options

  • Select an option

    Learn more about clone URLs
  • Save stephendonner/81a78cfcb46717b0c75e106847be2fe8 to your computer and use it in GitHub Desktop.

Select an option

Learn more about clone URLs
Save stephendonner/81a78cfcb46717b0c75e106847be2fe8 to your computer and use it in GitHub Desktop.
Download ZIP
./mach raptor -t raptor-tp6m-1-cold --app geckoview --binary org.mozilla.geckoview_example --browsertime
Raw
gistfile1.txt
17:31:50 INFO - raptor-mitmproxy Info: mozproxy_dir used for mitmproxy downloads and exe files: /Users/sdonner/mozilla-source/mozilla-unified/obj-x86_64-apple-darwin19.2.0/testing/mozproxy
17:31:50 INFO - raptor-mitmproxy Info: mitmproxy binary already exists. Skipping download
17:31:50 INFO - raptor-mitmproxy Info: downloading mitmproxy pageset
17:31:50 INFO - raptor-mitmproxy Info: INFO - unzipping "mitm4-pixel2-fennec-amazon.zip"
17:31:50 INFO - raptor-mitmproxy Info: mitmdump path: /Users/sdonner/mozilla-source/mozilla-unified/obj-x86_64-apple-darwin19.2.0/testing/mozproxy/mitmdump-4.0.4/mitmdump
17:31:50 INFO - raptor-mitmproxy Info: browser path: org.mozilla.geckoview_example
17:31:50 INFO - raptor-mitmproxy Info: Starting mitmproxy playback using env path: :/Users/sdonner/mozilla-source/mozilla-unified/obj-x86_64-apple-darwin19.2.0/testing/raptor-venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin:/Applications/VMware Fusion.app/Contents/Public:/Applications/Wireshark.app/Contents/MacOS
17:31:50 INFO - raptor-mitmproxy Info: Starting mitmproxy playback using command: /Users/sdonner/mozilla-source/mozilla-unified/obj-x86_64-apple-darwin19.2.0/testing/mozproxy/mitmdump-4.0.4/mitmdump -v --set upstream_cert=false --set websocket=false --set server_replay_files=/Users/sdonner/mozilla-source/mozilla-unified/obj-x86_64-apple-darwin19.2.0/testing/mozproxy/mitm4-pixel2-fennec-amazon.mp --scripts /Users/sdonner/mozilla-source/mozilla-unified/testing/mozbase/mozproxy/mozproxy/backends/mitm/scripts/alternate-server-replay-4.0.4.py
17:31:50 INFO - raptor-mitmproxy Info: Mitmproxy playback successfully started as pid 39610
17:31:50 INFO - raptor-mitmproxy Info: certutil is found at: /Users/sdonner/.mozbuild/android-device/host-utils-71.0a1.en-US.mac/certutil
17:31:50 INFO - raptor-mitmproxy Info: checking if the nss cert db already exists in the android browser profile
17:31:50 INFO - raptor-mitmproxy Info: Certutil: Running command: /Users/sdonner/.mozbuild/android-device/host-utils-71.0a1.en-US.mac/certutil -d sql:/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner/ -L
17:31:50 INFO - raptor-mitmproxy Info: nss cert db doesn't exist yet.
17:31:50 INFO - raptor-mitmproxy Info: creating nss cert database
17:31:50 INFO - raptor-mitmproxy Info: Certutil: Running command: /Users/sdonner/.mozbuild/android-device/host-utils-71.0a1.en-US.mac/certutil -d sql:/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner/ -N --empty-password
17:31:50 INFO - raptor-mitmproxy Info: Certutil returncode: 0
17:31:50 INFO - raptor-mitmproxy Info: Certutil output:
17:31:50 INFO - raptor-mitmproxy Info: checking if the nss cert db already exists in the android browser profile
17:31:50 INFO - raptor-mitmproxy Info: Certutil: Running command: /Users/sdonner/.mozbuild/android-device/host-utils-71.0a1.en-US.mac/certutil -d sql:/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner/ -L
17:31:50 INFO - raptor-mitmproxy Info: Certutil returncode: 0
17:31:50 INFO - raptor-mitmproxy Info: Certutil output:
17:31:50 INFO - Certificate Nickname Trust Attributes
17:31:50 INFO - SSL,S/MIME,JAR/XPI
17:31:50 INFO - raptor-mitmproxy Info: the nss cert db exists
17:31:50 INFO - raptor-mitmproxy Info: importing mitmproxy cert into db using command
17:31:50 INFO - raptor-mitmproxy Info: Certutil: Running command: /Users/sdonner/.mozbuild/android-device/host-utils-71.0a1.en-US.mac/certutil -A -d sql:/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner/ -n mitmproxy-cert -t TC,, -a -i /Users/sdonner/.mitmproxy/mitmproxy-ca-cert.cer
17:31:50 INFO - raptor-mitmproxy Info: Certutil returncode: 0
17:31:50 INFO - raptor-mitmproxy Info: Certutil output:
17:31:50 INFO - raptor-mitmproxy Info: verifying that the mitmproxy ca cert is installed on android
17:31:50 INFO - raptor-mitmproxy Info: getting the list of certs in the nss cert db in the android browser profile
17:31:50 INFO - raptor-mitmproxy Info: Certutil: Running command: /Users/sdonner/.mozbuild/android-device/host-utils-71.0a1.en-US.mac/certutil -d sql:/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner/ -L
17:31:50 INFO - raptor-mitmproxy Info: Certutil returncode: 0
17:31:50 INFO - raptor-mitmproxy Info: Certutil output:
17:31:50 INFO - Certificate Nickname Trust Attributes
17:31:50 INFO - SSL,S/MIME,JAR/XPI
17:31:50 INFO - mitmproxy-cert CT,,
17:31:50 INFO - raptor-mitmproxy Info: verfied the mitmproxy-cert is installed in the nss cert db on android
17:31:50 INFO - raptor-main Info: Playback recording information not available
17:31:50 INFO - raptor-main Info: test: {'expected_browser_cycles': 15, 'alert_threshold': '2.0', 'playback_pageset_manifest': 'mitm4-pixel2-fennec-amazon.manifest', 'browser_cycles': '15', 'ancestor-manifest': '/Users/sdonner/mozilla-source/mozilla-unified/testing/raptor/raptor/raptor.ini', 'here': '/Users/sdonner/mozilla-source/mozilla-unified/testing/raptor/raptor/tests/tp6/mobile', 'measure': ['fnbpaint', 'fcp', 'dcf', 'loadtime'], 'path': '/Users/sdonner/mozilla-source/mozilla-unified/testing/raptor/raptor/tests/tp6/mobile/raptor-tp6m-amazon-geckoview-cold', 'cold': True, 'manifest_relpath': 'tests/tp6/mobile/raptor-tp6m-1-cold.ini', 'unit': 'ms', 'alert_on': ['fcp', 'loadtime'], 'type': 'pageload', 'name': 'raptor-tp6m-amazon-geckoview-cold', 'playback_recordings': 'mitm4-pixel2-fennec-amazon.mp', 'page_cycles': 1, 'apps': 'geckoview', 'test_url': 'https://www.amazon.com', 'manifest': '/Users/sdonner/mozilla-source/mozilla-unified/testing/raptor/raptor/tests/tp6/mobile/raptor-tp6m-1-cold.ini', 'page_timeout': '60000', 'playback': 'mitmproxy-android', 'browser_cycle': 1, 'expected': 'pass', 'lower_is_better': True, 'relpath': 'tests/tp6/mobile/raptor-tp6m-amazon-geckoview-cold'}
17:31:50 INFO - raptor-main Info: timeout (s): 60000
17:31:50 INFO - raptor-main Info: browsertime cwd: /Users/sdonner/mozilla-source/mozilla-unified/testing/mozharness/build
17:31:50 INFO - raptor-main Info: browsertime cmd: /Users/sdonner/.mozbuild/node/bin/node /Users/sdonner/mozilla-source/mozilla-unified/tools/browsertime/node_modules/browsertime/bin/browsertime.js /Users/sdonner/mozilla-source/mozilla-unified/testing/raptor/raptor/../browsertime/browsertime_pageload.js --browser firefox --android --firefox.binaryPath /Users/sdonner/.mozbuild/node/bin/node --firefox.android.package org.mozilla.geckoview_example --firefox.android.activity org.mozilla.geckoview_example.GeckoViewActivity --browsertime.page_cycles 1 --browsertime.url https://www.amazon.com --browsertime.page_cycle_delay 1000 --browsertime.foreground_delay 5000 --browsertime.post_startup_delay 30000 --firefox.profileTemplate /var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner --skipHar --video false --visualMetrics false --timeouts.pageLoad 60000 --timeouts.script 60000 -vv --resultDir /Users/sdonner/mozilla-source/mozilla-unified/testing/mozharness/build/blobber_upload_dir/browsertime-results/raptor-tp6m-amazon-geckoview-cold -n 15
17:31:50 INFO - raptor-main Info: PATH: /Users/sdonner/mozilla-source/mozilla-unified/obj-x86_64-apple-darwin19.2.0/testing/raptor-venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin:/Applications/VMware Fusion.app/Contents/Public:/Applications/Wireshark.app/Contents/MacOS
17:31:51 INFO - [2019-11-27 12:31:51] DEBUG: [browsertime] Running with options: {
17:31:51 INFO - "scripts": [],
17:31:51 INFO - "iterations": 15,
17:31:51 INFO - "delay": 0,
17:31:51 INFO - "videoParams": {
17:31:51 INFO - "addTimer": true,
17:31:51 INFO - "debug": false,
17:31:51 INFO - "keepOriginalVideo": false,
17:31:51 INFO - "filmstripFullSize": false,
17:31:51 INFO - "createFilmstrip": true,
17:31:51 INFO - "framerate": 30,
17:31:51 INFO - "crf": 23,
17:31:51 INFO - "filmstripQuality": 75,
17:31:51 INFO - "nice": 0
17:31:51 INFO - },
17:31:51 INFO - "_": [
17:31:51 INFO - "/Users/sdonner/mozilla-source/mozilla-unified/testing/raptor/raptor/../browsertime/browsertime_pageload.js"
17:31:51 INFO - ],
17:31:51 INFO - "chrome": {
17:31:51 INFO - "cdp": {
17:31:51 INFO - "performance": true
17:31:51 INFO - },
17:31:51 INFO - "ignoreCertificateErrors": true,
17:31:51 INFO - "ignore-certificate-errors": true
17:31:51 INFO - },
17:31:51 INFO - "firefox": {
17:31:51 INFO - "disableSafeBrowsing": false,
17:31:51 INFO - "disable-safe-browsing": false,
17:31:51 INFO - "windowRecorder": true,
17:31:51 INFO - "window-recorder": true,
17:31:51 INFO - "geckoProfiler": false,
17:31:51 INFO - "gecko-profiler": false,
17:31:51 INFO - "binaryPath": "/Users/sdonner/.mozbuild/node/bin/node",
17:31:51 INFO - "binary-path": "/Users/sdonner/.mozbuild/node/bin/node",
17:31:51 INFO - "android": {
17:31:51 INFO - "package": "org.mozilla.geckoview_example",
17:31:51 INFO - "activity": "org.mozilla.geckoview_example.GeckoViewActivity"
17:31:51 INFO - },
17:31:51 INFO - "profileTemplate": "/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner",
17:31:51 INFO - "profile-template": "/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/tmpIOgFUn.mozrunner",
17:31:51 INFO - "includeResponseBodies": "none",
17:31:51 INFO - "include-response-bodies": "none",
17:31:51 INFO - "geckoProfilerParams": {
17:31:51 INFO - "features": "js,stackwalk,leaf,responsiveness",
17:31:51 INFO - "threads": "GeckoMain,Compositor,Renderer",
17:31:51 INFO - "bufferSize": 1000000
17:31:51 INFO - },
17:31:51 INFO - "gecko-profiler-params": {
17:31:51 INFO - "features": "js,stackwalk,leaf,responsiveness",
17:31:51 INFO - "threads": "GeckoMain,Compositor,Renderer",
17:31:51 INFO - "buffer-size": 1000000
17:31:51 INFO - }
17:31:51 INFO - },
17:31:51 INFO - "video-params": {
17:31:51 INFO - "add-timer": true,
17:31:51 INFO - "debug": false,
17:31:51 INFO - "keep-original-video": false,
17:31:51 INFO - "filmstrip-full-size": false,
17:31:51 INFO - "create-filmstrip": true,
17:31:51 INFO - "framerate": 30,
17:31:51 INFO - "crf": 23,
17:31:51 INFO - "filmstrip-quality": 75,
17:31:51 INFO - "nice": 0
17:31:51 INFO - },
17:31:51 INFO - "android": true,
17:31:51 INFO - "processStartTime": false,
17:31:51 INFO - "process-start-time": false,
17:31:51 INFO - "screenshot": false,
17:31:51 INFO - "pageCompleteCheckInactivity": false,
17:31:51 INFO - "page-complete-check-inactivity": false,
17:31:51 INFO - "prettyPrint": false,
17:31:51 INFO - "pretty-print": false,
17:31:51 INFO - "connectivity": {
17:31:51 INFO - "throttle": {
17:31:51 INFO - "localhost": false
17:31:51 INFO - },
17:31:51 INFO - "profile": "native",
17:31:51 INFO - "engine": "external"
17:31:51 INFO - },
17:31:51 INFO - "cacheClearRaw": false,
17:31:51 INFO - "cache-clear-raw": false,
17:31:51 INFO - "xvfb": false,
17:31:51 INFO - "headless": false,
17:31:51 INFO - "spa": false,
17:31:51 INFO - "browser": "firefox",
17:31:51 INFO - "b": "firefox",
17:31:51 INFO - "browsertime": {
17:31:51 INFO - "page_cycles": 1,
17:31:51 INFO - "url": "https://www.amazon.com",
17:31:51 INFO - "page_cycle_delay": 1000,
17:31:51 INFO - "foreground_delay": 5000,
17:31:51 INFO - "post_startup_delay": 30000
17:31:51 INFO - },
17:31:51 INFO - "skipHar": true,
17:31:51 INFO - "skip-har": true,
17:31:51 INFO - "video": false,
17:31:51 INFO - "visualMetrics": false,
17:31:51 INFO - "visual-metrics": false,
17:31:51 INFO - "timeouts": {
17:31:51 INFO - "pageLoad": 60000,
17:31:51 INFO - "page-load": 60000,
17:31:51 INFO - "script": 60000,
17:31:51 INFO - "browserStart": 60000,
17:31:51 INFO - "browser-start": 60000,
17:31:51 INFO - "pageCompleteCheck": 300000,
17:31:51 INFO - "page-complete-check": 300000
17:31:51 INFO - },
17:31:51 INFO - "v": 2,
17:31:51 INFO - "verbose": 2,
17:31:51 INFO - "resultDir": "/Users/sdonner/mozilla-source/mozilla-unified/testing/mozharness/build/blobber_upload_dir/browsertime-results/raptor-tp6m-amazon-geckoview-cold",
17:31:51 INFO - "result-dir": "/Users/sdonner/mozilla-source/mozilla-unified/testing/mozharness/build/blobber_upload_dir/browsertime-results/raptor-tp6m-amazon-geckoview-cold",
17:31:51 INFO - "n": 15,
17:31:51 INFO - "screenshotParams": {
17:31:51 INFO - "type": "jpg",
17:31:51 INFO - "png": {
17:31:51 INFO - "compressionLevel": 6
17:31:51 INFO - },
17:31:51 INFO - "jpg": {
17:31:51 INFO - "quality": 80
17:31:51 INFO - },
17:31:51 INFO - "maxSize": 2000
17:31:51 INFO - },
17:31:51 INFO - "screenshot-params": {
17:31:51 INFO - "type": "jpg",
17:31:51 INFO - "png": {
17:31:51 INFO - "compression-level": 6
17:31:51 INFO - },
17:31:51 INFO - "jpg": {
17:31:51 INFO - "quality": 80
17:31:51 INFO - },
17:31:51 INFO - "max-size": 2000
17:31:51 INFO - },
17:31:51 INFO - "pageCompleteWaitTime": 5000,
17:31:51 INFO - "page-complete-wait-time": 5000,
17:31:51 INFO - "c": "native",
17:31:51 INFO - "percentiles": [
17:31:51 INFO - 0,
17:31:51 INFO - 10,
17:31:51 INFO - 90,
17:31:51 INFO - 99,
17:31:51 INFO - 100
17:31:51 INFO - ],
17:31:51 INFO - "decimals": 0,
17:31:51 INFO - "xvfbParams": {
17:31:51 INFO - "display": 99
17:31:51 INFO - },
17:31:51 INFO - "xvfb-params": {
17:31:51 INFO - "display": 99
17:31:51 INFO - },
17:31:51 INFO - "preURLDelay": 1500,
17:31:51 INFO - "pre-url-delay": 1500,
17:31:51 INFO - "silent": 0,
17:31:51 INFO - "q": 0,
17:31:51 INFO - "$0": "../../../tools/browsertime/node_modules/browsertime/bin/browsertime.js"
17:31:51 INFO - }
17:31:51 INFO - [2019-11-27 12:31:51] VERBOSE: [browsertime] appConstants is a new-style script object.
17:31:51 INFO - [2019-11-27 12:31:51] VERBOSE: [browsertime] asyncAppConstants is a new-style script object.
17:31:51 INFO - [2019-11-27 12:31:51] INFO: [browsertime] Running tests using Firefox - 15 iteration(s)
17:31:51 INFO - 1574875911454 webdriver::command WARN You are using deprecated legacy session negotiation patterns (desiredCapabilities/requiredCapabilities), see https://developer.mozilla.org/en-US/docs/Web/WebDriver/Capabilities#Legacy
17:31:51 INFO - options: FirefoxOptions { binary: Some("/Users/sdonner/.mozbuild/node/bin/node"), profile: Some(Profile { path: "/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/rust_mozprofiletPPMPV", temp_dir: Some(TempDir { path: "/var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/rust_mozprofiletPPMPV" }), prefs: None, user_prefs: None }), args: Some(["-no-remote"]), log: LogOptions { level: None }, prefs: [], android: Some(AndroidOptions { activity: Some("org.mozilla.geckoview_example.GeckoViewActivity"), device_serial: None, package: "org.mozilla.geckoview_example", intent_arguments: Some([]) }) }
17:31:51 INFO - capabilities: {"browserName": String("firefox"), "pageLoadStrategy": String("normal")}
17:31:51 INFO - 1574875911462 mozdevice WARN adb server response contained hexstring length 98 and message length was 107 and message was "ZY322MQ6ZJ device usb:338690048X product:cedric model:Moto_G__5_ device:cedric transport_id:19\n"
17:31:51 INFO - 1574875911462 mozdevice INFO execute_host_shell_command: >> "pm clear org.mozilla.geckoview_example"
17:31:52 INFO - 1574875912450 mozdevice INFO execute_host_shell_command: << "Success\n"
17:31:52 INFO - 1574875912450 mozdevice INFO execute_host_shell_command: >> "pm grant org.mozilla.geckoview_example android.permission.WRITE_EXTERNAL_STORAGE"
17:31:53 INFO - 1574875913377 mozdevice INFO execute_host_shell_command: << ""
17:31:53 INFO - 1574875913377 mozdevice INFO execute_host_shell_command: >> "pm grant org.mozilla.geckoview_example android.permission.READ_EXTERNAL_STORAGE"
17:31:54 INFO - 1574875914296 mozdevice INFO execute_host_shell_command: << ""
17:31:54 INFO - 1574875914298 geckodriver::marionette INFO remote_port 2829
17:31:54 INFO - 1574875914303 mozdevice INFO execute_host_shell_command: >> "rm -rf /mnt/sdcard/geckodriver-profile"
17:31:54 INFO - 1574875914345 mozdevice INFO execute_host_shell_command: << ""
17:31:54 INFO - 1574875914345 geckodriver::marionette INFO Pushing /var/folders/tn/jmgsfg315sd73yn_dnrf66c80000gn/T/rust_mozprofiletPPMPV to /mnt/sdcard/geckodriver-profile
17:31:54 INFO - 1574875914394 mozdevice INFO execute_host_shell_command: >> "am start -W -n org.mozilla.geckoview_example/org.mozilla.geckoview_example.GeckoViewActivity --es args -marionette\\ -profile\\ /mnt/sdcard/geckodriver-profile"
17:31:55 INFO - 1574875915961 mozdevice INFO execute_host_shell_command: << "Starting: Intent { cmp=org.mozilla.geckoview_example/.GeckoViewActivity (has extras) }\nStatus: ok\nActivity: org.mozilla.geckoview_example/.GeckoViewActivity\nThisTime: 623\nTotalTime: 623\nWaitTime: 637\nComplete\n"
17:31:55 INFO - 1574875915961 geckodriver::marionette INFO Forwarding local port tcp:50570 to remote_port tcp:2829
17:31:55 INFO - 1574875915962 geckodriver::marionette INFO handshake, timeout is None
17:31:55 INFO - 1574875915963 geckodriver::marionette INFO Not connected, sleeping 120ms!
17:31:56 INFO - 1574875916087 geckodriver::marionette INFO handshake, timeout is None
17:31:56 INFO - 1574875916088 geckodriver::marionette INFO Not connected, sleeping 240ms!
17:31:56 INFO - 1574875916332 geckodriver::marionette INFO handshake, timeout is None
17:31:56 INFO - 1574875916333 geckodriver::marionette INFO Not connected, sleeping 480ms!
17:31:56 INFO - 1574875916816 geckodriver::marionette INFO handshake, timeout is None
17:31:56 INFO - 1574875916817 geckodriver::marionette INFO Not connected, sleeping 960ms!
17:31:57 INFO - 1574875917780 geckodriver::marionette INFO handshake, timeout is None
17:31:57 INFO - 1574875917781 geckodriver::marionette INFO Not connected, sleeping 960ms!
17:31:58 INFO - 1574875918745 geckodriver::marionette INFO handshake, timeout is None
17:31:58 INFO - 1574875918746 geckodriver::marionette INFO Not connected, sleeping 960ms!
17:31:59 INFO - 1574875919707 geckodriver::marionette INFO handshake, timeout is None
17:32:01 INFO - [2019-11-27 12:32:01] INFO: [browsertime] Starting a browsertime pageload
17:32:01 INFO - [2019-11-27 12:32:01] INFO: [browsertime] Waiting for 5000 ms (foreground_delay)
17:32:06 INFO - [2019-11-27 12:32:06] INFO: [browsertime] Navigating to about:blank
17:32:06 INFO - [2019-11-27 12:32:06] INFO: [browsertime.command.measure] Navigating to url about:blank iteration 1
17:32:07 INFO - [2019-11-27 12:32:07] DEBUG: [browsertime] Waiting for script pageCompleteCheck at most 300000 ms
17:32:07 INFO - [2019-11-27 12:32:07] VERBOSE: [browsertime] Waiting for script
17:32:07 INFO - return (function(waitTime) {
17:32:07 INFO - try {
17:32:07 INFO - var end = window.performance.timing.loadEventEnd;
17:32:07 INFO - return end > 0;
17:32:07 INFO - } catch(e) {
17:32:07 INFO - return true;
17:32:07 INFO - }
17:32:07 INFO - })(arguments[arguments.length - 1]);
17:32:07 INFO - [2019-11-27 12:32:07] DEBUG: [browsertime] Waiting after load event for 5000 ms.
17:32:12 INFO - [2019-11-27 12:32:12] INFO: [browsertime] Waiting for 30000 ms (post_startup_delay)
17:32:42 INFO - [2019-11-27 12:32:42] INFO: [browsertime] Cycle 0, waiting for 1000 ms
17:32:43 INFO - [2019-11-27 12:32:43] INFO: [browsertime] Cycle 0, starting the measure
17:32:43 INFO - [2019-11-27 12:32:43] INFO: [browsertime.command.measure] Testing url https://www.amazon.com iteration 1
17:32:44 INFO - [2019-11-27 12:32:44] DEBUG: [browsertime] Waiting for script pageCompleteCheck at most 300000 ms
17:32:44 INFO - [2019-11-27 12:32:44] VERBOSE: [browsertime] Waiting for script
17:32:44 INFO - return (function(waitTime) {
17:32:44 INFO - try {
17:32:44 INFO - var end = window.performance.timing.loadEventEnd;
17:32:44 INFO - return end > 0;
17:32:44 INFO - } catch(e) {
17:32:44 INFO - return true;
17:32:44 INFO - }
17:32:44 INFO - })(arguments[arguments.length - 1]);
17:32:44 INFO - [2019-11-27 12:32:44] DEBUG: [browsertime] Waiting after load event for 5000 ms.
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime.command.measure] Collecting metrics
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script appConstants with privilege.
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing privileged script appConstants
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script asyncAppConstants with privilege.
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing privileged async script asyncAppConstants
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script userAgent with privilege.
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing privileged script userAgent
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script windowSize with privilege.
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing privileged script windowSize
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script documentHeight
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script documentSize
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script documentTitle
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script documentWidth
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script navigationStartTime
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script nextHopProtocol
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script responsive
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script url
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script visualElements
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script firstPaint
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script loadEventEnd
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script navigationTiming
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script pageTimings
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script paintTiming
17:32:49 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script rumSpeedIndex
17:32:50 INFO - [2019-11-27 12:32:49] VERBOSE: [browsertime] Executing script serverTimings
17:32:50 INFO - [2019-11-27 12:32:50] VERBOSE: [browsertime] Executing script timeToContentfulPaint
17:32:50 INFO - [2019-11-27 12:32:50] VERBOSE: [browsertime] Executing script timeToDomContentFlushed
17:32:50 INFO - [2019-11-27 12:32:50] VERBOSE: [browsertime] Executing script timeToFirstInteractive
17:32:50 INFO - [2019-11-27 12:32:50] VERBOSE: [browsertime] Executing script userTimings
17:32:50 INFO - [2019-11-27 12:32:50] INFO: [browsertime] Browsertime pageload ended.
17:32:50 INFO - [2019-11-27 12:32:50] DEBUG: [browsertime] Telling browser to quit.
17:32:50 INFO - 1574875970156 geckodriver::marionette WARN delete_session called Some(Session { id: "0fd588f5-87a1-4337-8d31-abc1685fcde4" })
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment