builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0071 starttime: 1475666104.62 results: success (0) buildid: 20161005021900 builduid: dfa5dcaee758429c8384cc89f0ce2393 revision: c4a811b0531e3bf4279a1e19a3e4451f2e416719 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.622871) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.623282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.623570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.648115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.648484) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgvSUvHndU/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-05 04:15:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.0M=0s 2016-10-05 04:15:04 (39.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100675 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.770869) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.771183) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgvSUvHndU/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.027959 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:04.819949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-05 04:15:04.820302) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c4a811b0531e3bf4279a1e19a3e4451f2e416719 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c4a811b0531e3bf4279a1e19a3e4451f2e416719 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgvSUvHndU/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-05 04:15:04,892 truncating revision to first 12 chars 2016-10-05 04:15:04,892 Setting DEBUG logging. 2016-10-05 04:15:04,892 attempt 1/10 2016-10-05 04:15:04,892 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c4a811b0531e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 04:15:06,942 unpacking tar archive at: fx-team-c4a811b0531e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.295417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-05 04:15:07.138453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:07.138916) ========= script_repo_revision: c4a811b0531e3bf4279a1e19a3e4451f2e416719 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:07.139416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:07.139740) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 04:15:07.158237) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-05 04:15:07.158687) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgvSUvHndU/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:15:07 INFO - MultiFileLogger online at 20161005 04:15:07 in /builds/slave/test 04:15:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:15:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:15:07 INFO - {'append_to_log': False, 04:15:07 INFO - 'base_work_dir': '/builds/slave/test', 04:15:07 INFO - 'blob_upload_branch': 'Fx-Team', 04:15:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:15:07 INFO - 'branch': 'Fx-Team', 04:15:07 INFO - 'buildbot_json_path': 'buildprops.json', 04:15:07 INFO - 'config_files': ('talos/mac_config.py',), 04:15:07 INFO - 'default_actions': ('clobber', 04:15:07 INFO - 'read-buildbot-config', 04:15:07 INFO - 'download-and-extract', 04:15:07 INFO - 'populate-webroot', 04:15:07 INFO - 'create-virtualenv', 04:15:07 INFO - 'install', 04:15:07 INFO - 'run-tests'), 04:15:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:15:07 INFO - 'download_minidump_stackwalk': True, 04:15:07 INFO - 'download_symbols': 'ondemand', 04:15:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:15:07 INFO - 'tooltool.py': '/tools/tooltool.py', 04:15:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:15:07 INFO - '/tools/misc-python/virtualenv.py')}, 04:15:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:15:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:15:07 INFO - 'installer_path': 'installer.exe', 04:15:07 INFO - 'log_level': 'info', 04:15:07 INFO - 'log_name': 'talos', 04:15:07 INFO - 'log_to_console': True, 04:15:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:15:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:15:07 INFO - 'opt_config_files': (), 04:15:07 INFO - 'pip_index': False, 04:15:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:07 INFO - 'cmd': ('bash', 04:15:07 INFO - '-c', 04:15:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:07 INFO - 'enabled': True, 04:15:07 INFO - 'halt_on_failure': False, 04:15:07 INFO - 'name': 'check_screen_resolution'},), 04:15:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:07 INFO - 'cmd': ('bash', 04:15:07 INFO - '-c', 04:15:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:07 INFO - 'enabled': True, 04:15:07 INFO - 'halt_on_failure': False, 04:15:07 INFO - 'name': 'check_screen_resolution'},), 04:15:07 INFO - 'run_cmd_checks_enabled': True, 04:15:07 INFO - 'sps_profile': False, 04:15:07 INFO - 'sps_profile_interval': 0, 04:15:07 INFO - 'suite': 'dromaeojs', 04:15:07 INFO - 'system_bits': '32', 04:15:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:15:07 INFO - 'title': 't-yosemite-r7-0071', 04:15:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:15:07 INFO - 'use_talos_json': True, 04:15:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:15:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:15:07 INFO - 'work_dir': 'build'} 04:15:07 INFO - [mozharness: 2016-10-05 11:15:07.361377Z] Running clobber step. 04:15:07 INFO - Running pre-action listener: _resource_record_pre_action 04:15:07 INFO - Running main action method: clobber 04:15:07 INFO - rmtree: /builds/slave/test/build 04:15:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:15:09 INFO - Running post-action listener: _resource_record_post_action 04:15:09 INFO - [mozharness: 2016-10-05 11:15:09.478739Z] Finished clobber step (success) 04:15:09 INFO - [mozharness: 2016-10-05 11:15:09.478973Z] Running read-buildbot-config step. 04:15:09 INFO - Running pre-action listener: _resource_record_pre_action 04:15:09 INFO - Running main action method: read_buildbot_config 04:15:09 INFO - Using buildbot properties: 04:15:09 INFO - { 04:15:09 INFO - "project": "", 04:15:09 INFO - "product": "firefox", 04:15:09 INFO - "script_repo_revision": "production", 04:15:09 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:15:09 INFO - "repository": "", 04:15:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:15:09 INFO - "buildid": "20161005021900", 04:15:09 INFO - "pgo_build": "False", 04:15:09 INFO - "basedir": "/builds/slave/test", 04:15:09 INFO - "buildnumber": 10, 04:15:09 INFO - "slavename": "t-yosemite-r7-0071", 04:15:09 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:15:09 INFO - "platform": "yosemite_r7", 04:15:09 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:15:09 INFO - "branch": "fx-team", 04:15:09 INFO - "repo_path": "integration/fx-team", 04:15:09 INFO - "revision": "c4a811b0531e3bf4279a1e19a3e4451f2e416719", 04:15:09 INFO - "stage_platform": "macosx64", 04:15:09 INFO - "builduid": "dfa5dcaee758429c8384cc89f0ce2393", 04:15:09 INFO - "slavebuilddir": "test" 04:15:09 INFO - } 04:15:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:15:09 INFO - Running post-action listener: _resource_record_post_action 04:15:09 INFO - [mozharness: 2016-10-05 11:15:09.484837Z] Finished read-buildbot-config step (success) 04:15:09 INFO - [mozharness: 2016-10-05 11:15:09.485067Z] Running download-and-extract step. 04:15:09 INFO - Running pre-action listener: _resource_record_pre_action 04:15:09 INFO - Running main action method: download_and_extract 04:15:09 INFO - mkdir: /builds/slave/test/build/tests 04:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:09 INFO - trying https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:15:09 INFO - Downloading https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:15:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 04:15:10 INFO - Downloaded 1199 bytes. 04:15:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:15:10 INFO - Using the following test package requirements: 04:15:10 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:15:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:15:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:15:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'jsshell-mac.zip'], 04:15:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:15:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:15:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:15:10 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:15:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:15:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:15:10 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:15:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:15:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:15:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:15:10 INFO - Fetch https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:15:10 INFO - Http code: 200 04:15:10 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 04:15:10 INFO - x-amz-cf-id: F4tnJg5Wn9s2_5Op55BmbzxxkJNo_PXOP7ORYiz-2yTBJvUy0NqlcQ== 04:15:10 INFO - x-amz-version-id: MXQ6ZpVoUaWU6vCsYFIQbPpX1waC2Dca 04:15:11 INFO - Expected file size: 16476867 04:15:11 INFO - Obtained file size: 16476867 04:15:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:15:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:15:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:15:17 INFO - Fetch https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:15:18 INFO - Http code: 200 04:15:18 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 04:15:18 INFO - x-amz-cf-id: Fv6Hy36UKKhr3YETVyv2NVgaT6Ko1_1nx2gROFcfn4a4WZKkVMrl4A== 04:15:18 INFO - x-amz-version-id: MXQ6ZpVoUaWU6vCsYFIQbPpX1waC2Dca 04:15:18 INFO - Expected file size: 16476867 04:15:18 INFO - Obtained file size: 16476867 04:15:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:15:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:15:25 INFO - Fetch https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:15:26 INFO - Http code: 200 04:15:26 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 04:15:26 INFO - x-amz-cf-id: _DnRAhHrIl88W66RKEwXv6KhLh43pnifGM1JddmFO-4ni4y9pRDJjg== 04:15:26 INFO - x-amz-version-id: Bov62Q9_5wVb3c_9bJT1YrcKA7ObPVkA 04:15:26 INFO - Expected file size: 14409006 04:15:26 INFO - Obtained file size: 14409006 04:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:26 INFO - trying https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:15:26 INFO - Downloading https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 04:15:29 INFO - Downloaded 111714764 bytes. 04:15:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:15:29 INFO - mkdir: /builds/slave/test/properties 04:15:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:15:29 INFO - Writing to file /builds/slave/test/properties/build_url 04:15:29 INFO - Contents: 04:15:29 INFO - build_url:https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:15:30 INFO - Running post-action listener: _resource_record_post_action 04:15:30 INFO - Running post-action listener: set_extra_try_arguments 04:15:30 INFO - [mozharness: 2016-10-05 11:15:30.127611Z] Finished download-and-extract step (success) 04:15:30 INFO - [mozharness: 2016-10-05 11:15:30.127728Z] Running populate-webroot step. 04:15:30 INFO - Running pre-action listener: _resource_record_pre_action 04:15:30 INFO - Running main action method: populate_webroot 04:15:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:15:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:15:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:15:30 INFO - '--tppagecycles', 04:15:30 INFO - '7'], 04:15:30 INFO - 'tests': ['tsvgm']}}, 04:15:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:15:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:15:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:15:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:15:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['damp', 'tps']}, 04:15:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'tests': ['damp', 'tps']}, 04:15:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['dromaeo_dom']}, 04:15:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:15:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['basic_compositor_video']}, 04:15:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:15:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['a11yr', 04:15:30 INFO - 'ts_paint', 04:15:30 INFO - 'tpaint', 04:15:30 INFO - 'sessionrestore', 04:15:30 INFO - 'sessionrestore_no_auto_restore', 04:15:30 INFO - 'tabpaint']}, 04:15:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:15:30 INFO - 'ts_paint', 04:15:30 INFO - 'tpaint', 04:15:30 INFO - 'sessionrestore', 04:15:30 INFO - 'sessionrestore_no_auto_restore', 04:15:30 INFO - 'tabpaint']}, 04:15:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:15:30 INFO - 'ts_paint', 04:15:30 INFO - 'tpaint', 04:15:30 INFO - 'sessionrestore', 04:15:30 INFO - 'sessionrestore_no_auto_restore', 04:15:30 INFO - 'tabpaint']}, 04:15:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:15:30 INFO - 'ts_paint', 04:15:30 INFO - 'tpaint', 04:15:30 INFO - 'sessionrestore', 04:15:30 INFO - 'sessionrestore_no_auto_restore', 04:15:30 INFO - 'tabpaint']}, 04:15:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['a11yr', 04:15:30 INFO - 'ts_paint', 04:15:30 INFO - 'tpaint', 04:15:30 INFO - 'sessionrestore', 04:15:30 INFO - 'sessionrestore_no_auto_restore', 04:15:30 INFO - 'tabpaint']}, 04:15:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['a11yr', 04:15:30 INFO - 'ts_paint', 04:15:30 INFO - 'tpaint', 04:15:30 INFO - 'sessionrestore', 04:15:30 INFO - 'sessionrestore_no_auto_restore', 04:15:30 INFO - 'tabpaint']}, 04:15:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['tsvgx', 04:15:30 INFO - 'tsvgr_opacity', 04:15:30 INFO - 'tart', 04:15:30 INFO - 'tscrollx', 04:15:30 INFO - 'cart']}, 04:15:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:15:30 INFO - 'tsvgr_opacity', 04:15:30 INFO - 'tart', 04:15:30 INFO - 'tscrollx', 04:15:30 INFO - 'cart']}, 04:15:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'talos_options': ['--disable-e10s'], 04:15:30 INFO - 'tests': ['tp5o']}, 04:15:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'tests': ['tp5o']}, 04:15:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'talos_options': ['--disable-e10s', 04:15:30 INFO - '--xperf_path', 04:15:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:30 INFO - 'tests': ['tp5n']}, 04:15:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:30 INFO - 'talos_options': ['--xperf_path', 04:15:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:30 INFO - 'tests': ['tp5n']}}, 04:15:30 INFO - 'talos.zip': {'path': '', 04:15:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:15:30 INFO - Running post-action listener: _resource_record_post_action 04:15:30 INFO - [mozharness: 2016-10-05 11:15:30.146377Z] Finished populate-webroot step (success) 04:15:30 INFO - [mozharness: 2016-10-05 11:15:30.146496Z] Running create-virtualenv step. 04:15:30 INFO - Running pre-action listener: _resource_record_pre_action 04:15:30 INFO - Running main action method: create_virtualenv 04:15:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:15:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:15:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:15:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:15:30 INFO - Using real prefix '/tools/python27' 04:15:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:15:30 INFO - Installing distribute.............................................................................................................................................................................................done. 04:15:33 INFO - Installing pip.................done. 04:15:33 INFO - Return code: 0 04:15:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:15:34 INFO - Reading from file tmpfile_stdout 04:15:34 INFO - Output received: 04:15:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:15:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:15:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:34 INFO - 'HOME': '/Users/cltbld', 04:15:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:34 INFO - 'LOGNAME': 'cltbld', 04:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:34 INFO - 'MOZ_NO_REMOTE': '1', 04:15:34 INFO - 'NO_EM_RESTART': '1', 04:15:34 INFO - 'PAGER': '/bin/cat', 04:15:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:34 INFO - 'PWD': '/builds/slave/test', 04:15:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:34 INFO - 'SHELL': '/bin/bash', 04:15:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:34 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:34 INFO - 'USER': 'cltbld', 04:15:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:34 INFO - 'XPC_FLAGS': '0x0', 04:15:34 INFO - 'XPC_SERVICE_NAME': '0', 04:15:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:15:34 INFO - Cleaning up... 04:15:34 INFO - Return code: 0 04:15:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:15:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:34 INFO - 'HOME': '/Users/cltbld', 04:15:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:34 INFO - 'LOGNAME': 'cltbld', 04:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:34 INFO - 'MOZ_NO_REMOTE': '1', 04:15:34 INFO - 'NO_EM_RESTART': '1', 04:15:34 INFO - 'PAGER': '/bin/cat', 04:15:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:34 INFO - 'PWD': '/builds/slave/test', 04:15:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:34 INFO - 'SHELL': '/bin/bash', 04:15:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:34 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:34 INFO - 'USER': 'cltbld', 04:15:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:34 INFO - 'XPC_FLAGS': '0x0', 04:15:34 INFO - 'XPC_SERVICE_NAME': '0', 04:15:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:34 INFO - Downloading/unpacking psutil>=3.1.1 04:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:15:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:38 INFO - Installing collected packages: psutil 04:15:38 INFO - Running setup.py install for psutil 04:15:38 INFO - building 'psutil._psutil_osx' extension 04:15:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:15:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:15:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:15:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:15:38 INFO - building 'psutil._psutil_posix' extension 04:15:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:15:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:15:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:15:38 INFO - ^ 04:15:39 INFO - 1 warning generated. 04:15:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:15:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:39 INFO - Successfully installed psutil 04:15:39 INFO - Cleaning up... 04:15:39 INFO - Return code: 0 04:15:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:15:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:39 INFO - 'HOME': '/Users/cltbld', 04:15:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:39 INFO - 'LOGNAME': 'cltbld', 04:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:39 INFO - 'MOZ_NO_REMOTE': '1', 04:15:39 INFO - 'NO_EM_RESTART': '1', 04:15:39 INFO - 'PAGER': '/bin/cat', 04:15:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:39 INFO - 'PWD': '/builds/slave/test', 04:15:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:39 INFO - 'SHELL': '/bin/bash', 04:15:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:39 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:39 INFO - 'USER': 'cltbld', 04:15:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:39 INFO - 'XPC_FLAGS': '0x0', 04:15:39 INFO - 'XPC_SERVICE_NAME': '0', 04:15:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:15:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:15:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:15:42 INFO - Installing collected packages: mozsystemmonitor 04:15:42 INFO - Running setup.py install for mozsystemmonitor 04:15:42 INFO - Successfully installed mozsystemmonitor 04:15:42 INFO - Cleaning up... 04:15:42 INFO - Return code: 0 04:15:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:15:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:15:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:42 INFO - 'HOME': '/Users/cltbld', 04:15:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:42 INFO - 'LOGNAME': 'cltbld', 04:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:42 INFO - 'MOZ_NO_REMOTE': '1', 04:15:42 INFO - 'NO_EM_RESTART': '1', 04:15:42 INFO - 'PAGER': '/bin/cat', 04:15:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:42 INFO - 'PWD': '/builds/slave/test', 04:15:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:42 INFO - 'SHELL': '/bin/bash', 04:15:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:42 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:42 INFO - 'USER': 'cltbld', 04:15:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:42 INFO - 'XPC_FLAGS': '0x0', 04:15:42 INFO - 'XPC_SERVICE_NAME': '0', 04:15:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:42 INFO - Downloading/unpacking jsonschema==2.5.1 04:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:15:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:45 INFO - Downloading functools32-3.2.3-2.tar.gz 04:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:15:45 INFO - warning: no files found matching '*.txt' 04:15:45 INFO - no previously-included directories found matching 'build' 04:15:45 INFO - no previously-included directories found matching 'dist' 04:15:45 INFO - no previously-included directories found matching '.git*' 04:15:45 INFO - Installing collected packages: jsonschema, functools32 04:15:45 INFO - Running setup.py install for functools32 04:15:45 INFO - warning: no files found matching '*.txt' 04:15:45 INFO - no previously-included directories found matching 'build' 04:15:45 INFO - no previously-included directories found matching 'dist' 04:15:45 INFO - no previously-included directories found matching '.git*' 04:15:45 INFO - Successfully installed jsonschema functools32 04:15:45 INFO - Cleaning up... 04:15:46 INFO - Return code: 0 04:15:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:46 INFO - 'HOME': '/Users/cltbld', 04:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:46 INFO - 'LOGNAME': 'cltbld', 04:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:46 INFO - 'MOZ_NO_REMOTE': '1', 04:15:46 INFO - 'NO_EM_RESTART': '1', 04:15:46 INFO - 'PAGER': '/bin/cat', 04:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:46 INFO - 'PWD': '/builds/slave/test', 04:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:46 INFO - 'SHELL': '/bin/bash', 04:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:46 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:46 INFO - 'USER': 'cltbld', 04:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:46 INFO - 'XPC_FLAGS': '0x0', 04:15:46 INFO - 'XPC_SERVICE_NAME': '0', 04:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:15:46 INFO - Cleaning up... 04:15:46 INFO - Return code: 0 04:15:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:46 INFO - 'HOME': '/Users/cltbld', 04:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:46 INFO - 'LOGNAME': 'cltbld', 04:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:46 INFO - 'MOZ_NO_REMOTE': '1', 04:15:46 INFO - 'NO_EM_RESTART': '1', 04:15:46 INFO - 'PAGER': '/bin/cat', 04:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:46 INFO - 'PWD': '/builds/slave/test', 04:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:46 INFO - 'SHELL': '/bin/bash', 04:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:46 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:46 INFO - 'USER': 'cltbld', 04:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:46 INFO - 'XPC_FLAGS': '0x0', 04:15:46 INFO - 'XPC_SERVICE_NAME': '0', 04:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:49 INFO - Downloading blobuploader-1.2.4.tar.gz 04:15:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:15:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:15:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:50 INFO - Downloading docopt-0.6.1.tar.gz 04:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:15:50 INFO - Installing collected packages: blobuploader, requests, docopt 04:15:50 INFO - Running setup.py install for blobuploader 04:15:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:15:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:15:50 INFO - Running setup.py install for requests 04:15:50 INFO - Running setup.py install for docopt 04:15:51 INFO - Successfully installed blobuploader requests docopt 04:15:51 INFO - Cleaning up... 04:15:51 INFO - Return code: 0 04:15:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:51 INFO - 'HOME': '/Users/cltbld', 04:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:51 INFO - 'LOGNAME': 'cltbld', 04:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:51 INFO - 'MOZ_NO_REMOTE': '1', 04:15:51 INFO - 'NO_EM_RESTART': '1', 04:15:51 INFO - 'PAGER': '/bin/cat', 04:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:51 INFO - 'PWD': '/builds/slave/test', 04:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:51 INFO - 'SHELL': '/bin/bash', 04:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:51 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:51 INFO - 'USER': 'cltbld', 04:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:51 INFO - 'XPC_FLAGS': '0x0', 04:15:51 INFO - 'XPC_SERVICE_NAME': '0', 04:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-92Ynb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ShaJ3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-QE7jHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-yTFsFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:51 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Ck_xRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-F0JRBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-fRMAh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-_ffAsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-7j5HUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-eAR4rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-sodsob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-_g3TWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-bTLI9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-lGcuKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-uXK_TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-TA4VIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-tO47jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:15:53 INFO - Running setup.py install for manifestparser 04:15:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:15:53 INFO - Running setup.py install for mozcrash 04:15:53 INFO - Running setup.py install for mozdebug 04:15:53 INFO - Running setup.py install for mozdevice 04:15:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:15:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:15:53 INFO - Running setup.py install for mozfile 04:15:54 INFO - Running setup.py install for mozhttpd 04:15:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Running setup.py install for mozinfo 04:15:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Running setup.py install for mozInstall 04:15:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Running setup.py install for mozleak 04:15:54 INFO - Running setup.py install for mozlog 04:15:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Running setup.py install for moznetwork 04:15:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:15:54 INFO - Running setup.py install for mozprocess 04:15:55 INFO - Running setup.py install for mozprofile 04:15:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:15:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:15:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:15:55 INFO - Running setup.py install for mozrunner 04:15:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:15:55 INFO - Running setup.py install for mozscreenshot 04:15:55 INFO - Running setup.py install for moztest 04:15:55 INFO - Running setup.py install for mozversion 04:15:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:15:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:15:55 INFO - Cleaning up... 04:15:55 INFO - Return code: 0 04:15:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:15:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:15:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:55 INFO - 'HOME': '/Users/cltbld', 04:15:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:55 INFO - 'LOGNAME': 'cltbld', 04:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:55 INFO - 'MOZ_NO_REMOTE': '1', 04:15:55 INFO - 'NO_EM_RESTART': '1', 04:15:55 INFO - 'PAGER': '/bin/cat', 04:15:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:55 INFO - 'PWD': '/builds/slave/test', 04:15:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:55 INFO - 'SHELL': '/bin/bash', 04:15:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:15:55 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:15:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:55 INFO - 'USER': 'cltbld', 04:15:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:55 INFO - 'XPC_FLAGS': '0x0', 04:15:55 INFO - 'XPC_SERVICE_NAME': '0', 04:15:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8VX3w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-u_SMVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-FFBj3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Bg9pzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-oy4BD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-405R04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-fpwfEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-6gETUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:56 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-NwKWLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-wTf1OK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-oKOSKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xJgiZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-NiQIvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-GRlWiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-HuGOfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-jYAFFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:57 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8NvWfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:15:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:00 INFO - Downloading blessings-1.6.tar.gz 04:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:16:00 INFO - Installing collected packages: blessings 04:16:00 INFO - Running setup.py install for blessings 04:16:00 INFO - Successfully installed blessings 04:16:00 INFO - Cleaning up... 04:16:00 INFO - Return code: 0 04:16:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:01 INFO - Reading from file tmpfile_stdout 04:16:01 INFO - Current package versions: 04:16:01 INFO - blessings == 1.6 04:16:01 INFO - blobuploader == 1.2.4 04:16:01 INFO - docopt == 0.6.1 04:16:01 INFO - functools32 == 3.2.3-2 04:16:01 INFO - jsonschema == 2.5.1 04:16:01 INFO - manifestparser == 1.1 04:16:01 INFO - mozInstall == 1.12 04:16:01 INFO - mozcrash == 0.17 04:16:01 INFO - mozdebug == 0.1 04:16:01 INFO - mozdevice == 0.48 04:16:01 INFO - mozfile == 1.2 04:16:01 INFO - mozhttpd == 0.7 04:16:01 INFO - mozinfo == 0.9 04:16:01 INFO - mozleak == 0.1 04:16:01 INFO - mozlog == 3.4 04:16:01 INFO - moznetwork == 0.27 04:16:01 INFO - mozprocess == 0.23 04:16:01 INFO - mozprofile == 0.28 04:16:01 INFO - mozrunner == 6.12 04:16:01 INFO - mozscreenshot == 0.1 04:16:01 INFO - mozsystemmonitor == 0.3 04:16:01 INFO - moztest == 0.7 04:16:01 INFO - mozversion == 1.4 04:16:01 INFO - psutil == 3.1.1 04:16:01 INFO - requests == 1.2.3 04:16:01 INFO - wsgiref == 0.1.2 04:16:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:16:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:16:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:01 INFO - 'HOME': '/Users/cltbld', 04:16:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:01 INFO - 'LOGNAME': 'cltbld', 04:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:01 INFO - 'MOZ_NO_REMOTE': '1', 04:16:01 INFO - 'NO_EM_RESTART': '1', 04:16:01 INFO - 'PAGER': '/bin/cat', 04:16:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:01 INFO - 'PWD': '/builds/slave/test', 04:16:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:01 INFO - 'SHELL': '/bin/bash', 04:16:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:16:01 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:16:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:01 INFO - 'USER': 'cltbld', 04:16:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:01 INFO - 'XPC_FLAGS': '0x0', 04:16:01 INFO - 'XPC_SERVICE_NAME': '0', 04:16:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:16:01 INFO - Cleaning up... 04:16:01 INFO - Return code: 0 04:16:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a64490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50473d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:16:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:16:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:16:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:02 INFO - 'HOME': '/Users/cltbld', 04:16:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:02 INFO - 'LOGNAME': 'cltbld', 04:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:02 INFO - 'MOZ_NO_REMOTE': '1', 04:16:02 INFO - 'NO_EM_RESTART': '1', 04:16:02 INFO - 'PAGER': '/bin/cat', 04:16:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:02 INFO - 'PWD': '/builds/slave/test', 04:16:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:02 INFO - 'SHELL': '/bin/bash', 04:16:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:16:02 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:16:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:02 INFO - 'USER': 'cltbld', 04:16:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:02 INFO - 'XPC_FLAGS': '0x0', 04:16:02 INFO - 'XPC_SERVICE_NAME': '0', 04:16:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:16:02 INFO - Cleaning up... 04:16:02 INFO - Return code: 0 04:16:02 INFO - Running post-action listener: _resource_record_post_action 04:16:02 INFO - Running post-action listener: _start_resource_monitoring 04:16:02 INFO - Starting resource monitoring. 04:16:02 INFO - [mozharness: 2016-10-05 11:16:02.819200Z] Finished create-virtualenv step (success) 04:16:02 INFO - [mozharness: 2016-10-05 11:16:02.820159Z] Running install step. 04:16:02 INFO - Running pre-action listener: _resource_record_pre_action 04:16:02 INFO - Running main action method: install 04:16:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:03 INFO - Reading from file tmpfile_stdout 04:16:03 INFO - Detecting whether we're running mozinstall >=1.0... 04:16:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:16:03 INFO - Reading from file tmpfile_stdout 04:16:03 INFO - Output received: 04:16:03 INFO - Usage: mozinstall [options] installer 04:16:03 INFO - Options: 04:16:03 INFO - -h, --help show this help message and exit 04:16:03 INFO - -d DEST, --destination=DEST 04:16:03 INFO - Directory to install application into. [default: 04:16:03 INFO - "/builds/slave/test"] 04:16:03 INFO - --app=APP Application being installed. [default: firefox] 04:16:03 INFO - mkdir: /builds/slave/test/build/application 04:16:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:16:37 INFO - Reading from file tmpfile_stdout 04:16:37 INFO - Output received: 04:16:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:16:37 INFO - Running post-action listener: _resource_record_post_action 04:16:37 INFO - [mozharness: 2016-10-05 11:16:37.280429Z] Finished install step (success) 04:16:37 INFO - [mozharness: 2016-10-05 11:16:37.280580Z] Running run-tests step. 04:16:37 INFO - Running pre-action listener: _resource_record_pre_action 04:16:37 INFO - Running main action method: run_tests 04:16:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:16:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:16:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:16:37 INFO - 2016-10-05 04:16:37.295 screenresolution[1638:7596] starting screenresolution argv=screenresolution get 04:16:37 INFO - 2016-10-05 04:16:37.297 screenresolution[1638:7596] Display 0: 1600x1200x32@60 04:16:37 INFO - 2016-10-05 04:16:37.303 screenresolution[1639:7598] starting screenresolution argv=screenresolution list 04:16:37 INFO - Available Modes on Display 0 04:16:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:16:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:16:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:16:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:16:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:16:37 INFO - Intel Iris: 04:16:37 INFO - Chipset Model: Intel Iris 04:16:37 INFO - Type: GPU 04:16:37 INFO - Bus: Built-In 04:16:37 INFO - VRAM (Dynamic, Max): 1536 MB 04:16:37 INFO - Vendor: Intel (0x8086) 04:16:37 INFO - Device ID: 0x0a2e 04:16:37 INFO - Revision ID: 0x0009 04:16:37 INFO - Displays: 04:16:37 INFO - Display: 04:16:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:16:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:16:37 INFO - Display Serial Number: 1600x1200 60 04:16:37 INFO - Main Display: Yes 04:16:37 INFO - Mirror: Off 04:16:37 INFO - Online: Yes 04:16:37 INFO - Rotation: Supported 04:16:37 INFO - Return code: 0 04:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:16:37 INFO - Python 2.7.3 04:16:37 INFO - Return code: 0 04:16:37 INFO - grabbing minidump binary from tooltool 04:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102878d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10269edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102870b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:16:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:16:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:16:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:16:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:16:37 INFO - Return code: 0 04:16:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:16:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:16:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:16:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:16:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgvSUvHndU/Render', 04:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:37 INFO - 'HOME': '/Users/cltbld', 04:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:37 INFO - 'LOGNAME': 'cltbld', 04:16:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:16:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:37 INFO - 'MOZ_NO_REMOTE': '1', 04:16:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:16:37 INFO - 'NO_EM_RESTART': '1', 04:16:37 INFO - 'PAGER': '/bin/cat', 04:16:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:37 INFO - 'PWD': '/builds/slave/test', 04:16:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:37 INFO - 'SHELL': '/bin/bash', 04:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners', 04:16:37 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 04:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:37 INFO - 'USER': 'cltbld', 04:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:37 INFO - 'XPC_FLAGS': '0x0', 04:16:37 INFO - 'XPC_SERVICE_NAME': '0', 04:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:16:37 INFO - mozversion application_buildid: 20161005021900 04:16:37 INFO - mozversion application_changeset: c4a811b0531e3bf4279a1e19a3e4451f2e416719 04:16:37 INFO - mozversion application_display_name: Nightly 04:16:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:16:37 INFO - mozversion application_name: Firefox 04:16:37 INFO - mozversion application_remotingname: firefox 04:16:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:16:37 INFO - mozversion application_vendor: Mozilla 04:16:37 INFO - mozversion application_version: 52.0a1 04:16:37 INFO - mozversion platform_buildid: 20161005021900 04:16:37 INFO - mozversion platform_changeset: c4a811b0531e3bf4279a1e19a3e4451f2e416719 04:16:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:16:37 INFO - mozversion platform_version: 52.0a1 04:16:37 INFO - using testdate: 1475666197 04:16:37 INFO - actual date: 1475666197 04:16:37 INFO - starting webserver on 'localhost:49272' 04:16:37 INFO - SUITE-START | Running 2 tests 04:16:37 INFO - TEST-START | dromaeo_css 04:16:37 INFO - operating with platform_type : mac_ 04:16:37 INFO - Initialising browser for dromaeo_css test... 04:16:37 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmp4mudMO/profile http://localhost:49272/getInfo.html) 04:16:40 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 04:16:40 INFO - PROCESS | 1649 | colorDepth:24 04:16:40 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 04:16:40 INFO - PROCESS | 1649 | __metrics 04:16:40 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:16:40 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:41 INFO - TEST-INFO | 1649: exit 0 04:16:41 INFO - Browser initialized. 04:16:41 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:41 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmp4mudMO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:53 INFO - PROCESS | 1651 | RSS: Main: 236171264 04:16:53 INFO - PROCESS | 1651 | 04:17:44 INFO - PROCESS | 1651 | 1475666264689 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:19:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 04:20:00 INFO - PROCESS | 1651 | RSS: Main: 402112512 04:20:00 INFO - PROCESS | 1651 | 04:23:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 04:23:03 INFO - PROCESS | 1651 | RSS: Main: 372371456 04:23:03 INFO - PROCESS | 1651 | 04:26:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 04:26:06 INFO - PROCESS | 1651 | RSS: Main: 365355008 04:26:06 INFO - PROCESS | 1651 | 04:29:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 04:29:09 INFO - PROCESS | 1651 | RSS: Main: 345939968 04:29:09 INFO - PROCESS | 1651 | 04:32:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 04:32:13 INFO - PROCESS | 1651 | RSS: Main: 337874944 04:32:13 INFO - PROCESS | 1651 | 04:35:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 04:35:17 INFO - PROCESS | 1651 | RSS: Main: 429416448 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | __start_tp_report 04:35:17 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 04:35:17 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 04:35:17 INFO - PROCESS | 1651 | |i|pagename|runs| 04:35:17 INFO - PROCESS | 1651 | |0;dojo.html;3272.427325218087;3303.7522185836056;3321.8006919584827;3329.0678609989213;3369.5114208439777;5559.388467268597;5609.887802243953;5652.293463317085;5727.570432217584;5733.54131669467;5656.802011929573;5689;5753.626014309078;5770.422957704238;5790.913136302969;4389.31965545341;5049.596032317424;5094.51602097802;5121.12940800063;5171;5229.424763276036;5231.398389185235;5234.136367499377;5247;5283.762230699608;5167.560757335631;5198.298229738974;5220.29526013987;5239.476052394768;5281.498257665516;5125.641205115643;5133.614978876599;5144.485551444863;5173.844784656466;5183.896322073575;5095.312132862054;5135.948640513584;5137.820176293849;5164.974175129138;5193.065248255312;5650.208970744099;5650.237217975562;5700.429957004308;5716.714164291769;5739.540836733093;5783.739731712041;6201.131841542187;6219.471344935686;6228.750849965996;6242.563020588561;5864.970675146597;5912.940870591325;6145.385461453863;6211.875762484725;6233.937660623381;5735.770569177186;5750.683712395775;5751.9424805751405;5752.511036561856;5782.045962416196;6353;6358.586691865014;6372.203474565679;6372.681365931778;6377.585456945284;5941.079132734433;6004.54965877561;6012.549058820606;6092.72582733778;6106;6110.786122485781;6128.014278093809;6161.691915404302;6168.753249869955;6182.876342473125;6142.723577439026;6150.015997440478;6168.259808822881;6186.4741496972365;6212.254529456494;6220.533459990519;6222.533310001768;6229.221347331583;6234.688265586702;6238.532110091761;4830.516948305141;4869.196582563837;4926.482719314492;4944.307796908436;4948.134076536592;4367.45406824147;4423.557644235615;4429.734215947054;4437.489688685842;4470.977645111753;5603.6077474577205;5652.152177173375;5659.6321239119325;5661.575381846378;5668.489835914787;6292.244930608356;6306.085617585424;6315.715792789336;6320.494360451153;6331.05034244867;5481.862953426116;5547.16792481123;5556.555475561945;5612.719364031782;5707.258056452634;4588.49526552079;5349.598780091509;5363.785448582013;5364.275822763955;5383.838484845461;6329.556931014785;6331.588446750947;6340.968295158495;6342.7145778441;6346.524010699309;6113.415495409546;6127.234095738033;6149.84625384369;6259.092431597484;6277.717502712298;6246.812595622138;6263.937360626335;6287.591306565058;6350.1109844622715;6350.523710721622;6178.567500274938;6189.288231853395;6212.254529456494;6214.782482613087;6243.063540468966;6099.582179613229;6200.689965501797;6221.4089661482085;6297.433231009048;6306.054091886253;5997.220361352996;6072.939270607238;6099.37031394974;6105.84735381619;6106.096658337741;6101.792513241593;6109.495762817309;6120.233815636221;6120.995732433061;6136.090214303778;5905.43898329658;5948.524118070457;5949.286085669747;5956.255468066492;5969.015112506388;5935.139404786196;5936.495397891055;5973.701314934149;6098.43222861686;6130.877382452415;5905.763769449346;5934.902043122036;5943.732532035982;5979.581429300081;5996.850078748066 04:35:17 INFO - PROCESS | 1651 | |1;ext.html;472.97836672786786;518.9688618682878;525.9552938000269;533.6424595521;547.9370022157012;30179.378474583053;30505.38989220217;30543.694563054363;30755.38489230214;39982.40026399599;39975.60024399755;42595.57404425955;42708.57291427085;42748;42762;9637.903620963789;9840.458774767421;9852.458114803683;9878.2591305652;9882.752931176707;9681.515924203797;9772.07165319297;9791.363561368524;9796.265280103986;9809.901900981005;9804.117629413351;9862.013798620117;9874.061964113398;9877.555510002032;9885.604575816958;9779.70660880171;9834.262430317754;9837.114659680663;9846.212303015778;9846.261530385178;9701.563429645683;9785.706428807076;9788.55951482185;9795.265355098325;9809.705708828675;40485.98785030368;40645;40695;40695.59304406977;40740;3316.3698897209692;3350.8324583770714;3377;3423.5720534933134;3467;30866.38267234665;31318.37363252745;31405.685943140732;31673.782835201546;32087.83956080228;3186.824724640144;3213.5822343095483;3246.8376581171083;3295.9011229663147;3347.748918831073;503384;505723;506663;507451;507989;33010;33658.66341336604;58803.70598146982;59287.814243715744;59319;34595.48106778401;34947.12632184216;35047.12382190466;35132.473012904826;35133.82433087869;12495.687607809878;14403.711925761636;14507.637309067357;14508.637284067772;14532.709345813024;11912.463939122586;11975.820362694567;12368.876311236952;12397.194182378116;12397.75204495905;7646.694132234759;7696.076470823425;7737;7754.1470438251745;7763.573003484863;3158.7630927680516;3204.7436205103536;3207.775455718124;3211.71094601487;3251.3822373748912;890.643742502991;907.4600612635471;1076.8384742288563;1079.0989523747758;1145.1811954452605;22968.770312296998;28747.712522874503;29639.703602964124;29844.10467685973;30347;990.4107056301489;1058.3702696895334;1071.12703146935;1086.809808283563;1088.1621151713296;14620.853791462161;15494.845051549566;15566.377344906303;15598.298076586581;16520.75218871718;670242;676434;676694;677684;679418;57543;58070.41929580735;58135;58195.70902145462;58391.416085839446;1228.9385530723428;1263.0337791589625;1346.5623672306344;1376.2086800089987;1393.7909313602838;29413.85293073521;30825;30910.0726978191;31114.844425777726;32661.510077348383;49310.013799724526;49882.00235995332;49965;50081.49918500914;50088.98754174346;536039;536553.634463666;537677;538263;538744;23399;23842.64236036426;25857;25922.22233333003;26403.867980659972;45112.77443612761;46053.53946460626;46157.84605384892;47574.76212618915;47785.04429911313;30797.424418228766;38866.805665971486;39021.60978390293;40019.79990100031;40341;36789.44815827711;38438.423423648106;38546.03634909038;39850;39908.80045599753;32526.83736581302;32620.83689581537;32707;37237.069073273386;37674.246515069;31610.525842111925;32086.19784505406;32260.83869580637;33960.320793584484;34492.31015379728;25031.249062528153;25346.239612810907;25368.98524059017;25643.743562564883;25995.350116247246 04:35:17 INFO - PROCESS | 1651 | |2;jquery.html;350.7684927947555;368.5190825972107;369.31307767552363;380.31163593895053;399.73217943977534;15045.247737613117;15196.620084497894;16118.355265789383;20487.385378438634;20921;19432.514187145327;19944.603877728565;20379.49051273719;20592.691109633368;20921.895390523026;5245.029669511134;5672.234248376468;5959.642421454705;5969.820905372846;5976.581639285252;5792.884142317151;5962.433568810956;5975.402459754033;5980.910286345708;5983.1623572699855;5595.21666966625;5742.2247996520355;5905.379935106816;5916.437938395846;5919.467247947684;5739.569532285095;5752.050911599602;5791.218185544982;5806.506446951972;5807.216025836543;4862.3922009748785;5050.545450909399;5078.771455284521;5094.235864620299;5123.692578445305;25427.11005114812;25629.231123066336;25640.23079307605;25699.74300256992;25706.614400784005;2207.4591725027312;2312.849664771801;2394.305651361103;2498.912538061159;2874.2814296425895;15009.399624015026;15183.544493665207;15585.688286234212;15700.68598628021;15723.13522756248;1791.480470663506;1811.1035037656445;1812.4381441753098;1838.586318078435;1844.317602487075;102689.97310026879;118953;121289;125099;126682.43152037541;10081.890991990875;10254.692359229235;10654;10663.786724265472;10877.782444351227;13278.136921100098;13326.600201993955;13332.800007999887;13337.933310333387;13361.53234636783;8083;8386.287165590871;8388.496690198608;8551.273141782895;8552.358573107042;8435.987681478262;8460.450070745383;8469.872951905727;8631.956840215884;8660.913390866011;6295.276043254993;6448.3229260927865;6464.77373291942;6465.709043093072;6590.868182636321;1487.7693957436616;1500.6248437890526;1518.2105305241212;1769.761082253905;1799.9820001800076;903.503073309677;961.6682244625594;964.5370222293338;975.5902520941222;990.7424069741919;16891.408800691916;19789.505262368268;21691.13235470595;21704;21800.454988625093;954.236610711428;961.7884065505577;962.7400601837464;985.1281615770096;1010.9949450252701;9520.761980950394;10760.97770711781;11181.21731478805;11388.658340249805;11397.031252343479;591832;607016;608106;613669;618549;85954.57022714846;91521.1585611487;97968;111078;112719;767.6430459836203;881.0221053734407;917.4724533393326;942.8497233375272;994.0357852882837;18975.14611842498;19557.21771129139;19732.901335493232;19797.40607781769;19809.504762381057;51024.74487627687;51034;51399.97200056053;52334;52839;66945.66527167334;67347.25506566807;67440;68860.6556967212;69734.65132674304;5186.040582492251;5449.3460784706085;7163.746344389815;7646.235376462309;7852.6073696313815;12571.87428125707;12718.427670754652;12803.679908002374;13156.342182891009;13165.144265622705;10785.946070269598;10980.505877235704;11141.16441266892;11236.494357754083;11467.541298347973;10959.945200273947;11256.887431125584;11281.21031527785;11309.604163854226;11613.419329033684;9525.761855953657;11072.557097716;11154;11270.774584508428;11597.130215233723;7599.164091949991;7668.961655191688;7728.26581474759;7793.181715919974;8047.114817370201;10806.891931080589;10835.512401941774;11141.997220250216;11153.330800152016;11158.832617510581 04:35:17 INFO - PROCESS | 1651 | |3;mootools.html;1519.4226194046264;1536.5390382885134;1735.9218835152417;1842.2907180735413;1915.6168766246744;8610.354223433244;9177.311701622371;9177.770555736093;9184.311176661744;9186.356955013152;8003.839923201533;8264.876026859602;8697.478151310926;8937.597808098635;8973.102689731024;2062.9169685914903;2259.559385919746;2353.8352315337934;2355.105060077177;2355.893984770681;2308.988455057731;2338.8713620750937;2358.13927916311;2361.3624321433203;2364.76352364763;2347;2357.3045951444365;2358.3868194269444;2361.315218586608;2366.7751563601432;2309.5265470578493;2326.1858349577597;2333.0667732906886;2334.8248881333798;2349.3186975777003;2326.325365643961;2329.650552417134;2330.1378489958656;2332.486852892378;2333.0551126793675;8946.150115738994;9049.954750226207;9068.501232432214;9082.31882608807;9085.591148398273;5143.614228932845;5323.228131920889;5326.813561525328;5346.518813306782;5350.785968561253;3480.1821571930577;3518.700910422617;3672.926541469156;3677.632236776301;3678.9632103678887;1042.4162469017374;1080.6974047266779;1081.3836113415357;1099.6261271167762;1105.8838821923744;11903.702407439796;12024.158308918379;12025.398730063462;12031.51873925042;12051.879481205076;3726.7950262735267;3896.980515097406;4568.977155114269;4787.569118779326;4791.6406269529925;10067.5469603868;10145.9492702536;10154.898451015542;10160.983901609929;10163.94918025405;5209.140491818807;5219.164933610604;5306.734663266899;5358.089124848785;5374.731263436813;4397.824087036547;4423.579759922866;4577.061702351077;4843.515648435128;4897.730624815598;3519.982400087983;3834.750741201813;3843.2505661395758;3862.671672907778;3982.3230050891416;1252.3174869696038;1256.466001949168;1268.695513076855;1274.8908449648866;1291.0446269760498;817.7832874288318;877.9648814047366;904.7873749668822;923.6397804856111;952.8142012307673;4915.336429582032;5761.6254943429385;5823.650580965155;5964.642121472725;5998.370171131968;366.21081569218376;433.43436814956783;451.0775464175703;452.111600704617;458.53916813602115;3914.6085391461197;3918.2555314490155;4015.377616469452;4063.715539912178;4087.754734715867;9837.852432213522;10747.193960452998;10842.078423334106;10849.783004340026;10869.184811139196;10469.790604187874;10817.621383251548;10833.02502774754;10844.295120817122;10874.401907894984;8083.676652933935;8278.130796266305;8283.792905177299;8293.967977845587;8295.95852020736;5721.370649228665;5773.9895518284975;5824.5340372770925;5851.239338885917;5909.497692696169;9966.800663986825;10042.297039207477;10050.045245701647;10079.294449388472;10087.04173103554;9816.349585611988;10035.949820250851;10051.69844904654;10170.08469237772;10290.3825770454;6983.127109111361;7240.963795181201;7347.302006309392;7348.412127029718;7528.811779705551;8393.28657064132;8579.699710510102;8589.699360522602;8596.656133754579;8601.139886011599;7437.107547094383;7541.2458754124145;7673.155952845071;7674.347680447225;7710.421718371257;7166.390856777232;7288.635568221674;7407.666655000646;7466.962665186639;7596.8480630385975;6231.158793562811;7115.359617634438;7122.60825654594;7122.928770712227;7128.28717128283;4925.556699896883;5018.247262910592;5109.08036553424;5116.283720279238;5130.461301563282;4550;4970.751462426936;5070.670406423571;5092.7777333440445;5188.377394712658 04:35:17 INFO - PROCESS | 1651 | |4;prototype.html;283.82686561197664;299.34592914481846;308.0942030430534;313.9623245210575;314.8803454687218;4990.27640992056;5134.178531434971;5256.106461901476;5277.788888444463;5302.284191634129;4941.851744447663;5074.568661663763;5081.263216833557;5162.328897243363;5259.710715910624;4047.874865695504;4062.8577999770014;4075.918481630373;4081.9795901020602;4085.4893138357706;3332.900012999614;3348.64839191885;3901.2782635212434;4048.696347773914;4091.8363265469493;4009.83960641574;4011.618896204856;4029.8313489088127;4030.3349947258666;4031.979840100795;3981.024648960994;4016.4376987221813;4017.598240175974;4023.97988010061;4029.435878976946;3038.270815004383;3480.89557313281;3484.8083355415442;3495.6329585393423;3514.279572687593;4023.456833327522;4911.336969509106;5038.672486288379;5040.34475518184;5067.353110239187;3866.8786052044875;4561.042181141964;4570.382998295221;4600.101935780471;4614.700044497131;5366.517013468807;5443.591730620219;5469.343678758574;5471.23402723619;5513.61404701671;3389.5254664347012;3471.6460580373673;3589.8999085534992;3713.721470889667;3873.8572121224306;5696.601237913306;5699.544036477072;5731.140328950607;5735.139729040636;5753.137029445575;3331.4003479373946;3915.569287378386;3990.8004599770475;4000.8199631016673;4010.9197816043516;3267.6895694908944;3835.980820095882;4002.3596224604507;4004.5383435045997;4030.2544029354663;3678.7608805427562;4463.977680111579;4547.840825571089;4548.727076375362;4578.45058592971;4303.892402689895;4564.178447879413;5490.313710786152;5508.283923089973;5521.282233309725;1175.5826681527935;1212.9454174562165;1311.5278499740086;1327.8381416260772;1336.2316667915986;784.3254800871246;878.8813510176044;896.9058248883904;924.4915296586851;927.2998885841147;4270.35944608305;4700.952990470051;4779.904401912012;4909.337239472697;4950.499997501239;4895.363602731693;4900.852974410774;4969.614584999221;5261.579073674096;5451.454854514517;4058.0260737423396;4239.8092085856215;4449.777511124431;5010.674306170087;5097.566706829961;2230.6765518999655;2360.268316821791;2446.3761740755995;2448.4207686042405;2452.3378687754207;3807.485989391452;3853.2871418787613;4305.858947378947;4644.953550464452;4700.318453824245;4129.628333450004;4435.556444355539;4475.70907890986;4671.719696818202;4712.7746785835925;3632.7457078004813;4498.8425405110665;4651.744154071558;4683.484816670231;4760.428748550196;3724.7149733341953;4416.47002359706;4534.390291446489;4570.354672317896;4576.6109880660515;4232;4535;4566.977165114153;4567.543245675406;4597.310403439511;4667.276572131325;4700.130475861976;4707.693999889996;4721.677930179555;4765.785539650793;3327.933441331208;3348.9330213396083;3357.699975516779;3361.630220675675;3378.8141652209365;3872.4578559001197;4217.177650358273;4477.126960242721;4603.355530225704;4626.32207395115;4132.132252227066;4456.821727130879;4475.784486679115;4493.438320209973;4609.723416595015;3690.763594195867;4439.492800687421;4451.243288641003;4462.799174037111;4523.547645235451;4383.736975781463;4689.554492323224;5029.371328583927;5078.644494885322;5354.892902142014;5050.823221187241;5499.092649712753;5502.5597952164535;5534.446555344433;5594.580406469449;4561.201789686858;4792.7363994980615;5088.62318769724;5156.736599533138;5421.810236641699 04:35:17 INFO - PROCESS | 1651 | |5;yui.html;609.682964858274;692.6536731634179;703.5637904499212;710.1726488640734;850.851101057315;15213.771793423108;15581.220938953049;15843.524694259162;15871.285792139353;15947.920260398696;14209.502667406643;14358.641033974156;15014.774778378305;15559.766603500957;15719.13544755038;843.2537204573965;866.4541338956449;867.453504292295;868.5874209750381;869.9173578510031;683.6684208159064;705.2418649951322;708.2704814041546;830.7092517618815;854.9572521373938;898.4294972692322;901.7475106970059;901.8737376767258;902.6163880350857;903.0382642485731;897.833900728364;899.757065592293;900.5947323704327;900.6577500549749;900.7432881628704;521.8069314353713;534.871630808607;646.8382904273932;662.6951602262965;669.4911866981081;14022.298885055707;14063.859361406357;14064.437422503088;14071.437142514389;14096.71806563863;13194.604161875159;13361.732765344736;13479.528216512375;13504;13930.164190148624;12529.686757831036;12871.292078935654;13077.607671769767;13115.672108197276;13495.257760823151;4400.251957167289;4415;4466.843660471868;4569.657275704302;4655.7439340836245;112679;114221.42889285584;114292;114383;116215;10567;10658.733531661615;10679.350800553191;10936.781264374667;11041.613543525938;20851.687224691643;20859.8957005214;20870.58258834815;20878.686819697716;20881.791182088287;7415.486359332302;7577.810554736066;7607.885881711779;7756.069271687434;7769.417293702995;3994.7616238966016;4193.7483750974425;4197.160567886435;4287.721298115613;4343.1747967886;3559.2525569630666;3830.4637350770913;4045.2516284487465;4046.1300820323772;4062.7968601569805;654.8690261947629;704.5068452083563;707.1161048689139;729.4018904498366;734.772220611612;560.9411011843779;595.7110801261384;600.159776313165;617.9938200618026;665.125360151409;8979.595918183697;9178.35751497402;9215.861762073575;9368.34421590482;9434.764130896645;592.8725324055263;597.4205021129497;626.3736263736264;656.6585375604659;683.2006552333683;4575.519570445122;4583.381243532081;4614.676972611885;4639.313755734461;4656.720596764205;160335;164242.1787891053;164557;164581;167315;138762;139705;141991;144602;145428;93171;119013.40493297478;119888.01679747933;120625.39687301683;121333;8531.061583225715;8734.432261902955;8750;8797.956010219908;9012.729618111467;66750.66624666845;67598.98601520882;67642.66178669075;70056.24859378353;70061;67523.64952701017;77570.44859102674;80487;87950.56024719836;93742;10935;13187.73624527485;13256.469741210629;13273;13508.459661613426;14776.77834832457;15092.924535377253;15106.169160696267;15432.219106318575;15442.30509627092;13396.665083372995;13497.86502134966;13973.021888468116;14249.287535623385;14279.64300892486;13661.590152295446;13705.520306789214;14175.36210870534;14482.637934051732;14722.043067200597;13096;13291.66770830737;13433.26117063571;13690.86309136896;13786.862131378557;10944.39805810688;11108.277961932448;11211.439428028729;11476.540938362372;11774.411279436164;11147.944260278648;11280;11642.41787910618;11666.76666466649;11674.70813229676 04:35:17 INFO - PROCESS | 1651 | __end_tp_report 04:35:17 INFO - PROCESS | 1651 | __start_cc_report 04:35:17 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1740 04:35:17 INFO - PROCESS | 1651 | __end_cc_report 04:35:17 INFO - PROCESS | 1651 | __startTimestamp1475667317195__endTimestamp 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | ------- Summary: start ------- 04:35:17 INFO - PROCESS | 1651 | Number of tests: 6 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5711.75 Median:5936.50 stddev:655.52 (11.0%) stddev-sans-first:628.98 04:35:17 INFO - PROCESS | 1651 | Values: 3272.4 3303.8 3321.8 3329.1 3369.5 5559.4 5609.9 5652.3 5727.6 5733.5 5656.8 5689.0 5753.6 5770.4 5790.9 4389.3 5049.6 5094.5 5121.1 5171.0 5229.4 5231.4 5234.1 5247.0 5283.8 5167.6 5198.3 5220.3 5239.5 5281.5 5125.6 5133.6 5144.5 5173.8 5183.9 5095.3 5135.9 5137.8 5165.0 5193.1 5650.2 5650.2 5700.4 5716.7 5739.5 5783.7 6201.1 6219.5 6228.8 6242.6 5865.0 5912.9 6145.4 6211.9 6233.9 5735.8 5750.7 5751.9 5752.5 5782.0 6353.0 6358.6 6372.2 6372.7 6377.6 5941.1 6004.5 6012.5 6092.7 6106.0 6110.8 6128.0 6161.7 6168.8 6182.9 6142.7 6150.0 6168.3 6186.5 6212.3 6220.5 6222.5 6229.2 6234.7 6238.5 4830.5 4869.2 4926.5 4944.3 4948.1 4367.5 4423.6 4429.7 4437.5 4471.0 5603.6 5652.2 5659.6 5661.6 5668.5 6292.2 6306.1 6315.7 6320.5 6331.1 5481.9 5547.2 5556.6 5612.7 5707.3 4588.5 5349.6 5363.8 5364.3 5383.8 6329.6 6331.6 6341.0 6342.7 6346.5 6113.4 6127.2 6149.8 6259.1 6277.7 6246.8 6263.9 6287.6 6350.1 6350.5 6178.6 6189.3 6212.3 6214.8 6243.1 6099.6 6200.7 6221.4 6297.4 6306.1 5997.2 6072.9 6099.4 6105.8 6106.1 6101.8 6109.5 6120.2 6121.0 6136.1 5905.4 5948.5 5949.3 5956.3 5969.0 5935.1 5936.5 5973.7 6098.4 6130.9 5905.8 5934.9 5943.7 5979.6 5996.9 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:68637.12 Median:38492.23 stddev:154935.25 (402.5%) stddev-sans-first:155285.36 04:35:17 INFO - PROCESS | 1651 | Values: 473.0 519.0 526.0 533.6 547.9 30179.4 30505.4 30543.7 30755.4 39982.4 39975.6 42595.6 42708.6 42748.0 42762.0 9637.9 9840.5 9852.5 9878.3 9882.8 9681.5 9772.1 9791.4 9796.3 9809.9 9804.1 9862.0 9874.1 9877.6 9885.6 9779.7 9834.3 9837.1 9846.2 9846.3 9701.6 9785.7 9788.6 9795.3 9809.7 40486.0 40645.0 40695.0 40695.6 40740.0 3316.4 3350.8 3377.0 3423.6 3467.0 30866.4 31318.4 31405.7 31673.8 32087.8 3186.8 3213.6 3246.8 3295.9 3347.7 503384.0 505723.0 506663.0 507451.0 507989.0 33010.0 33658.7 58803.7 59287.8 59319.0 34595.5 34947.1 35047.1 35132.5 35133.8 12495.7 14403.7 14507.6 14508.6 14532.7 11912.5 11975.8 12368.9 12397.2 12397.8 7646.7 7696.1 7737.0 7754.1 7763.6 3158.8 3204.7 3207.8 3211.7 3251.4 890.6 907.5 1076.8 1079.1 1145.2 22968.8 28747.7 29639.7 29844.1 30347.0 990.4 1058.4 1071.1 1086.8 1088.2 14620.9 15494.8 15566.4 15598.3 16520.8 670242.0 676434.0 676694.0 677684.0 679418.0 57543.0 58070.4 58135.0 58195.7 58391.4 1228.9 1263.0 1346.6 1376.2 1393.8 29413.9 30825.0 30910.1 31114.8 32661.5 49310.0 49882.0 49965.0 50081.5 50089.0 536039.0 536553.6 537677.0 538263.0 538744.0 23399.0 23842.6 25857.0 25922.2 26403.9 45112.8 46053.5 46157.8 47574.8 47785.0 30797.4 38866.8 39021.6 40019.8 40341.0 36789.4 38438.4 38546.0 39850.0 39908.8 32526.8 32620.8 32707.0 37237.1 37674.2 31610.5 32086.2 32260.8 33960.3 34492.3 25031.2 25346.2 25369.0 25643.7 25995.4 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:34233.28 Median:1612.52 stddev:100684.96 (6243.9%) stddev-sans-first:100935.26 04:35:17 INFO - PROCESS | 1651 | Values: 350.8 368.5 369.3 380.3 399.7 15045.2 15196.6 16118.4 20487.4 20921.0 19432.5 19944.6 20379.5 20592.7 20921.9 5245.0 5672.2 5959.6 5969.8 5976.6 5792.9 5962.4 5975.4 5980.9 5983.2 5595.2 5742.2 5905.4 5916.4 5919.5 5739.6 5752.1 5791.2 5806.5 5807.2 4862.4 5050.5 5078.8 5094.2 5123.7 25427.1 25629.2 25640.2 25699.7 25706.6 2207.5 2312.8 2394.3 2498.9 2874.3 15009.4 15183.5 15585.7 15700.7 15723.1 1791.5 1811.1 1812.4 1838.6 1844.3 102690.0 118953.0 121289.0 125099.0 126682.4 10081.9 10254.7 10654.0 10663.8 10877.8 13278.1 13326.6 13332.8 13337.9 13361.5 8083.0 8386.3 8388.5 8551.3 8552.4 8436.0 8460.5 8469.9 8632.0 8660.9 6295.3 6448.3 6464.8 6465.7 6590.9 1487.8 1500.6 1518.2 1769.8 1800.0 903.5 961.7 964.5 975.6 990.7 16891.4 19789.5 21691.1 21704.0 21800.5 954.2 961.8 962.7 985.1 1011.0 9520.8 10761.0 11181.2 11388.7 11397.0 591832.0 607016.0 608106.0 613669.0 618549.0 85954.6 91521.2 97968.0 111078.0 112719.0 767.6 881.0 917.5 942.8 994.0 18975.1 19557.2 19732.9 19797.4 19809.5 51024.7 51034.0 51400.0 52334.0 52839.0 66945.7 67347.3 67440.0 68860.7 69734.7 5186.0 5449.3 7163.7 7646.2 7852.6 12571.9 12718.4 12803.7 13156.3 13165.1 10785.9 10980.5 11141.2 11236.5 11467.5 10959.9 11256.9 11281.2 11309.6 11613.4 9525.8 11072.6 11154.0 11270.8 11597.1 7599.2 7669.0 7728.3 7793.2 8047.1 10806.9 10835.5 11142.0 11153.3 11158.8 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5720.52 Median:4559.49 stddev:3276.15 (71.9%) stddev-sans-first:3270.13 04:35:17 INFO - PROCESS | 1651 | Values: 1519.4 1536.5 1735.9 1842.3 1915.6 8610.4 9177.3 9177.8 9184.3 9186.4 8003.8 8264.9 8697.5 8937.6 8973.1 2062.9 2259.6 2353.8 2355.1 2355.9 2309.0 2338.9 2358.1 2361.4 2364.8 2347.0 2357.3 2358.4 2361.3 2366.8 2309.5 2326.2 2333.1 2334.8 2349.3 2326.3 2329.7 2330.1 2332.5 2333.1 8946.2 9050.0 9068.5 9082.3 9085.6 5143.6 5323.2 5326.8 5346.5 5350.8 3480.2 3518.7 3672.9 3677.6 3679.0 1042.4 1080.7 1081.4 1099.6 1105.9 11903.7 12024.2 12025.4 12031.5 12051.9 3726.8 3897.0 4569.0 4787.6 4791.6 10067.5 10145.9 10154.9 10161.0 10163.9 5209.1 5219.2 5306.7 5358.1 5374.7 4397.8 4423.6 4577.1 4843.5 4897.7 3520.0 3834.8 3843.3 3862.7 3982.3 1252.3 1256.5 1268.7 1274.9 1291.0 817.8 878.0 904.8 923.6 952.8 4915.3 5761.6 5823.7 5964.6 5998.4 366.2 433.4 451.1 452.1 458.5 3914.6 3918.3 4015.4 4063.7 4087.8 9837.9 10747.2 10842.1 10849.8 10869.2 10469.8 10817.6 10833.0 10844.3 10874.4 8083.7 8278.1 8283.8 8294.0 8296.0 5721.4 5774.0 5824.5 5851.2 5909.5 9966.8 10042.3 10050.0 10079.3 10087.0 9816.3 10035.9 10051.7 10170.1 10290.4 6983.1 7241.0 7347.3 7348.4 7528.8 8393.3 8579.7 8589.7 8596.7 8601.1 7437.1 7541.2 7673.2 7674.3 7710.4 7166.4 7288.6 7407.7 7467.0 7596.8 6231.2 7115.4 7122.6 7122.9 7128.3 4925.6 5018.2 5109.1 5116.3 5130.5 4550.0 4970.8 5070.7 5092.8 5188.4 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4125.76 Median:4496.14 stddev:1225.28 (27.3%) stddev-sans-first:1194.31 04:35:17 INFO - PROCESS | 1651 | Values: 283.8 299.3 308.1 314.0 314.9 4990.3 5134.2 5256.1 5277.8 5302.3 4941.9 5074.6 5081.3 5162.3 5259.7 4047.9 4062.9 4075.9 4082.0 4085.5 3332.9 3348.6 3901.3 4048.7 4091.8 4009.8 4011.6 4029.8 4030.3 4032.0 3981.0 4016.4 4017.6 4024.0 4029.4 3038.3 3480.9 3484.8 3495.6 3514.3 4023.5 4911.3 5038.7 5040.3 5067.4 3866.9 4561.0 4570.4 4600.1 4614.7 5366.5 5443.6 5469.3 5471.2 5513.6 3389.5 3471.6 3589.9 3713.7 3873.9 5696.6 5699.5 5731.1 5735.1 5753.1 3331.4 3915.6 3990.8 4000.8 4010.9 3267.7 3836.0 4002.4 4004.5 4030.3 3678.8 4464.0 4547.8 4548.7 4578.5 4303.9 4564.2 5490.3 5508.3 5521.3 1175.6 1212.9 1311.5 1327.8 1336.2 784.3 878.9 896.9 924.5 927.3 4270.4 4701.0 4779.9 4909.3 4950.5 4895.4 4900.9 4969.6 5261.6 5451.5 4058.0 4239.8 4449.8 5010.7 5097.6 2230.7 2360.3 2446.4 2448.4 2452.3 3807.5 3853.3 4305.9 4645.0 4700.3 4129.6 4435.6 4475.7 4671.7 4712.8 3632.7 4498.8 4651.7 4683.5 4760.4 3724.7 4416.5 4534.4 4570.4 4576.6 4232.0 4535.0 4567.0 4567.5 4597.3 4667.3 4700.1 4707.7 4721.7 4765.8 3327.9 3348.9 3357.7 3361.6 3378.8 3872.5 4217.2 4477.1 4603.4 4626.3 4132.1 4456.8 4475.8 4493.4 4609.7 3690.8 4439.5 4451.2 4462.8 4523.5 4383.7 4689.6 5029.4 5078.6 5354.9 5050.8 5499.1 5502.6 5534.4 5594.6 4561.2 4792.7 5088.6 5156.7 5421.8 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:25834.61 Median:3912.61 stddev:42287.18 (1080.8%) stddev-sans-first:42363.40 04:35:17 INFO - PROCESS | 1651 | Values: 609.7 692.7 703.6 710.2 850.9 15213.8 15581.2 15843.5 15871.3 15947.9 14209.5 14358.6 15014.8 15559.8 15719.1 843.3 866.5 867.5 868.6 869.9 683.7 705.2 708.3 830.7 855.0 898.4 901.7 901.9 902.6 903.0 897.8 899.8 900.6 900.7 900.7 521.8 534.9 646.8 662.7 669.5 14022.3 14063.9 14064.4 14071.4 14096.7 13194.6 13361.7 13479.5 13504.0 13930.2 12529.7 12871.3 13077.6 13115.7 13495.3 4400.3 4415.0 4466.8 4569.7 4655.7 112679.0 114221.4 114292.0 114383.0 116215.0 10567.0 10658.7 10679.4 10936.8 11041.6 20851.7 20859.9 20870.6 20878.7 20881.8 7415.5 7577.8 7607.9 7756.1 7769.4 3994.8 4193.7 4197.2 4287.7 4343.2 3559.3 3830.5 4045.3 4046.1 4062.8 654.9 704.5 707.1 729.4 734.8 560.9 595.7 600.2 618.0 665.1 8979.6 9178.4 9215.9 9368.3 9434.8 592.9 597.4 626.4 656.7 683.2 4575.5 4583.4 4614.7 4639.3 4656.7 160335.0 164242.2 164557.0 164581.0 167315.0 138762.0 139705.0 141991.0 144602.0 145428.0 93171.0 119013.4 119888.0 120625.4 121333.0 8531.1 8734.4 8750.0 8798.0 9012.7 66750.7 67599.0 67642.7 70056.2 70061.0 67523.6 77570.4 80487.0 87950.6 93742.0 10935.0 13187.7 13256.5 13273.0 13508.5 14776.8 15092.9 15106.2 15432.2 15442.3 13396.7 13497.9 13973.0 14249.3 14279.6 13661.6 13705.5 14175.4 14482.6 14722.0 13096.0 13291.7 13433.3 13690.9 13786.9 10944.4 11108.3 11211.4 11476.5 11774.4 11147.9 11280.0 11642.4 11666.8 11674.7 04:35:17 INFO - PROCESS | 1651 | -------- Summary: end -------- 04:35:17 INFO - PROCESS | 1651 | 04:35:17 INFO - TEST-INFO | 1651: exit 0 04:35:17 INFO - TEST-OK | dromaeo_css | took 1119964ms 04:35:17 INFO - TEST-START | kraken 04:35:17 INFO - operating with platform_type : mac_ 04:35:17 INFO - Initialising browser for kraken test... 04:35:17 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpOPYoTb/profile http://localhost:49272/getInfo.html) 04:35:20 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:35:20 INFO - PROCESS | 1662 | colorDepth:24 04:35:20 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:35:20 INFO - PROCESS | 1662 | __metrics 04:35:20 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:35:20 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:35:21 INFO - TEST-INFO | 1662: exit 0 04:35:21 INFO - Browser initialized. 04:35:21 INFO - Running cycle 1/1 for kraken test... 04:35:21 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpOPYoTb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:33 INFO - PROCESS | 1663 | RSS: Main: 236261376 04:35:33 INFO - PROCESS | 1663 | 04:35:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:36 INFO - PROCESS | 1663 | RSS: Main: 297029632 04:35:36 INFO - PROCESS | 1663 | 04:35:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 04:35:40 INFO - PROCESS | 1663 | RSS: Main: 295362560 04:35:40 INFO - PROCESS | 1663 | 04:35:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 04:35:44 INFO - PROCESS | 1663 | RSS: Main: 386641920 04:35:44 INFO - PROCESS | 1663 | 04:35:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 04:35:46 INFO - PROCESS | 1663 | RSS: Main: 286605312 04:35:46 INFO - PROCESS | 1663 | 04:35:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:35:49 INFO - PROCESS | 1663 | RSS: Main: 290562048 04:35:49 INFO - PROCESS | 1663 | 04:35:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 04:35:52 INFO - PROCESS | 1663 | RSS: Main: 327733248 04:35:52 INFO - PROCESS | 1663 | 04:35:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 04:35:56 INFO - PROCESS | 1663 | RSS: Main: 329261056 04:35:56 INFO - PROCESS | 1663 | 04:35:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 04:35:59 INFO - PROCESS | 1663 | RSS: Main: 326389760 04:35:59 INFO - PROCESS | 1663 | 04:36:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:36:01 INFO - PROCESS | 1663 | RSS: Main: 301965312 04:36:01 INFO - PROCESS | 1663 | 04:36:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:36:04 INFO - PROCESS | 1663 | RSS: Main: 306810880 04:36:04 INFO - PROCESS | 1663 | 04:36:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:07 INFO - PROCESS | 1663 | RSS: Main: 313262080 04:36:07 INFO - PROCESS | 1663 | 04:36:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:11 INFO - PROCESS | 1663 | RSS: Main: 320282624 04:36:11 INFO - PROCESS | 1663 | 04:36:14 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:14 INFO - PROCESS | 1663 | RSS: Main: 320180224 04:36:14 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 04:36:16 INFO - PROCESS | 1663 | RSS: Main: 364335104 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | __start_tp_report 04:36:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 04:36:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 04:36:16 INFO - PROCESS | 1663 | |i|pagename|runs| 04:36:16 INFO - PROCESS | 1663 | |0;ai-astar;112;111;120;167;145;111;112;111;112;112 04:36:16 INFO - PROCESS | 1663 | |1;audio-beat-detection;156;113;117;173;103;133;140;143;144;113 04:36:16 INFO - PROCESS | 1663 | |2;audio-dft;184;184;183;184;185;182;184;182;181;185 04:36:16 INFO - PROCESS | 1663 | |3;audio-fft;79;60;57;90;60;58;58;59;82;59 04:36:16 INFO - PROCESS | 1663 | |4;audio-oscillator;91;70;90;69;69;69;92;68;68;90 04:36:16 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;131;131;87;88;85;88;87;88;85 04:36:16 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;94;93;94;95;94;94;94;94;94 04:36:16 INFO - PROCESS | 1663 | |7;imaging-desaturate;87;84;84;86;86;83;85;84;85;86 04:36:16 INFO - PROCESS | 1663 | |8;json-parse-financial;57;51;53;53;54;55;53;54;54;55 04:36:16 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;64;69;75;75;71;68;68;68;69;72 04:36:16 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;87;87;85;91;87;88;90;86;91;94 04:36:16 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;146;158;145;146;135;153;153;137;201;131 04:36:16 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;172;235;206;171;231;166;214;233;185;170 04:36:16 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;70;69;69;69;69;68;70;68;68;70 04:36:16 INFO - PROCESS | 1663 | __end_tp_report 04:36:16 INFO - PROCESS | 1663 | __start_cc_report 04:36:16 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2022 04:36:16 INFO - PROCESS | 1663 | __end_cc_report 04:36:16 INFO - PROCESS | 1663 | __startTimestamp1475667376838__endTimestamp 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | ------- Summary: start ------- 04:36:16 INFO - PROCESS | 1663 | Number of tests: 14 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:121.30 Median:112.00 stddev:19.20 (17.1%) stddev-sans-first:20.06 04:36:16 INFO - PROCESS | 1663 | Values: 112.0 111.0 120.0 167.0 145.0 111.0 112.0 111.0 112.0 112.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:141.50 stddev:21.99 (15.5%) stddev-sans-first:21.77 04:36:16 INFO - PROCESS | 1663 | Values: 156.0 113.0 117.0 173.0 103.0 133.0 140.0 143.0 144.0 113.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.35 (0.7%) stddev-sans-first:1.41 04:36:16 INFO - PROCESS | 1663 | Values: 184.0 184.0 183.0 184.0 185.0 182.0 184.0 182.0 181.0 185.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.00 stddev:12.38 (20.6%) stddev-sans-first:12.23 04:36:16 INFO - PROCESS | 1663 | Values: 79.0 60.0 57.0 90.0 60.0 58.0 58.0 59.0 82.0 59.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:80.00 stddev:11.35 (14.2%) stddev-sans-first:10.95 04:36:16 INFO - PROCESS | 1663 | Values: 91.0 70.0 90.0 69.0 69.0 69.0 92.0 68.0 68.0 90.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:87.50 stddev:18.54 (21.2%) stddev-sans-first:19.50 04:36:16 INFO - PROCESS | 1663 | Values: 89.0 131.0 131.0 87.0 88.0 85.0 88.0 87.0 88.0 85.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 04:36:16 INFO - PROCESS | 1663 | Values: 95.0 94.0 93.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:1.25 (1.5%) stddev-sans-first:1.09 04:36:16 INFO - PROCESS | 1663 | Values: 87.0 84.0 84.0 86.0 86.0 83.0 85.0 84.0 85.0 86.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.60 (3.0%) stddev-sans-first:1.24 04:36:16 INFO - PROCESS | 1663 | Values: 57.0 51.0 53.0 53.0 54.0 55.0 53.0 54.0 54.0 55.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:3.41 (4.9%) stddev-sans-first:2.88 04:36:16 INFO - PROCESS | 1663 | Values: 64.0 69.0 75.0 75.0 71.0 68.0 68.0 68.0 69.0 72.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:89.00 stddev:2.80 (3.1%) stddev-sans-first:2.91 04:36:16 INFO - PROCESS | 1663 | Values: 87.0 87.0 85.0 91.0 87.0 88.0 90.0 86.0 91.0 94.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:149.50 stddev:19.70 (13.2%) stddev-sans-first:20.83 04:36:16 INFO - PROCESS | 1663 | Values: 146.0 158.0 145.0 146.0 135.0 153.0 153.0 137.0 201.0 131.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.30 Median:210.00 stddev:28.64 (13.6%) stddev-sans-first:28.76 04:36:16 INFO - PROCESS | 1663 | Values: 172.0 235.0 206.0 171.0 231.0 166.0 214.0 233.0 185.0 170.0 04:36:16 INFO - PROCESS | 1663 | 04:36:16 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 04:36:16 INFO - PROCESS | 1663 | Values: 70.0 69.0 69.0 69.0 69.0 68.0 70.0 68.0 68.0 70.0 04:36:16 INFO - PROCESS | 1663 | -------- Summary: end -------- 04:36:16 INFO - PROCESS | 1663 | 04:36:17 INFO - TEST-INFO | 1663: exit 0 04:36:17 INFO - TEST-OK | kraken | took 59884ms 04:36:17 INFO - SUITE-END | took 1179s 04:36:17 INFO - Completed test suite (00:19:40) 04:36:17 INFO - javascript benchmark 04:36:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.427325218087, 3303.7522185836056, 3321.8006919584827, 3329.0678609989213, 3369.5114208439777, 5559.388467268597, 5609.887802243953, 5652.293463317085, 5727.570432217584, 5733.54131669467, 5656.802011929573, 5689.0, 5753.626014309078, 5770.422957704238, 5790.913136302969, 4389.31965545341, 5049.596032317424, 5094.51602097802, 5121.12940800063, 5171.0, 5229.424763276036, 5231.398389185235, 5234.136367499377, 5247.0, 5283.762230699608, 5167.560757335631, 5198.298229738974, 5220.29526013987, 5239.476052394768, 5281.498257665516, 5125.641205115643, 5133.614978876599, 5144.485551444863, 5173.844784656466, 5183.896322073575, 5095.312132862054, 5135.948640513584, 5137.820176293849, 5164.974175129138, 5193.065248255312, 5650.208970744099, 5650.237217975562, 5700.429957004308, 5716.714164291769, 5739.540836733093, 5783.739731712041, 6201.131841542187, 6219.471344935686, 6228.750849965996, 6242.563020588561, 5864.970675146597, 5912.940870591325, 6145.385461453863, 6211.875762484725, 6233.937660623381, 5735.770569177186, 5750.683712395775, 5751.9424805751405, 5752.511036561856, 5782.045962416196, 6353.0, 6358.586691865014, 6372.203474565679, 6372.681365931778, 6377.585456945284, 5941.079132734433, 6004.54965877561, 6012.549058820606, 6092.72582733778, 6106.0, 6110.786122485781, 6128.014278093809, 6161.691915404302, 6168.753249869955, 6182.876342473125, 6142.723577439026, 6150.015997440478, 6168.259808822881, 6186.4741496972365, 6212.254529456494, 6220.533459990519, 6222.533310001768, 6229.221347331583, 6234.688265586702, 6238.532110091761, 4830.516948305141, 4869.196582563837, 4926.482719314492, 4944.307796908436, 4948.134076536592, 4367.45406824147, 4423.557644235615, 4429.734215947054, 4437.489688685842, 4470.977645111753, 5603.6077474577205, 5652.152177173375, 5659.6321239119325, 5661.575381846378, 5668.489835914787, 6292.244930608356, 6306.085617585424, 6315.715792789336, 6320.494360451153, 6331.05034244867, 5481.862953426116, 5547.16792481123, 5556.555475561945, 5612.719364031782, 5707.258056452634, 4588.49526552079, 5349.598780091509, 5363.785448582013, 5364.275822763955, 5383.838484845461, 6329.556931014785, 6331.588446750947, 6340.968295158495, 6342.7145778441, 6346.524010699309, 6113.415495409546, 6127.234095738033, 6149.84625384369, 6259.092431597484, 6277.717502712298, 6246.812595622138, 6263.937360626335, 6287.591306565058, 6350.1109844622715, 6350.523710721622, 6178.567500274938, 6189.288231853395, 6212.254529456494, 6214.782482613087, 6243.063540468966, 6099.582179613229, 6200.689965501797, 6221.4089661482085, 6297.433231009048, 6306.054091886253, 5997.220361352996, 6072.939270607238, 6099.37031394974, 6105.84735381619, 6106.096658337741, 6101.792513241593, 6109.495762817309, 6120.233815636221, 6120.995732433061, 6136.090214303778, 5905.43898329658, 5948.524118070457, 5949.286085669747, 5956.255468066492, 5969.015112506388, 5935.139404786196, 5936.495397891055, 5973.701314934149, 6098.43222861686, 6130.877382452415, 5905.763769449346, 5934.902043122036, 5943.732532035982, 5979.581429300081, 5996.850078748066], "value": 5668.253312589023, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.97836672786786, 518.9688618682878, 525.9552938000269, 533.6424595521, 547.9370022157012, 30179.378474583053, 30505.38989220217, 30543.694563054363, 30755.38489230214, 39982.40026399599, 39975.60024399755, 42595.57404425955, 42708.57291427085, 42748.0, 42762.0, 9637.903620963789, 9840.458774767421, 9852.458114803683, 9878.2591305652, 9882.752931176707, 9681.515924203797, 9772.07165319297, 9791.363561368524, 9796.265280103986, 9809.901900981005, 9804.117629413351, 9862.013798620117, 9874.061964113398, 9877.555510002032, 9885.604575816958, 9779.70660880171, 9834.262430317754, 9837.114659680663, 9846.212303015778, 9846.261530385178, 9701.563429645683, 9785.706428807076, 9788.55951482185, 9795.265355098325, 9809.705708828675, 40485.98785030368, 40645.0, 40695.0, 40695.59304406977, 40740.0, 3316.3698897209692, 3350.8324583770714, 3377.0, 3423.5720534933134, 3467.0, 30866.38267234665, 31318.37363252745, 31405.685943140732, 31673.782835201546, 32087.83956080228, 3186.824724640144, 3213.5822343095483, 3246.8376581171083, 3295.9011229663147, 3347.748918831073, 503384.0, 505723.0, 506663.0, 507451.0, 507989.0, 33010.0, 33658.66341336604, 58803.70598146982, 59287.814243715744, 59319.0, 34595.48106778401, 34947.12632184216, 35047.12382190466, 35132.473012904826, 35133.82433087869, 12495.687607809878, 14403.711925761636, 14507.637309067357, 14508.637284067772, 14532.709345813024, 11912.463939122586, 11975.820362694567, 12368.876311236952, 12397.194182378116, 12397.75204495905, 7646.694132234759, 7696.076470823425, 7737.0, 7754.1470438251745, 7763.573003484863, 3158.7630927680516, 3204.7436205103536, 3207.775455718124, 3211.71094601487, 3251.3822373748912, 890.643742502991, 907.4600612635471, 1076.8384742288563, 1079.0989523747758, 1145.1811954452605, 22968.770312296998, 28747.712522874503, 29639.703602964124, 29844.10467685973, 30347.0, 990.4107056301489, 1058.3702696895334, 1071.12703146935, 1086.809808283563, 1088.1621151713296, 14620.853791462161, 15494.845051549566, 15566.377344906303, 15598.298076586581, 16520.75218871718, 670242.0, 676434.0, 676694.0, 677684.0, 679418.0, 57543.0, 58070.41929580735, 58135.0, 58195.70902145462, 58391.416085839446, 1228.9385530723428, 1263.0337791589625, 1346.5623672306344, 1376.2086800089987, 1393.7909313602838, 29413.85293073521, 30825.0, 30910.0726978191, 31114.844425777726, 32661.510077348383, 49310.013799724526, 49882.00235995332, 49965.0, 50081.49918500914, 50088.98754174346, 536039.0, 536553.634463666, 537677.0, 538263.0, 538744.0, 23399.0, 23842.64236036426, 25857.0, 25922.22233333003, 26403.867980659972, 45112.77443612761, 46053.53946460626, 46157.84605384892, 47574.76212618915, 47785.04429911313, 30797.424418228766, 38866.805665971486, 39021.60978390293, 40019.79990100031, 40341.0, 36789.44815827711, 38438.423423648106, 38546.03634909038, 39850.0, 39908.80045599753, 32526.83736581302, 32620.83689581537, 32707.0, 37237.069073273386, 37674.246515069, 31610.525842111925, 32086.19784505406, 32260.83869580637, 33960.320793584484, 34492.31015379728, 25031.249062528153, 25346.239612810907, 25368.98524059017, 25643.743562564883, 25995.350116247246], "value": 18400.095356412945, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7684927947555, 368.5190825972107, 369.31307767552363, 380.31163593895053, 399.73217943977534, 15045.247737613117, 15196.620084497894, 16118.355265789383, 20487.385378438634, 20921.0, 19432.514187145327, 19944.603877728565, 20379.49051273719, 20592.691109633368, 20921.895390523026, 5245.029669511134, 5672.234248376468, 5959.642421454705, 5969.820905372846, 5976.581639285252, 5792.884142317151, 5962.433568810956, 5975.402459754033, 5980.910286345708, 5983.1623572699855, 5595.21666966625, 5742.2247996520355, 5905.379935106816, 5916.437938395846, 5919.467247947684, 5739.569532285095, 5752.050911599602, 5791.218185544982, 5806.506446951972, 5807.216025836543, 4862.3922009748785, 5050.545450909399, 5078.771455284521, 5094.235864620299, 5123.692578445305, 25427.11005114812, 25629.231123066336, 25640.23079307605, 25699.74300256992, 25706.614400784005, 2207.4591725027312, 2312.849664771801, 2394.305651361103, 2498.912538061159, 2874.2814296425895, 15009.399624015026, 15183.544493665207, 15585.688286234212, 15700.68598628021, 15723.13522756248, 1791.480470663506, 1811.1035037656445, 1812.4381441753098, 1838.586318078435, 1844.317602487075, 102689.97310026879, 118953.0, 121289.0, 125099.0, 126682.43152037541, 10081.890991990875, 10254.692359229235, 10654.0, 10663.786724265472, 10877.782444351227, 13278.136921100098, 13326.600201993955, 13332.800007999887, 13337.933310333387, 13361.53234636783, 8083.0, 8386.287165590871, 8388.496690198608, 8551.273141782895, 8552.358573107042, 8435.987681478262, 8460.450070745383, 8469.872951905727, 8631.956840215884, 8660.913390866011, 6295.276043254993, 6448.3229260927865, 6464.77373291942, 6465.709043093072, 6590.868182636321, 1487.7693957436616, 1500.6248437890526, 1518.2105305241212, 1769.761082253905, 1799.9820001800076, 903.503073309677, 961.6682244625594, 964.5370222293338, 975.5902520941222, 990.7424069741919, 16891.408800691916, 19789.505262368268, 21691.13235470595, 21704.0, 21800.454988625093, 954.236610711428, 961.7884065505577, 962.7400601837464, 985.1281615770096, 1010.9949450252701, 9520.761980950394, 10760.97770711781, 11181.21731478805, 11388.658340249805, 11397.031252343479, 591832.0, 607016.0, 608106.0, 613669.0, 618549.0, 85954.57022714846, 91521.1585611487, 97968.0, 111078.0, 112719.0, 767.6430459836203, 881.0221053734407, 917.4724533393326, 942.8497233375272, 994.0357852882837, 18975.14611842498, 19557.21771129139, 19732.901335493232, 19797.40607781769, 19809.504762381057, 51024.74487627687, 51034.0, 51399.97200056053, 52334.0, 52839.0, 66945.66527167334, 67347.25506566807, 67440.0, 68860.6556967212, 69734.65132674304, 5186.040582492251, 5449.3460784706085, 7163.746344389815, 7646.235376462309, 7852.6073696313815, 12571.87428125707, 12718.427670754652, 12803.679908002374, 13156.342182891009, 13165.144265622705, 10785.946070269598, 10980.505877235704, 11141.16441266892, 11236.494357754083, 11467.541298347973, 10959.945200273947, 11256.887431125584, 11281.21031527785, 11309.604163854226, 11613.419329033684, 9525.761855953657, 11072.557097716, 11154.0, 11270.774584508428, 11597.130215233723, 7599.164091949991, 7668.961655191688, 7728.26581474759, 7793.181715919974, 8047.114817370201, 10806.891931080589, 10835.512401941774, 11141.997220250216, 11153.330800152016, 11158.832617510581], "value": 9456.48543950508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.4226194046264, 1536.5390382885134, 1735.9218835152417, 1842.2907180735413, 1915.6168766246744, 8610.354223433244, 9177.311701622371, 9177.770555736093, 9184.311176661744, 9186.356955013152, 8003.839923201533, 8264.876026859602, 8697.478151310926, 8937.597808098635, 8973.102689731024, 2062.9169685914903, 2259.559385919746, 2353.8352315337934, 2355.105060077177, 2355.893984770681, 2308.988455057731, 2338.8713620750937, 2358.13927916311, 2361.3624321433203, 2364.76352364763, 2347.0, 2357.3045951444365, 2358.3868194269444, 2361.315218586608, 2366.7751563601432, 2309.5265470578493, 2326.1858349577597, 2333.0667732906886, 2334.8248881333798, 2349.3186975777003, 2326.325365643961, 2329.650552417134, 2330.1378489958656, 2332.486852892378, 2333.0551126793675, 8946.150115738994, 9049.954750226207, 9068.501232432214, 9082.31882608807, 9085.591148398273, 5143.614228932845, 5323.228131920889, 5326.813561525328, 5346.518813306782, 5350.785968561253, 3480.1821571930577, 3518.700910422617, 3672.926541469156, 3677.632236776301, 3678.9632103678887, 1042.4162469017374, 1080.6974047266779, 1081.3836113415357, 1099.6261271167762, 1105.8838821923744, 11903.702407439796, 12024.158308918379, 12025.398730063462, 12031.51873925042, 12051.879481205076, 3726.7950262735267, 3896.980515097406, 4568.977155114269, 4787.569118779326, 4791.6406269529925, 10067.5469603868, 10145.9492702536, 10154.898451015542, 10160.983901609929, 10163.94918025405, 5209.140491818807, 5219.164933610604, 5306.734663266899, 5358.089124848785, 5374.731263436813, 4397.824087036547, 4423.579759922866, 4577.061702351077, 4843.515648435128, 4897.730624815598, 3519.982400087983, 3834.750741201813, 3843.2505661395758, 3862.671672907778, 3982.3230050891416, 1252.3174869696038, 1256.466001949168, 1268.695513076855, 1274.8908449648866, 1291.0446269760498, 817.7832874288318, 877.9648814047366, 904.7873749668822, 923.6397804856111, 952.8142012307673, 4915.336429582032, 5761.6254943429385, 5823.650580965155, 5964.642121472725, 5998.370171131968, 366.21081569218376, 433.43436814956783, 451.0775464175703, 452.111600704617, 458.53916813602115, 3914.6085391461197, 3918.2555314490155, 4015.377616469452, 4063.715539912178, 4087.754734715867, 9837.852432213522, 10747.193960452998, 10842.078423334106, 10849.783004340026, 10869.184811139196, 10469.790604187874, 10817.621383251548, 10833.02502774754, 10844.295120817122, 10874.401907894984, 8083.676652933935, 8278.130796266305, 8283.792905177299, 8293.967977845587, 8295.95852020736, 5721.370649228665, 5773.9895518284975, 5824.5340372770925, 5851.239338885917, 5909.497692696169, 9966.800663986825, 10042.297039207477, 10050.045245701647, 10079.294449388472, 10087.04173103554, 9816.349585611988, 10035.949820250851, 10051.69844904654, 10170.08469237772, 10290.3825770454, 6983.127109111361, 7240.963795181201, 7347.302006309392, 7348.412127029718, 7528.811779705551, 8393.28657064132, 8579.699710510102, 8589.699360522602, 8596.656133754579, 8601.139886011599, 7437.107547094383, 7541.2458754124145, 7673.155952845071, 7674.347680447225, 7710.421718371257, 7166.390856777232, 7288.635568221674, 7407.666655000646, 7466.962665186639, 7596.8480630385975, 6231.158793562811, 7115.359617634438, 7122.60825654594, 7122.928770712227, 7128.28717128283, 4925.556699896883, 5018.247262910592, 5109.08036553424, 5116.283720279238, 5130.461301563282, 4550.0, 4970.751462426936, 5070.670406423571, 5092.7777333440445, 5188.377394712658], "value": 4497.474069046704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.82686561197664, 299.34592914481846, 308.0942030430534, 313.9623245210575, 314.8803454687218, 4990.27640992056, 5134.178531434971, 5256.106461901476, 5277.788888444463, 5302.284191634129, 4941.851744447663, 5074.568661663763, 5081.263216833557, 5162.328897243363, 5259.710715910624, 4047.874865695504, 4062.8577999770014, 4075.918481630373, 4081.9795901020602, 4085.4893138357706, 3332.900012999614, 3348.64839191885, 3901.2782635212434, 4048.696347773914, 4091.8363265469493, 4009.83960641574, 4011.618896204856, 4029.8313489088127, 4030.3349947258666, 4031.979840100795, 3981.024648960994, 4016.4376987221813, 4017.598240175974, 4023.97988010061, 4029.435878976946, 3038.270815004383, 3480.89557313281, 3484.8083355415442, 3495.6329585393423, 3514.279572687593, 4023.456833327522, 4911.336969509106, 5038.672486288379, 5040.34475518184, 5067.353110239187, 3866.8786052044875, 4561.042181141964, 4570.382998295221, 4600.101935780471, 4614.700044497131, 5366.517013468807, 5443.591730620219, 5469.343678758574, 5471.23402723619, 5513.61404701671, 3389.5254664347012, 3471.6460580373673, 3589.8999085534992, 3713.721470889667, 3873.8572121224306, 5696.601237913306, 5699.544036477072, 5731.140328950607, 5735.139729040636, 5753.137029445575, 3331.4003479373946, 3915.569287378386, 3990.8004599770475, 4000.8199631016673, 4010.9197816043516, 3267.6895694908944, 3835.980820095882, 4002.3596224604507, 4004.5383435045997, 4030.2544029354663, 3678.7608805427562, 4463.977680111579, 4547.840825571089, 4548.727076375362, 4578.45058592971, 4303.892402689895, 4564.178447879413, 5490.313710786152, 5508.283923089973, 5521.282233309725, 1175.5826681527935, 1212.9454174562165, 1311.5278499740086, 1327.8381416260772, 1336.2316667915986, 784.3254800871246, 878.8813510176044, 896.9058248883904, 924.4915296586851, 927.2998885841147, 4270.35944608305, 4700.952990470051, 4779.904401912012, 4909.337239472697, 4950.499997501239, 4895.363602731693, 4900.852974410774, 4969.614584999221, 5261.579073674096, 5451.454854514517, 4058.0260737423396, 4239.8092085856215, 4449.777511124431, 5010.674306170087, 5097.566706829961, 2230.6765518999655, 2360.268316821791, 2446.3761740755995, 2448.4207686042405, 2452.3378687754207, 3807.485989391452, 3853.2871418787613, 4305.858947378947, 4644.953550464452, 4700.318453824245, 4129.628333450004, 4435.556444355539, 4475.70907890986, 4671.719696818202, 4712.7746785835925, 3632.7457078004813, 4498.8425405110665, 4651.744154071558, 4683.484816670231, 4760.428748550196, 3724.7149733341953, 4416.47002359706, 4534.390291446489, 4570.354672317896, 4576.6109880660515, 4232.0, 4535.0, 4566.977165114153, 4567.543245675406, 4597.310403439511, 4667.276572131325, 4700.130475861976, 4707.693999889996, 4721.677930179555, 4765.785539650793, 3327.933441331208, 3348.9330213396083, 3357.699975516779, 3361.630220675675, 3378.8141652209365, 3872.4578559001197, 4217.177650358273, 4477.126960242721, 4603.355530225704, 4626.32207395115, 4132.132252227066, 4456.821727130879, 4475.784486679115, 4493.438320209973, 4609.723416595015, 3690.763594195867, 4439.492800687421, 4451.243288641003, 4462.799174037111, 4523.547645235451, 4383.736975781463, 4689.554492323224, 5029.371328583927, 5078.644494885322, 5354.892902142014, 5050.823221187241, 5499.092649712753, 5502.5597952164535, 5534.446555344433, 5594.580406469449, 4561.201789686858, 4792.7363994980615, 5088.62318769724, 5156.736599533138, 5421.810236641699], "value": 3749.7329140004676, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.682964858274, 692.6536731634179, 703.5637904499212, 710.1726488640734, 850.851101057315, 15213.771793423108, 15581.220938953049, 15843.524694259162, 15871.285792139353, 15947.920260398696, 14209.502667406643, 14358.641033974156, 15014.774778378305, 15559.766603500957, 15719.13544755038, 843.2537204573965, 866.4541338956449, 867.453504292295, 868.5874209750381, 869.9173578510031, 683.6684208159064, 705.2418649951322, 708.2704814041546, 830.7092517618815, 854.9572521373938, 898.4294972692322, 901.7475106970059, 901.8737376767258, 902.6163880350857, 903.0382642485731, 897.833900728364, 899.757065592293, 900.5947323704327, 900.6577500549749, 900.7432881628704, 521.8069314353713, 534.871630808607, 646.8382904273932, 662.6951602262965, 669.4911866981081, 14022.298885055707, 14063.859361406357, 14064.437422503088, 14071.437142514389, 14096.71806563863, 13194.604161875159, 13361.732765344736, 13479.528216512375, 13504.0, 13930.164190148624, 12529.686757831036, 12871.292078935654, 13077.607671769767, 13115.672108197276, 13495.257760823151, 4400.251957167289, 4415.0, 4466.843660471868, 4569.657275704302, 4655.7439340836245, 112679.0, 114221.42889285584, 114292.0, 114383.0, 116215.0, 10567.0, 10658.733531661615, 10679.350800553191, 10936.781264374667, 11041.613543525938, 20851.687224691643, 20859.8957005214, 20870.58258834815, 20878.686819697716, 20881.791182088287, 7415.486359332302, 7577.810554736066, 7607.885881711779, 7756.069271687434, 7769.417293702995, 3994.7616238966016, 4193.7483750974425, 4197.160567886435, 4287.721298115613, 4343.1747967886, 3559.2525569630666, 3830.4637350770913, 4045.2516284487465, 4046.1300820323772, 4062.7968601569805, 654.8690261947629, 704.5068452083563, 707.1161048689139, 729.4018904498366, 734.772220611612, 560.9411011843779, 595.7110801261384, 600.159776313165, 617.9938200618026, 665.125360151409, 8979.595918183697, 9178.35751497402, 9215.861762073575, 9368.34421590482, 9434.764130896645, 592.8725324055263, 597.4205021129497, 626.3736263736264, 656.6585375604659, 683.2006552333683, 4575.519570445122, 4583.381243532081, 4614.676972611885, 4639.313755734461, 4656.720596764205, 160335.0, 164242.1787891053, 164557.0, 164581.0, 167315.0, 138762.0, 139705.0, 141991.0, 144602.0, 145428.0, 93171.0, 119013.40493297478, 119888.01679747933, 120625.39687301683, 121333.0, 8531.061583225715, 8734.432261902955, 8750.0, 8797.956010219908, 9012.729618111467, 66750.66624666845, 67598.98601520882, 67642.66178669075, 70056.24859378353, 70061.0, 67523.64952701017, 77570.44859102674, 80487.0, 87950.56024719836, 93742.0, 10935.0, 13187.73624527485, 13256.469741210629, 13273.0, 13508.459661613426, 14776.77834832457, 15092.924535377253, 15106.169160696267, 15432.219106318575, 15442.30509627092, 13396.665083372995, 13497.86502134966, 13973.021888468116, 14249.287535623385, 14279.64300892486, 13661.590152295446, 13705.520306789214, 14175.36210870534, 14482.637934051732, 14722.043067200597, 13096.0, 13291.66770830737, 13433.26117063571, 13690.86309136896, 13786.862131378557, 10944.39805810688, 11108.277961932448, 11211.439428028729, 11476.540938362372, 11774.411279436164, 11147.944260278648, 11280.0, 11642.41787910618, 11666.76666466649, 11674.70813229676], "value": 7889.814747713061, "unit": "score"}], "name": "dromaeo_css", "value": 7128.757160646886, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 120.0, 167.0, 145.0, 111.0, 112.0, 111.0, 112.0, 112.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 113.0, 117.0, 173.0, 103.0, 133.0, 140.0, 143.0, 144.0, 113.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 184.0, 185.0, 182.0, 184.0, 182.0, 181.0, 185.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 57.0, 90.0, 60.0, 58.0, 58.0, 59.0, 82.0, 59.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 90.0, 69.0, 69.0, 69.0, 92.0, 68.0, 68.0, 90.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 131.0, 131.0, 87.0, 88.0, 85.0, 88.0, 87.0, 88.0, 85.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 86.0, 86.0, 83.0, 85.0, 84.0, 85.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 51.0, 53.0, 53.0, 54.0, 55.0, 53.0, 54.0, 54.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 75.0, 75.0, 71.0, 68.0, 68.0, 68.0, 69.0, 72.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 91.0, 87.0, 88.0, 90.0, 86.0, 91.0, 94.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 158.0, 145.0, 146.0, 135.0, 153.0, 153.0, 137.0, 201.0, 131.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 235.0, 206.0, 171.0, 231.0, 166.0, 214.0, 233.0, 185.0, 170.0], "value": 198.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 69.0, 68.0, 70.0, 68.0, 68.0, 70.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1487.1999999999998, "alertThreshold": 2.0}]} 04:36:18 INFO - Return code: 0 04:36:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:18 INFO - # TBPL SUCCESS # 04:36:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:36:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:36:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:36:18 INFO - 2016-10-05 04:36:18.132 screenresolution[1667:10077] starting screenresolution argv=screenresolution get 04:36:18 INFO - 2016-10-05 04:36:18.136 screenresolution[1667:10077] Display 0: 1600x1200x32@60 04:36:18 INFO - 2016-10-05 04:36:18.143 screenresolution[1668:10080] starting screenresolution argv=screenresolution list 04:36:18 INFO - Available Modes on Display 0 04:36:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:36:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:36:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:36:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:36:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:36:18 INFO - Intel Iris: 04:36:18 INFO - Chipset Model: Intel Iris 04:36:18 INFO - Type: GPU 04:36:18 INFO - Bus: Built-In 04:36:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:36:18 INFO - Vendor: Intel (0x8086) 04:36:18 INFO - Device ID: 0x0a2e 04:36:18 INFO - Revision ID: 0x0009 04:36:18 INFO - Displays: 04:36:18 INFO - Display: 04:36:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:36:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:36:18 INFO - Display Serial Number: 1600x1200 60 04:36:18 INFO - Main Display: Yes 04:36:18 INFO - Mirror: Off 04:36:18 INFO - Online: Yes 04:36:18 INFO - Rotation: Supported 04:36:18 INFO - Return code: 0 04:36:18 INFO - Running post-action listener: _resource_record_post_action 04:36:18 INFO - [mozharness: 2016-10-05 11:36:18.381004Z] Finished run-tests step (success) 04:36:18 INFO - Running post-run listener: _resource_record_post_run 04:36:18 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 38908928; Write bytes: 707613184; Read time: 385; Write time: 1930 04:36:18 INFO - TinderboxPrint: CPU usage
53.7% 04:36:18 INFO - TinderboxPrint: I/O read bytes / time
38,908,928 / 385 04:36:18 INFO - TinderboxPrint: I/O write bytes / time
707,613,184 / 1,930 04:36:18 INFO - TinderboxPrint: CPU idle
2,250.7 (46.3%) 04:36:18 INFO - TinderboxPrint: CPU system
973.7 (20.0%) 04:36:18 INFO - TinderboxPrint: CPU user
1,637.2 (33.7%) 04:36:18 INFO - TinderboxPrint: Swap in / out
446,263,296 / 0 04:36:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400605696; Write bytes: 394684416; Read time: 29677; Write time: 753 04:36:18 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36545536; Write bytes: 296081920; Read time: 356; Write time: 1138 04:36:18 INFO - Running post-run listener: _upload_blobber_files 04:36:18 INFO - Blob upload gear active. 04:36:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:36:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:36:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:36:19 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:36:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:36:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:20 INFO - (blobuploader) - INFO - Done attempting. 04:36:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:36:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:36:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:21 INFO - (blobuploader) - INFO - Done attempting. 04:36:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:23 INFO - (blobuploader) - INFO - Done attempting. 04:36:23 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:23 INFO - Return code: 0 04:36:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1e8f0f495c1f26923bbc8f6284a3dd9760fcddfc0ca7e14379957d6421ff6c7da6a26372fe76d36ecdb7a3f9e0e6a8bc3e6ad87aa2c30a5177db0e7e31e0c08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/626a532d9f01a39dbb5934c64bd43e25808fb082177b3cead1b9fcfd212c7d2549e9244cbaf6c683608df08b94f2219758eaee050163bfec985eb55bf01694e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1030629b71a584d20892c70e8c8afe374657043c031614a9ce7bd00ec09c19b0d50b7d077b892ab75bf05a126a071b363901f89fc19745f5aeacad65601d59c1"} 04:36:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:23 INFO - Contents: 04:36:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1e8f0f495c1f26923bbc8f6284a3dd9760fcddfc0ca7e14379957d6421ff6c7da6a26372fe76d36ecdb7a3f9e0e6a8bc3e6ad87aa2c30a5177db0e7e31e0c08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/626a532d9f01a39dbb5934c64bd43e25808fb082177b3cead1b9fcfd212c7d2549e9244cbaf6c683608df08b94f2219758eaee050163bfec985eb55bf01694e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1030629b71a584d20892c70e8c8afe374657043c031614a9ce7bd00ec09c19b0d50b7d077b892ab75bf05a126a071b363901f89fc19745f5aeacad65601d59c1"} 04:36:23 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:23 INFO - Copying logs to upload dir... 04:36:23 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.618816 ========= master_lag: 2.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-05 04:36:26.184599) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 04:36:26.188174) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgvSUvHndU/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1e8f0f495c1f26923bbc8f6284a3dd9760fcddfc0ca7e14379957d6421ff6c7da6a26372fe76d36ecdb7a3f9e0e6a8bc3e6ad87aa2c30a5177db0e7e31e0c08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/626a532d9f01a39dbb5934c64bd43e25808fb082177b3cead1b9fcfd212c7d2549e9244cbaf6c683608df08b94f2219758eaee050163bfec985eb55bf01694e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1030629b71a584d20892c70e8c8afe374657043c031614a9ce7bd00ec09c19b0d50b7d077b892ab75bf05a126a071b363901f89fc19745f5aeacad65601d59c1"} build_url:https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/VhXpMsZCRI6fxFqzfz0fzA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1e8f0f495c1f26923bbc8f6284a3dd9760fcddfc0ca7e14379957d6421ff6c7da6a26372fe76d36ecdb7a3f9e0e6a8bc3e6ad87aa2c30a5177db0e7e31e0c08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/626a532d9f01a39dbb5934c64bd43e25808fb082177b3cead1b9fcfd212c7d2549e9244cbaf6c683608df08b94f2219758eaee050163bfec985eb55bf01694e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1030629b71a584d20892c70e8c8afe374657043c031614a9ce7bd00ec09c19b0d50b7d077b892ab75bf05a126a071b363901f89fc19745f5aeacad65601d59c1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 04:36:26.220519) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:36:26.220862) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgvSUvHndU/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mYVpyAD8Vs/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005304 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:36:26.269608) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 04:36:26.269933) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 04:36:26.270245) ========= ========= Total master_lag: 2.54 =========