builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0257 starttime: 1487094353.26 results: success (0) buildid: 20170214070945 builduid: 15eb97c329c14a38b448e071f7a15064 revision: 3a0e9dab386403cec5b899eb7f321c2aa27e0186 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.260515) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.260987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.261331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.291856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.292212) ========= 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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 --2017-02-14 09:45:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.0M=0s 2017-02-14 09:45:53 (24.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152862 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.472536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.473209) ========= 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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.025392 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:53.525713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-14 09:45:53.526294) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3a0e9dab386403cec5b899eb7f321c2aa27e0186 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3a0e9dab386403cec5b899eb7f321c2aa27e0186 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lPWUFfCKVS/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.sQbEHNkXcj/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 2017-02-14 09:45:53,630 truncating revision to first 12 chars 2017-02-14 09:45:53,630 Setting DEBUG logging. 2017-02-14 09:45:53,630 attempt 1/10 2017-02-14 09:45:53,630 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3a0e9dab3864?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-14 09:45:54,850 unpacking tar archive at: mozilla-beta-3a0e9dab3864/testing/mozharness/ program finished with exit code 0 elapsedTime=1.739668 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-14 09:45:55.293955) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:55.294714) ========= script_repo_revision: 3a0e9dab386403cec5b899eb7f321c2aa27e0186 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:55.295525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:55.296106) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 09:45:55.317837) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-02-14 09:45:55.318450) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lPWUFfCKVS/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.sQbEHNkXcj/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:45:55 INFO - MultiFileLogger online at 20170214 09:45:55 in /builds/slave/test 09:45:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:45:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:45:55 INFO - {'append_to_log': False, 09:45:55 INFO - 'base_work_dir': '/builds/slave/test', 09:45:55 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:45:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:45:55 INFO - 'branch': 'Mozilla-Beta', 09:45:55 INFO - 'buildbot_json_path': 'buildprops.json', 09:45:55 INFO - 'config_files': ('talos/mac_config.py',), 09:45:55 INFO - 'default_actions': ('clobber', 09:45:55 INFO - 'read-buildbot-config', 09:45:55 INFO - 'download-and-extract', 09:45:55 INFO - 'populate-webroot', 09:45:55 INFO - 'create-virtualenv', 09:45:55 INFO - 'install', 09:45:55 INFO - 'run-tests'), 09:45:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:45:55 INFO - 'download_minidump_stackwalk': True, 09:45:55 INFO - 'download_symbols': 'ondemand', 09:45:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:45:55 INFO - 'tooltool.py': '/tools/tooltool.py', 09:45:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:45:55 INFO - '/tools/misc-python/virtualenv.py')}, 09:45:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:45:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:45:55 INFO - 'installer_path': 'installer.exe', 09:45:55 INFO - 'log_level': 'info', 09:45:55 INFO - 'log_name': 'talos', 09:45:55 INFO - 'log_to_console': True, 09:45:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:45:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:45:55 INFO - 'opt_config_files': (), 09:45:55 INFO - 'pip_index': False, 09:45:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:45:55 INFO - 'cmd': ('bash', 09:45:55 INFO - '-c', 09:45:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:45:55 INFO - 'enabled': True, 09:45:55 INFO - 'halt_on_failure': False, 09:45:55 INFO - 'name': 'check_screen_resolution'},), 09:45:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:45:55 INFO - 'cmd': ('bash', 09:45:55 INFO - '-c', 09:45:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:45:55 INFO - 'enabled': True, 09:45:55 INFO - 'halt_on_failure': False, 09:45:55 INFO - 'name': 'check_screen_resolution'},), 09:45:55 INFO - 'run_cmd_checks_enabled': True, 09:45:55 INFO - 'sps_profile': False, 09:45:55 INFO - 'sps_profile_interval': 0, 09:45:55 INFO - 'suite': 'dromaeojs-e10s', 09:45:55 INFO - 'system_bits': '32', 09:45:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:45:55 INFO - 'title': 't-yosemite-r7-0257', 09:45:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:45:55 INFO - 'use_talos_json': True, 09:45:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:45:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:45:55 INFO - 'work_dir': 'build'} 09:45:55 INFO - [mozharness: 2017-02-14 17:45:55.531524Z] Running clobber step. 09:45:55 INFO - Running pre-action listener: _resource_record_pre_action 09:45:55 INFO - Running main action method: clobber 09:45:55 INFO - rmtree: /builds/slave/test/build 09:45:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:45:57 INFO - Running post-action listener: _resource_record_post_action 09:45:57 INFO - [mozharness: 2017-02-14 17:45:57.978210Z] Finished clobber step (success) 09:45:57 INFO - [mozharness: 2017-02-14 17:45:57.978400Z] Running read-buildbot-config step. 09:45:57 INFO - Running pre-action listener: _resource_record_pre_action 09:45:57 INFO - Running main action method: read_buildbot_config 09:45:57 INFO - Using buildbot properties: 09:45:57 INFO - { 09:45:57 INFO - "project": "", 09:45:57 INFO - "product": "firefox", 09:45:57 INFO - "script_repo_revision": "production", 09:45:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:45:57 INFO - "repository": "", 09:45:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:45:57 INFO - "buildid": "20170214070945", 09:45:57 INFO - "pgo_build": "False", 09:45:57 INFO - "basedir": "/builds/slave/test", 09:45:57 INFO - "buildnumber": 61, 09:45:57 INFO - "slavename": "t-yosemite-r7-0257", 09:45:57 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:45:57 INFO - "platform": "yosemite_r7", 09:45:57 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:45:57 INFO - "branch": "mozilla-beta", 09:45:57 INFO - "repo_path": "releases/mozilla-beta", 09:45:57 INFO - "revision": "3a0e9dab386403cec5b899eb7f321c2aa27e0186", 09:45:57 INFO - "stage_platform": "macosx64", 09:45:57 INFO - "builduid": "15eb97c329c14a38b448e071f7a15064", 09:45:57 INFO - "slavebuilddir": "test" 09:45:57 INFO - } 09:45:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 09:45:57 INFO - Running post-action listener: _resource_record_post_action 09:45:57 INFO - [mozharness: 2017-02-14 17:45:57.982529Z] Finished read-buildbot-config step (success) 09:45:57 INFO - [mozharness: 2017-02-14 17:45:57.982706Z] Running download-and-extract step. 09:45:57 INFO - Running pre-action listener: _resource_record_pre_action 09:45:57 INFO - Running main action method: download_and_extract 09:45:57 INFO - mkdir: /builds/slave/test/build/tests 09:45:57 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')]} 09:45:57 INFO - trying https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 09:45:57 INFO - Downloading https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 09:45:58 INFO - Downloaded 1165 bytes. 09:45:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:45:58 INFO - Using the following test package requirements: 09:45:58 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:45:58 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 09:45:58 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 09:45:58 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:45:58 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 09:45:58 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:45:58 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 09:45:58 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 09:45:58 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 09:45:58 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:45:58 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 09:45:58 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 09:45:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:45:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:45:58 INFO - Fetch https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:45:59 INFO - Http code: 200 09:45:59 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 09:45:59 INFO - x-amz-cf-id: 29q4zhljLGJQUc74nMedb1atjwKoWMRWx_7TnHIz5dcANflqKS_IXQ== 09:45:59 INFO - x-amz-version-id: fcHQCB3lps82cGuN4Ld9cpiPR1Zgkm1z 09:46:00 INFO - Expected file size: 16094119 09:46:00 INFO - Obtained file size: 16094119 09:46:06 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:46:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:46:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:46:06 INFO - Fetch https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:46:07 INFO - Http code: 200 09:46:07 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 09:46:07 INFO - x-amz-cf-id: aHjIRf6v6srqV7KAUeVNCON1aBaRo5RimUWZpVJDGeoSbPy1hJT13A== 09:46:07 INFO - x-amz-version-id: fcHQCB3lps82cGuN4Ld9cpiPR1Zgkm1z 09:46:07 INFO - Expected file size: 16094119 09:46:07 INFO - Obtained file size: 16094119 09:46:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 09:46:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 09:46:14 INFO - Fetch https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 09:46:14 INFO - Http code: 200 09:46:14 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:46:14 INFO - x-amz-cf-id: Lnhp5ci1h7iHVw-YRWCrb3P6sr7Z9QzpuimOR3CrMePwcJaoAwyaDA== 09:46:14 INFO - x-amz-version-id: 8R8Qv0fRQmxtvazaEJo1GFgQN3N3kxcg 09:46:15 INFO - Expected file size: 14403005 09:46:15 INFO - Obtained file size: 14403005 09:46:15 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')]} 09:46:15 INFO - trying https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:46:15 INFO - Downloading https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 09:46:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 09:46:18 INFO - Downloaded 82111699 bytes. 09:46:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:46:18 INFO - mkdir: /builds/slave/test/properties 09:46:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:46:18 INFO - Writing to file /builds/slave/test/properties/build_url 09:46:18 INFO - Contents: 09:46:18 INFO - build_url:https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:46:19 INFO - Running post-action listener: _resource_record_post_action 09:46:19 INFO - Running post-action listener: set_extra_try_arguments 09:46:19 INFO - [mozharness: 2017-02-14 17:46:19.540997Z] Finished download-and-extract step (success) 09:46:19 INFO - [mozharness: 2017-02-14 17:46:19.541136Z] Running populate-webroot step. 09:46:19 INFO - Running pre-action listener: _resource_record_pre_action 09:46:19 INFO - Running main action method: populate_webroot 09:46:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:46:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:46:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:46:19 INFO - '--tppagecycles', 09:46:19 INFO - '7'], 09:46:19 INFO - 'tests': ['tsvgm']}}, 09:46:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:46:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:46:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:46:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:46:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['damp', 'tps']}, 09:46:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'tests': ['damp', 'tps']}, 09:46:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['dromaeo_dom']}, 09:46:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:46:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['basic_compositor_video']}, 09:46:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:46:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['a11yr', 09:46:19 INFO - 'ts_paint', 09:46:19 INFO - 'tpaint', 09:46:19 INFO - 'sessionrestore', 09:46:19 INFO - 'sessionrestore_no_auto_restore', 09:46:19 INFO - 'tabpaint']}, 09:46:19 INFO - 'other-e10s': {'tests': ['a11yr', 09:46:19 INFO - 'ts_paint', 09:46:19 INFO - 'tpaint', 09:46:19 INFO - 'sessionrestore', 09:46:19 INFO - 'sessionrestore_no_auto_restore', 09:46:19 INFO - 'tabpaint']}, 09:46:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:46:19 INFO - 'ts_paint', 09:46:19 INFO - 'tpaint', 09:46:19 INFO - 'sessionrestore', 09:46:19 INFO - 'sessionrestore_no_auto_restore', 09:46:19 INFO - 'tabpaint']}, 09:46:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:46:19 INFO - 'ts_paint', 09:46:19 INFO - 'tpaint', 09:46:19 INFO - 'sessionrestore', 09:46:19 INFO - 'sessionrestore_no_auto_restore', 09:46:19 INFO - 'tabpaint']}, 09:46:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['a11yr', 09:46:19 INFO - 'ts_paint', 09:46:19 INFO - 'tpaint', 09:46:19 INFO - 'sessionrestore', 09:46:19 INFO - 'sessionrestore_no_auto_restore', 09:46:19 INFO - 'tabpaint']}, 09:46:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['a11yr', 09:46:19 INFO - 'ts_paint', 09:46:19 INFO - 'tpaint', 09:46:19 INFO - 'sessionrestore', 09:46:19 INFO - 'sessionrestore_no_auto_restore', 09:46:19 INFO - 'tabpaint']}, 09:46:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['tsvgx', 09:46:19 INFO - 'tsvgr_opacity', 09:46:19 INFO - 'tart', 09:46:19 INFO - 'tscrollx', 09:46:19 INFO - 'cart']}, 09:46:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:46:19 INFO - 'tsvgr_opacity', 09:46:19 INFO - 'tart', 09:46:19 INFO - 'tscrollx', 09:46:19 INFO - 'cart']}, 09:46:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'talos_options': ['--disable-e10s'], 09:46:19 INFO - 'tests': ['tp5o']}, 09:46:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'tests': ['tp5o']}, 09:46:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'talos_options': ['--disable-e10s', 09:46:19 INFO - '--xperf_path', 09:46:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:19 INFO - 'tests': ['tp5n']}, 09:46:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:19 INFO - 'talos_options': ['--xperf_path', 09:46:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:19 INFO - 'tests': ['tp5n']}}, 09:46:19 INFO - 'talos.zip': {'path': '', 09:46:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:46:19 INFO - Running post-action listener: _resource_record_post_action 09:46:19 INFO - [mozharness: 2017-02-14 17:46:19.559845Z] Finished populate-webroot step (success) 09:46:19 INFO - [mozharness: 2017-02-14 17:46:19.559931Z] Running create-virtualenv step. 09:46:19 INFO - Running pre-action listener: _resource_record_pre_action 09:46:19 INFO - Running main action method: create_virtualenv 09:46:19 INFO - Creating virtualenv /builds/slave/test/build/venv 09:46:19 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 09:46:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:46:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:46:19 INFO - Using real prefix '/tools/python27' 09:46:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:46:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:46:23 INFO - Installing pip.................done. 09:46:23 INFO - Return code: 0 09:46:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:46:23 INFO - Reading from file tmpfile_stdout 09:46:23 INFO - Output received: 09:46:23 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) 09:46:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:46:23 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')]} 09:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:23 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')]} 09:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:23 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:23 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 09:46:23 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 09:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:23 INFO - 'HOME': '/Users/cltbld', 09:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:23 INFO - 'LOGNAME': 'cltbld', 09:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:23 INFO - 'MOZ_NO_REMOTE': '1', 09:46:23 INFO - 'NO_EM_RESTART': '1', 09:46:23 INFO - 'PAGER': '/bin/cat', 09:46:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:23 INFO - 'PWD': '/builds/slave/test', 09:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:23 INFO - 'SHELL': '/bin/bash', 09:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:23 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:23 INFO - 'USER': 'cltbld', 09:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:23 INFO - 'XPC_FLAGS': '0x0', 09:46:23 INFO - 'XPC_SERVICE_NAME': '0', 09:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:23 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 09:46:23 INFO - Cleaning up... 09:46:23 INFO - Return code: 0 09:46:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:46:23 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')]} 09:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:23 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')]} 09:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:23 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:23 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 09:46:23 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 09:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:23 INFO - 'HOME': '/Users/cltbld', 09:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:23 INFO - 'LOGNAME': 'cltbld', 09:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:23 INFO - 'MOZ_NO_REMOTE': '1', 09:46:23 INFO - 'NO_EM_RESTART': '1', 09:46:23 INFO - 'PAGER': '/bin/cat', 09:46:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:23 INFO - 'PWD': '/builds/slave/test', 09:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:23 INFO - 'SHELL': '/bin/bash', 09:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:23 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:23 INFO - 'USER': 'cltbld', 09:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:23 INFO - 'XPC_FLAGS': '0x0', 09:46:23 INFO - 'XPC_SERVICE_NAME': '0', 09:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:24 INFO - Downloading/unpacking psutil>=3.1.1 09:46:24 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 09:46:24 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 09:46:24 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 09:46:24 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 09:46:24 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 09:46:24 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 09:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:46:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:27 INFO - Installing collected packages: psutil 09:46:27 INFO - Running setup.py install for psutil 09:46:27 INFO - building 'psutil._psutil_osx' extension 09:46:27 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 09:46:28 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 09:46:28 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 09:46:28 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 09:46:28 INFO - building 'psutil._psutil_posix' extension 09:46:28 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 09:46:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:46:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:46:28 INFO - ^ 09:46:28 INFO - 1 warning generated. 09:46:28 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 09:46:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:28 INFO - Successfully installed psutil 09:46:28 INFO - Cleaning up... 09:46:28 INFO - Return code: 0 09:46:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:46:28 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')]} 09:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:28 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')]} 09:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:28 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:28 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 09:46:28 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 09:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:28 INFO - 'HOME': '/Users/cltbld', 09:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:28 INFO - 'LOGNAME': 'cltbld', 09:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:28 INFO - 'MOZ_NO_REMOTE': '1', 09:46:28 INFO - 'NO_EM_RESTART': '1', 09:46:28 INFO - 'PAGER': '/bin/cat', 09:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:28 INFO - 'PWD': '/builds/slave/test', 09:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:28 INFO - 'SHELL': '/bin/bash', 09:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:28 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:28 INFO - 'USER': 'cltbld', 09:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:28 INFO - 'XPC_FLAGS': '0x0', 09:46:28 INFO - 'XPC_SERVICE_NAME': '0', 09:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:46:28 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 09:46:28 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 09:46:28 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 09:46:28 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 09:46:28 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 09:46:28 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 09:46:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:46:31 INFO - Installing collected packages: mozsystemmonitor 09:46:31 INFO - Running setup.py install for mozsystemmonitor 09:46:31 INFO - Successfully installed mozsystemmonitor 09:46:31 INFO - Cleaning up... 09:46:31 INFO - Return code: 0 09:46:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:46:31 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')]} 09:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:31 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')]} 09:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:31 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:31 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 09:46:31 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 09:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:31 INFO - 'HOME': '/Users/cltbld', 09:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:31 INFO - 'LOGNAME': 'cltbld', 09:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:31 INFO - 'MOZ_NO_REMOTE': '1', 09:46:31 INFO - 'NO_EM_RESTART': '1', 09:46:31 INFO - 'PAGER': '/bin/cat', 09:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:31 INFO - 'PWD': '/builds/slave/test', 09:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:31 INFO - 'SHELL': '/bin/bash', 09:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:31 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:31 INFO - 'USER': 'cltbld', 09:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:31 INFO - 'XPC_FLAGS': '0x0', 09:46:31 INFO - 'XPC_SERVICE_NAME': '0', 09:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:32 INFO - Downloading/unpacking jsonschema==2.5.1 09:46:32 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 09:46:32 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 09:46:32 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 09:46:32 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 09:46:32 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 09:46:32 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 09:46:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:46:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:46: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 09:46: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 09:46: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 09:46: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 09:46: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 09:46: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 09:46:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:46:35 INFO - warning: no files found matching '*.txt' 09:46:35 INFO - no previously-included directories found matching 'build' 09:46:35 INFO - no previously-included directories found matching 'dist' 09:46:35 INFO - no previously-included directories found matching '.git*' 09:46:35 INFO - Installing collected packages: jsonschema, functools32 09:46:35 INFO - Running setup.py install for functools32 09:46:35 INFO - warning: no files found matching '*.txt' 09:46:35 INFO - no previously-included directories found matching 'build' 09:46:35 INFO - no previously-included directories found matching 'dist' 09:46:35 INFO - no previously-included directories found matching '.git*' 09:46:35 INFO - Successfully installed jsonschema functools32 09:46:35 INFO - Cleaning up... 09:46:35 INFO - Return code: 0 09:46:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:46:35 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')]} 09:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:35 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')]} 09:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:35 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:35 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 09:46:35 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 09:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:35 INFO - 'HOME': '/Users/cltbld', 09:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:35 INFO - 'LOGNAME': 'cltbld', 09:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:35 INFO - 'MOZ_NO_REMOTE': '1', 09:46:35 INFO - 'NO_EM_RESTART': '1', 09:46:35 INFO - 'PAGER': '/bin/cat', 09:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:35 INFO - 'PWD': '/builds/slave/test', 09:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:35 INFO - 'SHELL': '/bin/bash', 09:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:35 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:35 INFO - 'USER': 'cltbld', 09:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:35 INFO - 'XPC_FLAGS': '0x0', 09:46:35 INFO - 'XPC_SERVICE_NAME': '0', 09:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:46:35 INFO - Cleaning up... 09:46:35 INFO - Return code: 0 09:46:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:46:35 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')]} 09:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:35 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')]} 09:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:35 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:35 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 09:46:35 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 09:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:35 INFO - 'HOME': '/Users/cltbld', 09:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:35 INFO - 'LOGNAME': 'cltbld', 09:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:35 INFO - 'MOZ_NO_REMOTE': '1', 09:46:35 INFO - 'NO_EM_RESTART': '1', 09:46:35 INFO - 'PAGER': '/bin/cat', 09:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:35 INFO - 'PWD': '/builds/slave/test', 09:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:35 INFO - 'SHELL': '/bin/bash', 09:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:35 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:35 INFO - 'USER': 'cltbld', 09:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:35 INFO - 'XPC_FLAGS': '0x0', 09:46:35 INFO - 'XPC_SERVICE_NAME': '0', 09:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:46:36 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 09:46:36 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 09:46:36 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 09:46:36 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 09:46:36 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 09:46:36 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 09:46:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:46:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:46:38 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 09:46:38 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 09:46:38 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 09:46:38 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 09:46:38 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 09:46:38 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 09:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:46:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:46: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 09:46: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 09:46: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 09:46: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 09:46: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 09:46: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 09:46:39 INFO - Downloading docopt-0.6.1.tar.gz 09:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:46:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:46:40 INFO - Running setup.py install for blobuploader 09:46:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:46:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:46:40 INFO - Running setup.py install for requests 09:46:40 INFO - Running setup.py install for docopt 09:46:40 INFO - Successfully installed blobuploader requests docopt 09:46:40 INFO - Cleaning up... 09:46:40 INFO - Return code: 0 09:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:40 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')]} 09:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:40 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')]} 09:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:40 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:40 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 09:46:40 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 09:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:40 INFO - 'HOME': '/Users/cltbld', 09:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:40 INFO - 'LOGNAME': 'cltbld', 09:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:40 INFO - 'MOZ_NO_REMOTE': '1', 09:46:40 INFO - 'NO_EM_RESTART': '1', 09:46:40 INFO - 'PAGER': '/bin/cat', 09:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:40 INFO - 'PWD': '/builds/slave/test', 09:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:40 INFO - 'SHELL': '/bin/bash', 09:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:40 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:40 INFO - 'USER': 'cltbld', 09:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:40 INFO - 'XPC_FLAGS': '0x0', 09:46:40 INFO - 'XPC_SERVICE_NAME': '0', 09:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-iVVQwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-T4Ngtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-0sewAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-I41yCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-2lYAtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-grw9fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-JbbJSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:46:41 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-CdmGjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-5sfvi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-lp_qr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-tYGan2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-1iByJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-aEIPdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-WqNuna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-w9gxfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-LaCsVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:46:42 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-l5clCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:46:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:46:43 INFO - Running setup.py install for manifestparser 09:46:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:46:43 INFO - Running setup.py install for mozcrash 09:46:43 INFO - Running setup.py install for mozdebug 09:46:43 INFO - Running setup.py install for mozdevice 09:46:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:46:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:46:43 INFO - Running setup.py install for mozfile 09:46:43 INFO - Running setup.py install for mozhttpd 09:46:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:46:43 INFO - Running setup.py install for mozinfo 09:46:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Running setup.py install for mozInstall 09:46:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Running setup.py install for mozleak 09:46:44 INFO - Running setup.py install for mozlog 09:46:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Running setup.py install for moznetwork 09:46:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Running setup.py install for mozprocess 09:46:44 INFO - Running setup.py install for mozprofile 09:46:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:46:44 INFO - Running setup.py install for mozrunner 09:46:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:46:45 INFO - Running setup.py install for mozscreenshot 09:46:45 INFO - Running setup.py install for moztest 09:46:45 INFO - Running setup.py install for mozversion 09:46:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:46:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:46:45 INFO - Cleaning up... 09:46:45 INFO - Return code: 0 09:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:45 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')]} 09:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:45 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')]} 09:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:45 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:45 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 09:46:45 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 09:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:45 INFO - 'HOME': '/Users/cltbld', 09:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:45 INFO - 'LOGNAME': 'cltbld', 09:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:45 INFO - 'MOZ_NO_REMOTE': '1', 09:46:45 INFO - 'NO_EM_RESTART': '1', 09:46:45 INFO - 'PAGER': '/bin/cat', 09:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:45 INFO - 'PWD': '/builds/slave/test', 09:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:45 INFO - 'SHELL': '/bin/bash', 09:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:45 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:45 INFO - 'USER': 'cltbld', 09:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:45 INFO - 'XPC_FLAGS': '0x0', 09:46:45 INFO - 'XPC_SERVICE_NAME': '0', 09:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:46:45 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-obcSxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:46:45 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)) 09:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:46:45 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-cQxyJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-YmTyhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-xmcCHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-VVg0TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-oK3T9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-fmQQ5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-wu55yJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ND4TyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-aLpYgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:46:46 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)) 09:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:46:46 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-nUJ6bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:46:47 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)) 09:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:46:47 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-S7oewY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:46:47 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)) 09:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:46:47 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-lBQOmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:46:47 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)) 09:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:46:47 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-o_40rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:47 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-y20zQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:47 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)) 09:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:46:47 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-z3qkSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:46:47 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-NnO1qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:46:47 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)) 09:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:46:47 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)) 09:46:47 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)) 09:46:47 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)) 09:46:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:46:47 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 09:46:47 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 09:46:47 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 09:46:47 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 09:46:47 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 09:46:47 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 09:46:50 INFO - Downloading blessings-1.6.tar.gz 09:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:46:50 INFO - Installing collected packages: blessings 09:46:50 INFO - Running setup.py install for blessings 09:46:50 INFO - Successfully installed blessings 09:46:50 INFO - Cleaning up... 09:46:50 INFO - Return code: 0 09:46:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:46:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:46:50 INFO - Reading from file tmpfile_stdout 09:46:50 INFO - Current package versions: 09:46:50 INFO - blessings == 1.6 09:46:50 INFO - blobuploader == 1.2.4 09:46:50 INFO - docopt == 0.6.1 09:46:50 INFO - functools32 == 3.2.3-2 09:46:50 INFO - jsonschema == 2.5.1 09:46:50 INFO - manifestparser == 1.1 09:46:50 INFO - mozInstall == 1.12 09:46:50 INFO - mozcrash == 1.0 09:46:50 INFO - mozdebug == 0.1 09:46:50 INFO - mozdevice == 0.48 09:46:50 INFO - mozfile == 1.2 09:46:50 INFO - mozhttpd == 0.7 09:46:50 INFO - mozinfo == 0.9 09:46:50 INFO - mozleak == 0.1 09:46:50 INFO - mozlog == 3.4 09:46:50 INFO - moznetwork == 0.27 09:46:50 INFO - mozprocess == 0.23 09:46:50 INFO - mozprofile == 0.28 09:46:50 INFO - mozrunner == 6.13 09:46:50 INFO - mozscreenshot == 0.1 09:46:50 INFO - mozsystemmonitor == 0.3 09:46:50 INFO - moztest == 0.8 09:46:50 INFO - mozversion == 1.4 09:46:50 INFO - psutil == 3.1.1 09:46:50 INFO - requests == 1.2.3 09:46:50 INFO - wsgiref == 0.1.2 09:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:50 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')]} 09:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46: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')]} 09:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:51 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:51 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 09:46:51 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 09:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:51 INFO - 'HOME': '/Users/cltbld', 09:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:51 INFO - 'LOGNAME': 'cltbld', 09:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:51 INFO - 'MOZ_NO_REMOTE': '1', 09:46:51 INFO - 'NO_EM_RESTART': '1', 09:46:51 INFO - 'PAGER': '/bin/cat', 09:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:51 INFO - 'PWD': '/builds/slave/test', 09:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:51 INFO - 'SHELL': '/bin/bash', 09:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:51 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:51 INFO - 'USER': 'cltbld', 09:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:51 INFO - 'XPC_FLAGS': '0x0', 09:46:51 INFO - 'XPC_SERVICE_NAME': '0', 09:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 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)) 09:46:51 INFO - Cleaning up... 09:46:51 INFO - Return code: 0 09:46:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:46: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')]} 09:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46: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')]} 09:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:52 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 0x107b0b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a81570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552c60080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, '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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 09:46:52 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 09:46:52 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 09:46:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:46:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:52 INFO - 'HOME': '/Users/cltbld', 09:46:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:52 INFO - 'LOGNAME': 'cltbld', 09:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:52 INFO - 'MOZ_NO_REMOTE': '1', 09:46:52 INFO - 'NO_EM_RESTART': '1', 09:46:52 INFO - 'PAGER': '/bin/cat', 09:46:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:52 INFO - 'PWD': '/builds/slave/test', 09:46:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:52 INFO - 'SHELL': '/bin/bash', 09:46:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:46:52 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:46:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:52 INFO - 'USER': 'cltbld', 09:46:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:52 INFO - 'XPC_FLAGS': '0x0', 09:46:52 INFO - 'XPC_SERVICE_NAME': '0', 09:46:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:46:52 INFO - Cleaning up... 09:46:52 INFO - Return code: 0 09:46:52 INFO - Running post-action listener: _resource_record_post_action 09:46:52 INFO - Running post-action listener: _start_resource_monitoring 09:46:52 INFO - Starting resource monitoring. 09:46:52 INFO - [mozharness: 2017-02-14 17:46:52.593831Z] Finished create-virtualenv step (success) 09:46:52 INFO - [mozharness: 2017-02-14 17:46:52.594824Z] Running install step. 09:46:52 INFO - Running pre-action listener: _resource_record_pre_action 09:46:52 INFO - Running main action method: install 09:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:46:52 INFO - Reading from file tmpfile_stdout 09:46:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:46:52 INFO - Reading from file tmpfile_stdout 09:46:52 INFO - Output received: 09:46:52 INFO - Usage: mozinstall [options] installer 09:46:52 INFO - Options: 09:46:52 INFO - -h, --help show this help message and exit 09:46:52 INFO - -d DEST, --destination=DEST 09:46:52 INFO - Directory to install application into. [default: 09:46:52 INFO - "/builds/slave/test"] 09:46:52 INFO - --app=APP Application being installed. [default: firefox] 09:46:52 INFO - mkdir: /builds/slave/test/build/application 09:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:47:14 INFO - Reading from file tmpfile_stdout 09:47:14 INFO - Output received: 09:47:14 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:47:14 INFO - Running post-action listener: _resource_record_post_action 09:47:14 INFO - [mozharness: 2017-02-14 17:47:14.601974Z] Finished install step (success) 09:47:14 INFO - [mozharness: 2017-02-14 17:47:14.602109Z] Running run-tests step. 09:47:14 INFO - Running pre-action listener: _resource_record_pre_action 09:47:14 INFO - Running main action method: run_tests 09:47:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:14 INFO - 2017-02-14 09:47:14.616 screenresolution[1634:7347] starting screenresolution argv=screenresolution get 09:47:14 INFO - 2017-02-14 09:47:14.619 screenresolution[1634:7347] Display 0: 1600x1200x32@60 09:47:14 INFO - 2017-02-14 09:47:14.625 screenresolution[1635:7349] starting screenresolution argv=screenresolution list 09:47:14 INFO - Available Modes on Display 0 09:47:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:14 INFO - Intel Iris: 09:47:14 INFO - Chipset Model: Intel Iris 09:47:14 INFO - Type: GPU 09:47:14 INFO - Bus: Built-In 09:47:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:14 INFO - Vendor: Intel (0x8086) 09:47:14 INFO - Device ID: 0x0a2e 09:47:14 INFO - Revision ID: 0x0009 09:47:14 INFO - Displays: 09:47:14 INFO - Display: 09:47:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:14 INFO - Display Serial Number: 1600x1200 60 09:47:14 INFO - Main Display: Yes 09:47:14 INFO - Mirror: Off 09:47:14 INFO - Online: Yes 09:47:14 INFO - Rotation: Supported 09:47:14 INFO - Return code: 0 09:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:14 INFO - Python 2.7.3 09:47:14 INFO - Return code: 0 09:47:14 INFO - grabbing minidump binary from tooltool 09:47:14 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')]} 09:47:14 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 0x10791fd30>, '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 0x107785db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107917b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:14 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 09:47:14 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 09:47:14 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 09:47:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:14 INFO - Return code: 0 09:47:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:47:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:47:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0257 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lPWUFfCKVS/Render', 09:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:14 INFO - 'HOME': '/Users/cltbld', 09:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:14 INFO - 'LOGNAME': 'cltbld', 09:47:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:14 INFO - 'MOZ_NO_REMOTE': '1', 09:47:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:14 INFO - 'NO_EM_RESTART': '1', 09:47:14 INFO - 'PAGER': '/bin/cat', 09:47:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:14 INFO - 'PWD': '/builds/slave/test', 09:47:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:14 INFO - 'SHELL': '/bin/bash', 09:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQbEHNkXcj/Listeners', 09:47:14 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 09:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:14 INFO - 'USER': 'cltbld', 09:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:14 INFO - 'XPC_FLAGS': '0x0', 09:47:14 INFO - 'XPC_SERVICE_NAME': '0', 09:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:47:15 INFO - mozversion application_buildid: 20170214070945 09:47:15 INFO - mozversion application_changeset: 3a0e9dab386403cec5b899eb7f321c2aa27e0186 09:47:15 INFO - mozversion application_display_name: Firefox 09:47:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:15 INFO - mozversion application_name: Firefox 09:47:15 INFO - mozversion application_remotingname: firefox 09:47:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:47:15 INFO - mozversion application_vendor: Mozilla 09:47:15 INFO - mozversion application_version: 52.0 09:47:15 INFO - mozversion platform_buildid: 20170214070945 09:47:15 INFO - mozversion platform_changeset: 3a0e9dab386403cec5b899eb7f321c2aa27e0186 09:47:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:47:15 INFO - mozversion platform_version: 52.0 09:47:15 INFO - using testdate: 1487094435 09:47:15 INFO - actual date: 1487094435 09:47:15 INFO - starting webserver on 'localhost:49236' 09:47:15 INFO - SUITE-START | Running 2 tests 09:47:15 INFO - TEST-START | dromaeo_css 09:47:15 INFO - operating with platform_type : mac_ 09:47:15 INFO - Initialising browser for dromaeo_css test... 09:47:15 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpFtnoDW/profile http://localhost:49236/getInfo.html) 09:47:18 INFO - PROCESS | 1645 | 2017-02-14 09:47:18.377 plugin-container[1649:7552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c43, name = 'com.apple.tsm.portname' 09:47:18 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:18 INFO - PROCESS | 1645 | 2017-02-14 09:47:18.377 plugin-container[1649:7552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 09:47:18 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:18 INFO - PROCESS | 1645 | 2017-02-14 09:47:18.377 plugin-container[1649:7552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:18 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 09:47:18 INFO - PROCESS | 1645 | colorDepth:24 09:47:18 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 09:47:18 INFO - PROCESS | 1645 | __metrics 09:47:18 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:47:19 INFO - TEST-INFO | 1645: exit 0 09:47:19 INFO - Browser initialized. 09:47:19 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:19 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpFtnoDW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:21 INFO - PROCESS | 1650 | 2017-02-14 09:47:21.869 plugin-container[1651:7683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x823f, name = 'com.apple.tsm.portname' 09:47:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:21 INFO - PROCESS | 1650 | 2017-02-14 09:47:21.869 plugin-container[1651:7683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 09:47:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:21 INFO - PROCESS | 1650 | 2017-02-14 09:47:21.870 plugin-container[1651:7683] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:31 INFO - PROCESS | 1650 | RSS: Main: 177303552 09:47:31 INFO - PROCESS | 1650 | 09:50:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:50:35 INFO - PROCESS | 1650 | RSS: Main: 183365632 09:50:35 INFO - PROCESS | 1650 | 09:53:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:53:38 INFO - PROCESS | 1650 | RSS: Main: 191787008 09:53:38 INFO - PROCESS | 1650 | 09:56:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:56:40 INFO - PROCESS | 1650 | RSS: Main: 193437696 09:56:40 INFO - PROCESS | 1650 | 09:59:42 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:59:43 INFO - PROCESS | 1650 | RSS: Main: 198262784 09:59:43 INFO - PROCESS | 1650 | 10:02:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 10:02:46 INFO - PROCESS | 1650 | RSS: Main: 200081408 10:02:46 INFO - PROCESS | 1650 | 10:05:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 10:05:49 INFO - PROCESS | 1650 | RSS: Main: 199823360 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | __start_tp_report 10:05:49 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 10:05:49 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 10:05:49 INFO - PROCESS | 1650 | |i|pagename|runs| 10:05:49 INFO - PROCESS | 1650 | |0;dojo.html;3527.8059706716126;3570.3037907608027;3607.098225443639;3608.1701208721993;3609.4224924012165;6002.699865006749;6068.757249710017;6072.089186622009;6085.2089228400355;6088.66512341821;6041.154238406615;6063.120847477113;6130.417610327023;6140.81577552673;6155.692215389224;4679.27471241958;5464.781408743645;5520.91718624221;5539.335279766414;5596.58025648078;5586.497215250627;5598.804041858536;5604.467575580334;5613.522850557708;5647.802326918559;5432.994895944244;5472.589555783332;5594.776208951637;5598.384177740445;5611.186377975191;5520.723963801794;5527.115661494162;5567.47109024642;5577.135543990688;5643.435656434365;5428.75570599324;5515.918220516829;5530.308711411074;5536.252605898192;5568.470995255485;5667.234923285386;6110.450059494667;6116.96941515294;6154.599951003171;6169;6186.56694031418;6278.968605156991;6288.622682639057;6305.936940630627;6420.743170273184;6480.416762491352;6481.481481481517;6494.285628580852;6497.967510162466;6507.251666058369;6143.986242270064;6165.352637972993;6198.070289456573;6200.348963358872;6207.813765586994;6343.270523889812;6435.935640643534;6471.805845824632;6480.935190648128;6486.902696459557;6323.146375239376;6325.335839736854;6330.68346582669;6330.873382532415;6343.175387199634;6400.583962042546;6454.483641308684;6473.126127972663;6473.643949582818;6474.935250647433;6246.187995560548;6250.562460627712;6258.561900667;6305.117283580303;6333.778317758948;6342.365763423621;6359.141515895388;6374.84062898422;6397.328280530571;6407.10300557913;5155.9430316784965;5192.21467005102;5215.47845215483;5216.713080780518;5252.789888404421;4563.657725670588;4572.862814115582;4602.010567727954;4677.742724150136;4713.599344055794;4419.690621656518;5970.984932561475;6101.145839582462;6179.783707570214;6210.844728881723;6458.903116453257;6467.547271690936;6469.967650161719;6479.643619600873;6490.350964903566;6050.2437195350585;6132;6132.386761323921;6136.21274067861;6143.266246520256;5659.858503537363;5681.261436013292;5705.515031222392;5712.028955077563;5725.312962444534;6650.534462587572;6655.301193374723;6665.466762658975;6689.698963546554;6702.899456508254;6283.151774510383;6327.936720632735;6385.68071596409;6399.840003999937;6483.935160648333;6665.0335701324;6690.364415380531;6699.363560461748;6701.765438209639;6713.966430167818;6422.908105621962;6424.903626445701;6433.710483028369;6442.935570644234;6443.548951573345;6279.058141278845;6323.241211054703;6403.519736019724;6464.5151613630105;6515.250746164063;6157.53818463626;6223.253209614875;6248.968755156195;6288.591241569284;6300.653464059521;5102.4897510248675;6283.214598175228;6288.905666415099;6303;6304.684765761602;6144.969275153596;6165.630062196283;6179.3820617937845;6204.255489341308;6280.434760871543;6221.9377806223165;6226.844328891632;6261.373862613702;6305.716242769178;6311.147995020613;5189.065968125623;5685.746832546607;6089.086637004485;6174.722137503913;6251 10:05:49 INFO - PROCESS | 1650 | |1;ext.html;469.25857145709784;472.79433446450787;479.9112164249612;546.4944925943502;553.9058360078786;34737.65262347376;41569.79215103928;41650.58349416505;41718.791406042925;41754.956126096826;42111;42984;43335.78332108343;43418.56581434185;43487.34768978468;9573.569189386495;9589.089036541518;9616.80766384672;9705.514724263758;9806.656767013155;9475.38410003351;9523.666671666493;9624.711258662217;9629.518524073803;9635.132838044574;9467.668631597895;9472.905270947305;9533.380330278544;9591.472469014201;9630.325877188565;9465.526723663823;9552;9621.663241786573;9622.037796220391;9641.37331073478;9401.529923503798;9498.525073746285;9584.37701549397;9609.279304052225;9619.951900240523;40059.59940400617;40160.995975100566;40353.596464035276;40944.795276023724;41166.176676466304;2961.7778666599875;3558.3061303045915;3621.8732344367822;3835.673967712748;4084.9387259191135;24734.639594822278;27752.02867899634;28857.422851543062;31209;31608.525872111935;3064.88131831882;3067.877284908601;3254.4304746669236;3302.075418882717;3312.834358282076;513361;515180;517212.41393793165;517404;518920;65083.69832603321;65536;65670.34329656643;65753;65906.67046664831;65527;66411.33588664052;66440;66655;66874.33125668681;16292.674146517003;16298.592535186479;16814.57963550921;16832.158392080346;17134;13011.869881301254;13034.152780069266;13105.27920964356;13131.540396086091;13141.408636611375;14936.925315373572;17546.649067018843;18351.908240458713;18729.344472943652;18808.811911880704;6777.525573209828;6806.76176333826;6844.93155068443;6849.863002740017;6887.414569761627;882.8719835623799;946.3895787217344;968.5060619084318;986.8667729856378;1115.7154925493946;30682.693173067983;30838.38323233523;30949.226269343446;31291.84354078215;31299.687003130133;1050.9789804203874;1073.8281874899978;1094.8084085285202;1103.8730545987153;1108.728361551425;15580.454684086002;16872.831271687126;16969.490915272563;17072.975621462752;17366.871153374992;889336;904685;904845;905564;906910;61077.08384374238;61604;61628;61731.691341543;61931;1233.4511142541483;1356.9118007329491;1382.5483242595235;1407.8732914037785;1414.144442612246;30756.538651919793;31977.520337195427;32247.87132450353;32657.34685306328;33009.83495082605;52033.7398313006;54122;54436.72781636067;54490;54547.909041818144;501816;501862;502039;503613;503660;27114;27217.455650886477;27562.448751024465;27595.03417380382;28082.85958570194;47676.523234767206;47750.80622984453;48841.511584883694;49397;49407.50592494029;39631;40074.79962600285;40090;40533.98665033398;41095.7945210272;39140.6085939137;39351.80324098361;39881;40186.19627607373;40946.385804213554;38515.6148438512;39026.02434939149;39165.804170980104;39253.41119883147;39272;26087.347816304744;30235.546466803455;34142.317153657285;34907.47638785471;35530.644693552735;25912.22263332103;26307;26564.601530976663;26575.335616608965;27044.45911081807 10:05:49 INFO - PROCESS | 1650 | |2;jquery.html;384.4818055969986;386.50720331577236;390.28772490205375;393.8877419935318;412.5997782151312;17931.372401965935;19405.902970485145;20638.793612063895;20720.89639551802;21218.151273949024;20759.377218683425;20834.062467188975;20894;21061.57876842462;21327.466813329673;5688.857778555538;5733.655980641154;5859.7656093756195;5904.940950590503;5910.940890591082;5685.13846368631;5739.139129130622;5760.62555933865;5810.389909059551;5876.970615146918;5703.144528320764;5847.064469684851;5852.824415267528;5855.560832937525;5857.150713146591;5502.147167189092;5671.347795003545;5838.941610583882;5849.210356601868;5853.385394533555;4996;4998.950010499885;5072.442031376546;5091.439941606421;5095.719735414551;24005.519889602285;24055.03779848804;25213.621795672898;25534.744652553607;25573;1601.2474137155518;2046.6213750456213;2239.876806775627;2512.196097248881;2997.4904266274784;10064.647737329158;10181.694549163536;10211.183105351554;10640.733981650443;10683.14534837211;1847.898365589892;1938.6122775444967;1943.650142974264;1980.4058782365378;2000;101273.4936325321;116492;126758;128156;137617;9468.57391417388;11793.823092653616;11835.940820295844;11914.46384912681;12006;15079.54761357161;15139;15148.545543633709;15150.31823567942;15213.31540080699;7610.657520411595;8662.35032372562;8836.64653413869;8871.64513419456;8930.821383572422;8698.173673501007;8766.649334026695;8770.365968404943;8874;8912.287017038621;4303.376010478526;4645.581897629229;4648.604868586208;4651.418572678415;4704.976475117603;1728.0150314320967;1815.210383483189;2011.6680747676762;2013.6073465674347;2013.6375452418413;894.7360528644066;901.8962819275736;915.1169121797365;921.083521895718;991.1476130527884;20238.291659791837;20723.171073157206;21130.683039754414;21213.363599091743;21245.256416025364;945.4421891084294;957.7701351675547;986.2159583131428;1000.5097502224004;1054.5623566220086;9244.583993720164;10389.116925061455;11152.442377881074;11182.384968826793;11194.888051119548;626421;635111;635289;644362;650487;129911;139941.6005839949;145043;146954;147632;926.2867591954158;943.1464524605243;980.1717548671361;981.2444417798256;986.8223719730374;18762.155702993125;19162.233510659975;19358.225670973003;19454.8054519453;19478.8052119477;55296;57698.4230157693;58011;58762;61349.773004538765;85029;87032.5648371754;87274.69087963559;88092.5595372019;89863;8014.759557213293;8133.308668762985;8244.54654993981;8384.99380074395;8407.033191183093;9264.166225039779;11221.877812218712;11685.182037257651;13045.152065115737;13181.60455186346;8901.33240007015;9373.906260937303;10886.40124793144;11049.33703977764;11208.327500350006;10933.45332733376;11163.162762792661;11211.66365009051;11384.316940983568;11390.886091138982;10187.949060254949;10504.78990420172;10822.458877056273;11021.669349919515;11392.974632283134;8045;8127.634256458592;8281.130481299377;8406.07533171363;8434.493930364199;9503.429794212369;10862.424948382599;10940.75714478514;11000.449977501254;11123.388213648002 10:05:49 INFO - PROCESS | 1650 | |3;mootools.html;1512.2741084279544;1531.287951102737;1645.1198608744323;1783.7324401339797;1868.9532761680948;7704.6918123275;7937.563434011127;8094.271515563598;8647.048824629286;8655.61049752761;8110.837783244331;8422.452540584858;8427.494350338984;8447;8539.615717292721;2347.7652234776556;2375.821813364005;2394.916177933773;2395.568797616428;2409.819263555232;2408.6989126359204;2414.311921102486;2426.8786560671983;2430.2466235466973;2455.9017639294407;2284.006457191119;2292.33522278539;2309.3072078376504;2319.0375993962484;2345.777151170636;2372.430616652008;2376.239603326936;2401.2196036288187;2401.4956859059616;2409.229046705055;2057.9915841237835;2412.8914198861094;2431.8297719159664;2435;2440.2679196241056;6498.837529061764;7305.707771689179;8196.877046844302;8203.58982050895;8359.414840961135;5197.870053248699;5295.708736019517;5324.866878328034;5327.493888080626;5418.322709661293;3402.897913062612;3436.518887355772;3457.2566898116775;3497.318022985519;3544.468329750532;1029.0995379043336;1037.9013993670665;1082.5345101606306;1109.3732041396586;1116.609186784627;10973.451327433597;11078;11135.05352045077;11155.609553665663;11174.776504469946;4607.193741095295;4740.170470167775;4798.184308667535;4824.34871292371;4837.37114175155;9709.902900971041;9724;9737.172340351008;9742.123208911233;9842.114209721016;5291.947080529222;5353;5371.328583927009;5379.35447746273;5384.865378365494;4367.344898265285;4406.537313582092;4571;4634.675572709945;4848.272759086165;3906.2187562487047;3976.840926362971;4020.8190631421658;4044.3933409988736;4088.1194650460525;1288.1948782011243;1319.4260496683974;1321.6761893336002;1327.860574639649;1329.1559859489294;786.7443080998698;910.961503885571;915.54680433186;925.6898938855425;938.1603464898877;5509.614326997072;5548.0845660466375;5606.971965140148;5609.186667933209;5720.971395143081;355.4171159298752;454.6726357022938;455.53307859444334;458.601017115112;460.0109764007411;3561.4479755637917;3622.8913132606062;3677.9080522987138;3755.9436608450897;3820.67524260441;9943.950280248553;10249.282550221413;10318.329308594917;10374.844377334195;10391.636292729718;9904.801903962025;9996.700098997042;10219;10280.742981425525;10348.948255258676;7939.20607939213;7968.800779980547;8035.638396272182;8037.196280371916;8107.108218096005;5446.618736688394;5525.668459892419;5544.334679838284;5611.859703507445;5618.915716264341;9209.86185207209;9448.149666530046;9556.092171243721;9583.568739406883;9697.272704547046;9208.263338932735;9474;9534.141927226583;9554.187894029086;9598.472084035166;6760.290169532128;6919.515633905578;7143.107111611049;7251.1298644163035;7410.777676669709;7928.603569821601;7983.481073730189;8037.879431808645;8063.233992770677;8139.023317201973;7165.498415110892;7217.639118043971;7321.084864391951;7381.077365329334;7413.073365829271;7063.540869843444;7114.0396046535125;7148.499605027599;7152.821179470555;7172.426205903411;6716.3955244028275;6786.8642627148165;6855.862882742417;6877.3810357068105;6883.311668833277;4785.353977213032;4838.177509823268;4840.927386089141;4889.315495830658;4916.139675556835;4782.234842425195;4809.44691360498;4809.711417314972;4911.557959783494;4915.336429581961 10:05:49 INFO - PROCESS | 1650 | |4;prototype.html;276.70807298300286;286.91392582225336;310.27705446310085;319.14469222483734;321.43106701139;4629.3334399616115;5476.479734425223;5592.328920529532;5652.604317697763;5787.855303617406;4780.952190478094;5408.378036525801;5416.268803711498;5534.723263836802;5548.500634942854;3543.114221444639;3688.944665830015;4211.557786432427;4216.241076606209;4220.409142720014;3499.777641197845;3976.7216294859372;4126.442930204414;4237.131388065454;4273.893152671177;3462.7402944779114;3474.8783792567265;4115.176964607091;4166.333386658135;4170.582941705836;4148.543660197376;4154.896127596804;4159;4159.147374788176;4202.394515400471;3021.6071910651694;3273.6398996110647;3458.7375607902936;3623.8188090595363;3655.342038433081;4592.611841842257;5173.17229243323;5391.487808658171;5393.865153371158;5415.783368665248;3953.723239373245;4741.359916411275;4770.546798054179;4774.116788394158;4785.234362501983;5048.318477005594;5829.737661805187;5837.357890632027;5862.006917647977;5891.882162356729;3857.7492462989817;3924.9018774530578;3933.409988501719;3970.2456533258587;3984.183242435323;6107.603005804653;6113.877722445526;6188.938110618927;6191.721372538292;6199.566030377875;3117.1116231873807;3369.966300336965;4038.9798051010143;4040.3535434330365;4090.1615168890307;3578.5526809148855;4036.2129384769687;4070.2266569351727;4119.9176016479505;4139.482564679415;4641.280601506772;4703.836235331717;4708.599769019604;4734.07685501324;4757.452892917289;4861.708297502161;4882.511748825089;5741.712914354265;5761.596688231783;5792.972459639092;1136.909047276216;1235.7899157143306;1294.4239813283186;1300.6928037322618;1332.9200247985152;779.4037561265636;882.3338379523547;889.9943064337258;902.359324879343;903.679193886161;4668.626509879202;4868.196747536616;5203.383648364351;5216.58267338612;5242.659227150223;4647.536026151819;4963.528464795911;5243.711595862188;5283.471652834686;5484.848181881849;4365.432493775789;4467.906203822134;4837.565328616652;4941.257602030907;4993.800247990112;2638.5910183921524;2765.502209602251;2969.3615872587498;3008.3231272963408;3015.5374643297982;5247.580193584504;5432.713208508874;5949.9107513387335;6000.969995150083;6060.787872424444;5069.873253168626;5914.231149950565;5922.851928701731;5922.8815423691285;5963.910541341883;5022.59819214462;5541.556675465953;5640.661560306313;5932.139839723302;5963.612365196248;5068.808829924891;5147.9227811582105;5734.942650573441;5777.682227477361;5782.352029671523;4568.680192386501;4576.725396476222;4814.6870453420415;5273.472652734849;5606.439356064361;5262.97368513155;5624.846906384263;5962.1951036611235;6006.498375406149;6016.886875927968;3700.1869084148034;3833.3866581347966;3847.576766555733;3866.2654095721723;3874.070223146481;4296.871093867189;4947.734495233129;5436.238926550207;5695.544356451557;5875.383084776037;5857.47282744555;5877.118432235028;5877.794277200277;5884.116782776494;5895.29256489224;4598;5679.744411501575;5826.436367998067;5855.619384739978;5895.498882595028;5588.972055139861;5723.3418156912485;5743.4543718346695;5780.710964451845;5782.6988927490975;5874.765009399576;5878.000739874317;5886.705664716661;5910.965581023218;5913.231279933753;5042.814938489525;5461.5357694596405;5491.258680078298;5755.309362876482;5772.451617096369 10:05:49 INFO - PROCESS | 1650 | |5;yui.html;725.7024619905836;766.2184571736829;789.4158322841092;793.5913004802526;848.7411339541442;14610.780838287434;15040.247987600604;15373.692526149498;15422.768658470131;15653.217339133027;13904.652383690387;13964.581062568113;14050.64873378166;14106.365213565388;14193.574192774233;669.8526324208678;854.9700760473385;858.720915702396;859.944103633265;860.4450129666361;834.7996480844614;845.6828689241535;879.0330636300051;881.8765372847806;888.1695614600337;877.0484024833064;879.520661239626;901.5266984832949;914.9268058555333;915.0620613870769;877.2105105405154;901.5762591581946;902.7833320003185;910.9979023074665;911.6034524981588;649.9155109835739;652.2922628947598;673.8921772516422;674.2684187656399;676.4655921821754;14471;14743;14993.700125997528;15074.472393466176;15194.696106077927;12628.747425051448;13868.372395182609;13999.501619735858;14111.435542578387;14150.646233844132;10811.513481893255;11710.76578468426;11855.762884742344;11915.344656043913;11945.03963199929;3444.689977901976;3511.613722490524;3707.277080969212;3759.868404605853;3800.410936304877;78968.21031789666;92170.15659686769;93126.06873931241;94279.64300892464;95002.0499795;8079.353651707849;9712.223022158352;10198.643047493302;10202.744931376626;10220.846687299696;19118.808811911982;19150;19169.04154792255;19255.614887702166;19380.321688740827;7642.312191902644;7820.178881217504;7827.647755850887;7881.487703299267;7927.128015918244;4379.956200438019;4467.597916187558;4564.315352697122;4574.405327307495;4587.610053145453;3361.3781450431748;3477.3393055319407;3477.51314815926;3624.2751449710163;3804.5434547854434;575.6891278709534;597.6832278892194;685.3146853146853;693.303230253602;702.357343031131;571.4742436958009;639.9104125422444;643.4787821864211;659.7427003468651;693.3101563943908;9149.63401463934;9293.3330002748;9351.579178936829;9595.856062159073;9712.854307185398;626.8150895485843;648.18652590998;672.4284358295507;676.0940339944498;697.4315932514984;4175.976885663032;4416.227160247008;4494.5505449454795;4712.811487540528;4713.622910167179;78737.85048598106;98964.50517747509;117611;117905;123099;83956;93890.53054734683;105224.94775052305;109522.45238773915;110212;65471.67264163648;66441.00338494926;82302.58848705718;92588.53705731429;93200;8785.560721963748;9145.817083658423;9158.908410916072;9176.944651365178;9300.302477314364;51921.48078519318;58066.129008065756;58133.70933145307;58515.70742146262;59219.70390148194;53992.73003634957;57562.7121864388;74521;77036.61481692556;80560.59719701365;11485.540578377106;12038.518459261531;12734.490076145301;12910.289934053342;13171;14213.289335533389;14474.710505790035;14720;14850.183239921907;14943.028703134261;13383.665408364868;13852.722945541234;14075.718485630025;14444.350004250044;14477.638059048608;13547.322633868464;13818.239996800272;13819.654508636962;14407;14455.927720361331;13384.93307533456;13501;13525.458981640624;14180.929095354457;14226.14643121416;11382.658520244406;11480.770384592428;11524.078073753912;11596.826047609462;11923.642290731292;9676.806463870824;11371.507850163363;11638.410227904364;11723.237989530653;11757.176997610428 10:05:49 INFO - PROCESS | 1650 | __end_tp_report 10:05:49 INFO - PROCESS | 1650 | __start_cc_report 10:05:49 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,360 10:05:49 INFO - PROCESS | 1650 | __end_cc_report 10:05:49 INFO - PROCESS | 1650 | __startTimestamp1487095549112__endTimestamp 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | ------- Summary: start ------- 10:05:49 INFO - PROCESS | 1650 | Number of tests: 6 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5980.61 Median:6200.35 stddev:646.04 (10.4%) stddev-sans-first:618.70 10:05:49 INFO - PROCESS | 1650 | Values: 3527.8 3570.3 3607.1 3608.2 3609.4 6002.7 6068.8 6072.1 6085.2 6088.7 6041.2 6063.1 6130.4 6140.8 6155.7 4679.3 5464.8 5520.9 5539.3 5596.6 5586.5 5598.8 5604.5 5613.5 5647.8 5433.0 5472.6 5594.8 5598.4 5611.2 5520.7 5527.1 5567.5 5577.1 5643.4 5428.8 5515.9 5530.3 5536.3 5568.5 5667.2 6110.5 6117.0 6154.6 6169.0 6186.6 6279.0 6288.6 6305.9 6420.7 6480.4 6481.5 6494.3 6498.0 6507.3 6144.0 6165.4 6198.1 6200.3 6207.8 6343.3 6435.9 6471.8 6480.9 6486.9 6323.1 6325.3 6330.7 6330.9 6343.2 6400.6 6454.5 6473.1 6473.6 6474.9 6246.2 6250.6 6258.6 6305.1 6333.8 6342.4 6359.1 6374.8 6397.3 6407.1 5155.9 5192.2 5215.5 5216.7 5252.8 4563.7 4572.9 4602.0 4677.7 4713.6 4419.7 5971.0 6101.1 6179.8 6210.8 6458.9 6467.5 6470.0 6479.6 6490.4 6050.2 6132.0 6132.4 6136.2 6143.3 5659.9 5681.3 5705.5 5712.0 5725.3 6650.5 6655.3 6665.5 6689.7 6702.9 6283.2 6327.9 6385.7 6399.8 6483.9 6665.0 6690.4 6699.4 6701.8 6714.0 6422.9 6424.9 6433.7 6442.9 6443.5 6279.1 6323.2 6403.5 6464.5 6515.3 6157.5 6223.3 6249.0 6288.6 6300.7 5102.5 6283.2 6288.9 6303.0 6304.7 6145.0 6165.6 6179.4 6204.3 6280.4 6221.9 6226.8 6261.4 6305.7 6311.1 5189.1 5685.7 6089.1 6174.7 6251.0 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:76898.42 Median:41130.99 stddev:179626.09 (436.7%) stddev-sans-first:180038.33 10:05:49 INFO - PROCESS | 1650 | Values: 469.3 472.8 479.9 546.5 553.9 34737.7 41569.8 41650.6 41718.8 41755.0 42111.0 42984.0 43335.8 43418.6 43487.3 9573.6 9589.1 9616.8 9705.5 9806.7 9475.4 9523.7 9624.7 9629.5 9635.1 9467.7 9472.9 9533.4 9591.5 9630.3 9465.5 9552.0 9621.7 9622.0 9641.4 9401.5 9498.5 9584.4 9609.3 9620.0 40059.6 40161.0 40353.6 40944.8 41166.2 2961.8 3558.3 3621.9 3835.7 4084.9 24734.6 27752.0 28857.4 31209.0 31608.5 3064.9 3067.9 3254.4 3302.1 3312.8 513361.0 515180.0 517212.4 517404.0 518920.0 65083.7 65536.0 65670.3 65753.0 65906.7 65527.0 66411.3 66440.0 66655.0 66874.3 16292.7 16298.6 16814.6 16832.2 17134.0 13011.9 13034.2 13105.3 13131.5 13141.4 14936.9 17546.6 18351.9 18729.3 18808.8 6777.5 6806.8 6844.9 6849.9 6887.4 882.9 946.4 968.5 986.9 1115.7 30682.7 30838.4 30949.2 31291.8 31299.7 1051.0 1073.8 1094.8 1103.9 1108.7 15580.5 16872.8 16969.5 17073.0 17366.9 889336.0 904685.0 904845.0 905564.0 906910.0 61077.1 61604.0 61628.0 61731.7 61931.0 1233.5 1356.9 1382.5 1407.9 1414.1 30756.5 31977.5 32247.9 32657.3 33009.8 52033.7 54122.0 54436.7 54490.0 54547.9 501816.0 501862.0 502039.0 503613.0 503660.0 27114.0 27217.5 27562.4 27595.0 28082.9 47676.5 47750.8 48841.5 49397.0 49407.5 39631.0 40074.8 40090.0 40534.0 41095.8 39140.6 39351.8 39881.0 40186.2 40946.4 38515.6 39026.0 39165.8 39253.4 39272.0 26087.3 30235.5 34142.3 34907.5 35530.6 25912.2 26307.0 26564.6 26575.3 27044.5 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:37006.77 Median:1690.99 stddev:106813.53 (6316.6%) stddev-sans-first:107077.77 10:05:49 INFO - PROCESS | 1650 | Values: 384.5 386.5 390.3 393.9 412.6 17931.4 19405.9 20638.8 20720.9 21218.2 20759.4 20834.1 20894.0 21061.6 21327.5 5688.9 5733.7 5859.8 5904.9 5910.9 5685.1 5739.1 5760.6 5810.4 5877.0 5703.1 5847.1 5852.8 5855.6 5857.2 5502.1 5671.3 5838.9 5849.2 5853.4 4996.0 4999.0 5072.4 5091.4 5095.7 24005.5 24055.0 25213.6 25534.7 25573.0 1601.2 2046.6 2239.9 2512.2 2997.5 10064.6 10181.7 10211.2 10640.7 10683.1 1847.9 1938.6 1943.7 1980.4 2000.0 101273.5 116492.0 126758.0 128156.0 137617.0 9468.6 11793.8 11835.9 11914.5 12006.0 15079.5 15139.0 15148.5 15150.3 15213.3 7610.7 8662.4 8836.6 8871.6 8930.8 8698.2 8766.6 8770.4 8874.0 8912.3 4303.4 4645.6 4648.6 4651.4 4705.0 1728.0 1815.2 2011.7 2013.6 2013.6 894.7 901.9 915.1 921.1 991.1 20238.3 20723.2 21130.7 21213.4 21245.3 945.4 957.8 986.2 1000.5 1054.6 9244.6 10389.1 11152.4 11182.4 11194.9 626421.0 635111.0 635289.0 644362.0 650487.0 129911.0 139941.6 145043.0 146954.0 147632.0 926.3 943.1 980.2 981.2 986.8 18762.2 19162.2 19358.2 19454.8 19478.8 55296.0 57698.4 58011.0 58762.0 61349.8 85029.0 87032.6 87274.7 88092.6 89863.0 8014.8 8133.3 8244.5 8385.0 8407.0 9264.2 11221.9 11685.2 13045.2 13181.6 8901.3 9373.9 10886.4 11049.3 11208.3 10933.5 11163.2 11211.7 11384.3 11390.9 10187.9 10504.8 10822.5 11021.7 11393.0 8045.0 8127.6 8281.1 8406.1 8434.5 9503.4 10862.4 10940.8 11000.4 11123.4 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5505.87 Median:4915.74 stddev:3033.47 (61.7%) stddev-sans-first:3027.13 10:05:49 INFO - PROCESS | 1650 | Values: 1512.3 1531.3 1645.1 1783.7 1869.0 7704.7 7937.6 8094.3 8647.0 8655.6 8110.8 8422.5 8427.5 8447.0 8539.6 2347.8 2375.8 2394.9 2395.6 2409.8 2408.7 2414.3 2426.9 2430.2 2455.9 2284.0 2292.3 2309.3 2319.0 2345.8 2372.4 2376.2 2401.2 2401.5 2409.2 2058.0 2412.9 2431.8 2435.0 2440.3 6498.8 7305.7 8196.9 8203.6 8359.4 5197.9 5295.7 5324.9 5327.5 5418.3 3402.9 3436.5 3457.3 3497.3 3544.5 1029.1 1037.9 1082.5 1109.4 1116.6 10973.5 11078.0 11135.1 11155.6 11174.8 4607.2 4740.2 4798.2 4824.3 4837.4 9709.9 9724.0 9737.2 9742.1 9842.1 5291.9 5353.0 5371.3 5379.4 5384.9 4367.3 4406.5 4571.0 4634.7 4848.3 3906.2 3976.8 4020.8 4044.4 4088.1 1288.2 1319.4 1321.7 1327.9 1329.2 786.7 911.0 915.5 925.7 938.2 5509.6 5548.1 5607.0 5609.2 5721.0 355.4 454.7 455.5 458.6 460.0 3561.4 3622.9 3677.9 3755.9 3820.7 9944.0 10249.3 10318.3 10374.8 10391.6 9904.8 9996.7 10219.0 10280.7 10348.9 7939.2 7968.8 8035.6 8037.2 8107.1 5446.6 5525.7 5544.3 5611.9 5618.9 9209.9 9448.1 9556.1 9583.6 9697.3 9208.3 9474.0 9534.1 9554.2 9598.5 6760.3 6919.5 7143.1 7251.1 7410.8 7928.6 7983.5 8037.9 8063.2 8139.0 7165.5 7217.6 7321.1 7381.1 7413.1 7063.5 7114.0 7148.5 7152.8 7172.4 6716.4 6786.9 6855.9 6877.4 6883.3 4785.4 4838.2 4840.9 4889.3 4916.1 4782.2 4809.4 4809.7 4911.6 4915.3 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4579.03 Median:5032.71 stddev:1432.88 (28.5%) stddev-sans-first:1400.03 10:05:49 INFO - PROCESS | 1650 | Values: 276.7 286.9 310.3 319.1 321.4 4629.3 5476.5 5592.3 5652.6 5787.9 4781.0 5408.4 5416.3 5534.7 5548.5 3543.1 3688.9 4211.6 4216.2 4220.4 3499.8 3976.7 4126.4 4237.1 4273.9 3462.7 3474.9 4115.2 4166.3 4170.6 4148.5 4154.9 4159.0 4159.1 4202.4 3021.6 3273.6 3458.7 3623.8 3655.3 4592.6 5173.2 5391.5 5393.9 5415.8 3953.7 4741.4 4770.5 4774.1 4785.2 5048.3 5829.7 5837.4 5862.0 5891.9 3857.7 3924.9 3933.4 3970.2 3984.2 6107.6 6113.9 6188.9 6191.7 6199.6 3117.1 3370.0 4039.0 4040.4 4090.2 3578.6 4036.2 4070.2 4119.9 4139.5 4641.3 4703.8 4708.6 4734.1 4757.5 4861.7 4882.5 5741.7 5761.6 5793.0 1136.9 1235.8 1294.4 1300.7 1332.9 779.4 882.3 890.0 902.4 903.7 4668.6 4868.2 5203.4 5216.6 5242.7 4647.5 4963.5 5243.7 5283.5 5484.8 4365.4 4467.9 4837.6 4941.3 4993.8 2638.6 2765.5 2969.4 3008.3 3015.5 5247.6 5432.7 5949.9 6001.0 6060.8 5069.9 5914.2 5922.9 5922.9 5963.9 5022.6 5541.6 5640.7 5932.1 5963.6 5068.8 5147.9 5734.9 5777.7 5782.4 4568.7 4576.7 4814.7 5273.5 5606.4 5263.0 5624.8 5962.2 6006.5 6016.9 3700.2 3833.4 3847.6 3866.3 3874.1 4296.9 4947.7 5436.2 5695.5 5875.4 5857.5 5877.1 5877.8 5884.1 5895.3 4598.0 5679.7 5826.4 5855.6 5895.5 5589.0 5723.3 5743.5 5780.7 5782.7 5874.8 5878.0 5886.7 5911.0 5913.2 5042.8 5461.5 5491.3 5755.3 5772.5 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:20669.38 Median:4650.21 stddev:30129.44 (647.9%) stddev-sans-first:30176.75 10:05:49 INFO - PROCESS | 1650 | Values: 725.7 766.2 789.4 793.6 848.7 14610.8 15040.2 15373.7 15422.8 15653.2 13904.7 13964.6 14050.6 14106.4 14193.6 669.9 855.0 858.7 859.9 860.4 834.8 845.7 879.0 881.9 888.2 877.0 879.5 901.5 914.9 915.1 877.2 901.6 902.8 911.0 911.6 649.9 652.3 673.9 674.3 676.5 14471.0 14743.0 14993.7 15074.5 15194.7 12628.7 13868.4 13999.5 14111.4 14150.6 10811.5 11710.8 11855.8 11915.3 11945.0 3444.7 3511.6 3707.3 3759.9 3800.4 78968.2 92170.2 93126.1 94279.6 95002.0 8079.4 9712.2 10198.6 10202.7 10220.8 19118.8 19150.0 19169.0 19255.6 19380.3 7642.3 7820.2 7827.6 7881.5 7927.1 4380.0 4467.6 4564.3 4574.4 4587.6 3361.4 3477.3 3477.5 3624.3 3804.5 575.7 597.7 685.3 693.3 702.4 571.5 639.9 643.5 659.7 693.3 9149.6 9293.3 9351.6 9595.9 9712.9 626.8 648.2 672.4 676.1 697.4 4176.0 4416.2 4494.6 4712.8 4713.6 78737.9 98964.5 117611.0 117905.0 123099.0 83956.0 93890.5 105224.9 109522.5 110212.0 65471.7 66441.0 82302.6 92588.5 93200.0 8785.6 9145.8 9158.9 9176.9 9300.3 51921.5 58066.1 58133.7 58515.7 59219.7 53992.7 57562.7 74521.0 77036.6 80560.6 11485.5 12038.5 12734.5 12910.3 13171.0 14213.3 14474.7 14720.0 14850.2 14943.0 13383.7 13852.7 14075.7 14444.4 14477.6 13547.3 13818.2 13819.7 14407.0 14455.9 13384.9 13501.0 13525.5 14180.9 14226.1 11382.7 11480.8 11524.1 11596.8 11923.6 9676.8 11371.5 11638.4 11723.2 11757.2 10:05:49 INFO - PROCESS | 1650 | -------- Summary: end -------- 10:05:49 INFO - PROCESS | 1650 | 10:05:49 INFO - TEST-INFO | 1650: exit 0 10:05:49 INFO - TEST-OK | dromaeo_css | took 1114463ms 10:05:49 INFO - TEST-START | kraken 10:05:49 INFO - operating with platform_type : mac_ 10:05:49 INFO - Initialising browser for kraken test... 10:05:49 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpRvYMSx/profile http://localhost:49236/getInfo.html) 10:05:52 INFO - PROCESS | 1662 | 2017-02-14 10:05:52.260 plugin-container[1664:9942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 10:05:52 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:52 INFO - PROCESS | 1662 | 2017-02-14 10:05:52.261 plugin-container[1664:9942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 10:05:52 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:52 INFO - PROCESS | 1662 | 2017-02-14 10:05:52.261 plugin-container[1664:9942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:05:52 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 10:05:52 INFO - PROCESS | 1662 | colorDepth:24 10:05:52 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 10:05:52 INFO - PROCESS | 1662 | __metrics 10:05:52 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 10:05:53 INFO - TEST-INFO | 1662: exit 0 10:05:53 INFO - Browser initialized. 10:05:53 INFO - Running cycle 1/1 for kraken test... 10:05:53 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpRvYMSx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:05:55 INFO - PROCESS | 1665 | 2017-02-14 10:05:55.484 plugin-container[1666:10067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:05:55 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:55 INFO - PROCESS | 1665 | 2017-02-14 10:05:55.485 plugin-container[1666:10067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8723, name = 'com.apple.CFPasteboardClient' 10:05:55 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:55 INFO - PROCESS | 1665 | 2017-02-14 10:05:55.485 plugin-container[1666:10067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:05 INFO - PROCESS | 1665 | RSS: Main: 177217536 10:06:05 INFO - PROCESS | 1665 | 10:06:07 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:08 INFO - PROCESS | 1665 | RSS: Main: 190013440 10:06:08 INFO - PROCESS | 1665 | 10:06:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:06:11 INFO - PROCESS | 1665 | RSS: Main: 197873664 10:06:11 INFO - PROCESS | 1665 | 10:06:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:06:16 INFO - PROCESS | 1665 | RSS: Main: 201760768 10:06:16 INFO - PROCESS | 1665 | 10:06:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:06:18 INFO - PROCESS | 1665 | RSS: Main: 203317248 10:06:18 INFO - PROCESS | 1665 | 10:06:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:06:21 INFO - PROCESS | 1665 | RSS: Main: 203485184 10:06:21 INFO - PROCESS | 1665 | 10:06:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:06:24 INFO - PROCESS | 1665 | RSS: Main: 207491072 10:06:24 INFO - PROCESS | 1665 | 10:06:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:06:27 INFO - PROCESS | 1665 | RSS: Main: 208048128 10:06:27 INFO - PROCESS | 1665 | 10:06:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:06:31 INFO - PROCESS | 1665 | RSS: Main: 207151104 10:06:31 INFO - PROCESS | 1665 | 10:06:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:06:33 INFO - PROCESS | 1665 | RSS: Main: 206995456 10:06:33 INFO - PROCESS | 1665 | 10:06:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:06:36 INFO - PROCESS | 1665 | RSS: Main: 206397440 10:06:36 INFO - PROCESS | 1665 | 10:06:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:06:38 INFO - PROCESS | 1665 | RSS: Main: 206340096 10:06:38 INFO - PROCESS | 1665 | 10:06:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:06:42 INFO - PROCESS | 1665 | RSS: Main: 206647296 10:06:42 INFO - PROCESS | 1665 | 10:06:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:06:46 INFO - PROCESS | 1665 | RSS: Main: 206675968 10:06:46 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:06:47 INFO - PROCESS | 1665 | RSS: Main: 208609280 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | __start_tp_report 10:06:47 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 10:06:47 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 10:06:47 INFO - PROCESS | 1665 | |i|pagename|runs| 10:06:47 INFO - PROCESS | 1665 | |0;ai-astar;107;119;117;116;120;116;119;113;118;118 10:06:47 INFO - PROCESS | 1665 | |1;audio-beat-detection;139;109;109;145;115;116;110;111;138;112 10:06:47 INFO - PROCESS | 1665 | |2;audio-dft;197;238;274;195;196;197;192;193;228;278 10:06:47 INFO - PROCESS | 1665 | |3;audio-fft;82;50;63;74;79;76;84;84;71;76 10:06:47 INFO - PROCESS | 1665 | |4;audio-oscillator;76;71;91;70;70;69;84;67;70;88 10:06:47 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;86;89;86;89;86;90;87;89;106 10:06:47 INFO - PROCESS | 1665 | |6;imaging-darkroom;99;101;100;100;100;98;100;100;134;99 10:06:47 INFO - PROCESS | 1665 | |7;imaging-desaturate;89;93;86;88;119;142;92;90;89;89 10:06:47 INFO - PROCESS | 1665 | |8;json-parse-financial;55;55;55;57;60;61;68;58;59;59 10:06:47 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;72;67;80;77;71;63;67;66;69 10:06:47 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;92;88;100;101;104;73;96;95;101;86 10:06:47 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;137;153;149;173;155;159;133;145;180;138 10:06:47 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;165;166;164;164;169;165;164;162;167;163 10:06:47 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;66;69;67;67;64;66;67;64;66 10:06:47 INFO - PROCESS | 1665 | __end_tp_report 10:06:47 INFO - PROCESS | 1665 | __start_cc_report 10:06:47 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,908 10:06:47 INFO - PROCESS | 1665 | __end_cc_report 10:06:47 INFO - PROCESS | 1665 | __startTimestamp1487095607985__endTimestamp 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | ------- Summary: start ------- 10:06:47 INFO - PROCESS | 1665 | Number of tests: 14 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:116.30 Median:118.00 stddev:3.83 (3.2%) stddev-sans-first:2.12 10:06:47 INFO - PROCESS | 1665 | Values: 107.0 119.0 117.0 116.0 120.0 116.0 119.0 113.0 118.0 118.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:115.50 stddev:14.28 (12.4%) stddev-sans-first:13.47 10:06:47 INFO - PROCESS | 1665 | Values: 139.0 109.0 109.0 145.0 115.0 116.0 110.0 111.0 138.0 112.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:218.80 Median:212.50 stddev:34.04 (16.0%) stddev-sans-first:35.17 10:06:47 INFO - PROCESS | 1665 | Values: 197.0 238.0 274.0 195.0 196.0 197.0 192.0 193.0 228.0 278.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:73.90 Median:77.50 stddev:10.56 (13.6%) stddev-sans-first:10.78 10:06:47 INFO - PROCESS | 1665 | Values: 82.0 50.0 63.0 74.0 79.0 76.0 84.0 84.0 71.0 76.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:73.50 stddev:8.78 (12.0%) stddev-sans-first:9.32 10:06:47 INFO - PROCESS | 1665 | Values: 76.0 71.0 91.0 70.0 70.0 69.0 84.0 67.0 70.0 88.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.00 stddev:5.93 (6.7%) stddev-sans-first:6.28 10:06:47 INFO - PROCESS | 1665 | Values: 89.0 86.0 89.0 86.0 89.0 86.0 90.0 87.0 89.0 106.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:117.50 stddev:10.89 (9.3%) stddev-sans-first:11.45 10:06:47 INFO - PROCESS | 1665 | Values: 99.0 101.0 100.0 100.0 100.0 98.0 100.0 100.0 134.0 99.0 10:06:47 INFO - PROCESS | 1665 | 10:06:47 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:89.00 stddev:18.22 (20.5%) stddev-sans-first:19.05 10:06:47 INFO - PROCESS | 1665 | Values: 89.0 93.0 86.0 88.0 119.0 142.0 92.0 90.0 89.0 89.0 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:3.92 (6.6%) stddev-sans-first:3.92 10:06:48 INFO - PROCESS | 1665 | Values: 55.0 55.0 55.0 57.0 60.0 61.0 68.0 58.0 59.0 59.0 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:5.31 (7.6%) stddev-sans-first:5.45 10:06:48 INFO - PROCESS | 1665 | Values: 66.0 72.0 67.0 80.0 77.0 71.0 63.0 67.0 66.0 69.0 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:87.00 stddev:9.32 (10.7%) stddev-sans-first:9.87 10:06:48 INFO - PROCESS | 1665 | Values: 92.0 88.0 100.0 101.0 104.0 73.0 96.0 95.0 101.0 86.0 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:154.00 stddev:15.36 (10.0%) stddev-sans-first:15.28 10:06:48 INFO - PROCESS | 1665 | Values: 137.0 153.0 149.0 173.0 155.0 159.0 133.0 145.0 180.0 138.0 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.00 stddev:2.02 (1.2%) stddev-sans-first:2.15 10:06:48 INFO - PROCESS | 1665 | Values: 165.0 166.0 164.0 164.0 169.0 165.0 164.0 162.0 167.0 163.0 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.49 (2.2%) stddev-sans-first:1.56 10:06:48 INFO - PROCESS | 1665 | Values: 67.0 66.0 69.0 67.0 67.0 64.0 66.0 67.0 64.0 66.0 10:06:48 INFO - PROCESS | 1665 | -------- Summary: end -------- 10:06:48 INFO - PROCESS | 1665 | 10:06:48 INFO - TEST-INFO | 1665: exit 0 10:06:48 INFO - TEST-OK | kraken | took 59300ms 10:06:48 INFO - SUITE-END | took 1173s 10:06:49 INFO - Completed test suite (00:19:33) 10:06:49 INFO - javascript benchmark 10:06:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7356.324026306828, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3527.8059706716126, 3570.3037907608027, 3607.098225443639, 3608.1701208721993, 3609.4224924012165, 6002.699865006749, 6068.757249710017, 6072.089186622009, 6085.2089228400355, 6088.66512341821, 6041.154238406615, 6063.120847477113, 6130.417610327023, 6140.81577552673, 6155.692215389224, 4679.27471241958, 5464.781408743645, 5520.91718624221, 5539.335279766414, 5596.58025648078, 5586.497215250627, 5598.804041858536, 5604.467575580334, 5613.522850557708, 5647.802326918559, 5432.994895944244, 5472.589555783332, 5594.776208951637, 5598.384177740445, 5611.186377975191, 5520.723963801794, 5527.115661494162, 5567.47109024642, 5577.135543990688, 5643.435656434365, 5428.75570599324, 5515.918220516829, 5530.308711411074, 5536.252605898192, 5568.470995255485, 5667.234923285386, 6110.450059494667, 6116.96941515294, 6154.599951003171, 6169.0, 6186.56694031418, 6278.968605156991, 6288.622682639057, 6305.936940630627, 6420.743170273184, 6480.416762491352, 6481.481481481517, 6494.285628580852, 6497.967510162466, 6507.251666058369, 6143.986242270064, 6165.352637972993, 6198.070289456573, 6200.348963358872, 6207.813765586994, 6343.270523889812, 6435.935640643534, 6471.805845824632, 6480.935190648128, 6486.902696459557, 6323.146375239376, 6325.335839736854, 6330.68346582669, 6330.873382532415, 6343.175387199634, 6400.583962042546, 6454.483641308684, 6473.126127972663, 6473.643949582818, 6474.935250647433, 6246.187995560548, 6250.562460627712, 6258.561900667, 6305.117283580303, 6333.778317758948, 6342.365763423621, 6359.141515895388, 6374.84062898422, 6397.328280530571, 6407.10300557913, 5155.9430316784965, 5192.21467005102, 5215.47845215483, 5216.713080780518, 5252.789888404421, 4563.657725670588, 4572.862814115582, 4602.010567727954, 4677.742724150136, 4713.599344055794, 4419.690621656518, 5970.984932561475, 6101.145839582462, 6179.783707570214, 6210.844728881723, 6458.903116453257, 6467.547271690936, 6469.967650161719, 6479.643619600873, 6490.350964903566, 6050.2437195350585, 6132.0, 6132.386761323921, 6136.21274067861, 6143.266246520256, 5659.858503537363, 5681.261436013292, 5705.515031222392, 5712.028955077563, 5725.312962444534, 6650.534462587572, 6655.301193374723, 6665.466762658975, 6689.698963546554, 6702.899456508254, 6283.151774510383, 6327.936720632735, 6385.68071596409, 6399.840003999937, 6483.935160648333, 6665.0335701324, 6690.364415380531, 6699.363560461748, 6701.765438209639, 6713.966430167818, 6422.908105621962, 6424.903626445701, 6433.710483028369, 6442.935570644234, 6443.548951573345, 6279.058141278845, 6323.241211054703, 6403.519736019724, 6464.5151613630105, 6515.250746164063, 6157.53818463626, 6223.253209614875, 6248.968755156195, 6288.591241569284, 6300.653464059521, 5102.4897510248675, 6283.214598175228, 6288.905666415099, 6303.0, 6304.684765761602, 6144.969275153596, 6165.630062196283, 6179.3820617937845, 6204.255489341308, 6280.434760871543, 6221.9377806223165, 6226.844328891632, 6261.373862613702, 6305.716242769178, 6311.147995020613, 5189.065968125623, 5685.746832546607, 6089.086637004485, 6174.722137503913, 6251.0], "value": 5942.371822965534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.25857145709784, 472.79433446450787, 479.9112164249612, 546.4944925943502, 553.9058360078786, 34737.65262347376, 41569.79215103928, 41650.58349416505, 41718.791406042925, 41754.956126096826, 42111.0, 42984.0, 43335.78332108343, 43418.56581434185, 43487.34768978468, 9573.569189386495, 9589.089036541518, 9616.80766384672, 9705.514724263758, 9806.656767013155, 9475.38410003351, 9523.666671666493, 9624.711258662217, 9629.518524073803, 9635.132838044574, 9467.668631597895, 9472.905270947305, 9533.380330278544, 9591.472469014201, 9630.325877188565, 9465.526723663823, 9552.0, 9621.663241786573, 9622.037796220391, 9641.37331073478, 9401.529923503798, 9498.525073746285, 9584.37701549397, 9609.279304052225, 9619.951900240523, 40059.59940400617, 40160.995975100566, 40353.596464035276, 40944.795276023724, 41166.176676466304, 2961.7778666599875, 3558.3061303045915, 3621.8732344367822, 3835.673967712748, 4084.9387259191135, 24734.639594822278, 27752.02867899634, 28857.422851543062, 31209.0, 31608.525872111935, 3064.88131831882, 3067.877284908601, 3254.4304746669236, 3302.075418882717, 3312.834358282076, 513361.0, 515180.0, 517212.41393793165, 517404.0, 518920.0, 65083.69832603321, 65536.0, 65670.34329656643, 65753.0, 65906.67046664831, 65527.0, 66411.33588664052, 66440.0, 66655.0, 66874.33125668681, 16292.674146517003, 16298.592535186479, 16814.57963550921, 16832.158392080346, 17134.0, 13011.869881301254, 13034.152780069266, 13105.27920964356, 13131.540396086091, 13141.408636611375, 14936.925315373572, 17546.649067018843, 18351.908240458713, 18729.344472943652, 18808.811911880704, 6777.525573209828, 6806.76176333826, 6844.93155068443, 6849.863002740017, 6887.414569761627, 882.8719835623799, 946.3895787217344, 968.5060619084318, 986.8667729856378, 1115.7154925493946, 30682.693173067983, 30838.38323233523, 30949.226269343446, 31291.84354078215, 31299.687003130133, 1050.9789804203874, 1073.8281874899978, 1094.8084085285202, 1103.8730545987153, 1108.728361551425, 15580.454684086002, 16872.831271687126, 16969.490915272563, 17072.975621462752, 17366.871153374992, 889336.0, 904685.0, 904845.0, 905564.0, 906910.0, 61077.08384374238, 61604.0, 61628.0, 61731.691341543, 61931.0, 1233.4511142541483, 1356.9118007329491, 1382.5483242595235, 1407.8732914037785, 1414.144442612246, 30756.538651919793, 31977.520337195427, 32247.87132450353, 32657.34685306328, 33009.83495082605, 52033.7398313006, 54122.0, 54436.72781636067, 54490.0, 54547.909041818144, 501816.0, 501862.0, 502039.0, 503613.0, 503660.0, 27114.0, 27217.455650886477, 27562.448751024465, 27595.03417380382, 28082.85958570194, 47676.523234767206, 47750.80622984453, 48841.511584883694, 49397.0, 49407.50592494029, 39631.0, 40074.79962600285, 40090.0, 40533.98665033398, 41095.7945210272, 39140.6085939137, 39351.80324098361, 39881.0, 40186.19627607373, 40946.385804213554, 38515.6148438512, 39026.02434939149, 39165.804170980104, 39253.41119883147, 39272.0, 26087.347816304744, 30235.546466803455, 34142.317153657285, 34907.47638785471, 35530.644693552735, 25912.22263332103, 26307.0, 26564.601530976663, 26575.335616608965, 27044.45911081807], "value": 20430.96315253309, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.4818055969986, 386.50720331577236, 390.28772490205375, 393.8877419935318, 412.5997782151312, 17931.372401965935, 19405.902970485145, 20638.793612063895, 20720.89639551802, 21218.151273949024, 20759.377218683425, 20834.062467188975, 20894.0, 21061.57876842462, 21327.466813329673, 5688.857778555538, 5733.655980641154, 5859.7656093756195, 5904.940950590503, 5910.940890591082, 5685.13846368631, 5739.139129130622, 5760.62555933865, 5810.389909059551, 5876.970615146918, 5703.144528320764, 5847.064469684851, 5852.824415267528, 5855.560832937525, 5857.150713146591, 5502.147167189092, 5671.347795003545, 5838.941610583882, 5849.210356601868, 5853.385394533555, 4996.0, 4998.950010499885, 5072.442031376546, 5091.439941606421, 5095.719735414551, 24005.519889602285, 24055.03779848804, 25213.621795672898, 25534.744652553607, 25573.0, 1601.2474137155518, 2046.6213750456213, 2239.876806775627, 2512.196097248881, 2997.4904266274784, 10064.647737329158, 10181.694549163536, 10211.183105351554, 10640.733981650443, 10683.14534837211, 1847.898365589892, 1938.6122775444967, 1943.650142974264, 1980.4058782365378, 2000.0, 101273.4936325321, 116492.0, 126758.0, 128156.0, 137617.0, 9468.57391417388, 11793.823092653616, 11835.940820295844, 11914.46384912681, 12006.0, 15079.54761357161, 15139.0, 15148.545543633709, 15150.31823567942, 15213.31540080699, 7610.657520411595, 8662.35032372562, 8836.64653413869, 8871.64513419456, 8930.821383572422, 8698.173673501007, 8766.649334026695, 8770.365968404943, 8874.0, 8912.287017038621, 4303.376010478526, 4645.581897629229, 4648.604868586208, 4651.418572678415, 4704.976475117603, 1728.0150314320967, 1815.210383483189, 2011.6680747676762, 2013.6073465674347, 2013.6375452418413, 894.7360528644066, 901.8962819275736, 915.1169121797365, 921.083521895718, 991.1476130527884, 20238.291659791837, 20723.171073157206, 21130.683039754414, 21213.363599091743, 21245.256416025364, 945.4421891084294, 957.7701351675547, 986.2159583131428, 1000.5097502224004, 1054.5623566220086, 9244.583993720164, 10389.116925061455, 11152.442377881074, 11182.384968826793, 11194.888051119548, 626421.0, 635111.0, 635289.0, 644362.0, 650487.0, 129911.0, 139941.6005839949, 145043.0, 146954.0, 147632.0, 926.2867591954158, 943.1464524605243, 980.1717548671361, 981.2444417798256, 986.8223719730374, 18762.155702993125, 19162.233510659975, 19358.225670973003, 19454.8054519453, 19478.8052119477, 55296.0, 57698.4230157693, 58011.0, 58762.0, 61349.773004538765, 85029.0, 87032.5648371754, 87274.69087963559, 88092.5595372019, 89863.0, 8014.759557213293, 8133.308668762985, 8244.54654993981, 8384.99380074395, 8407.033191183093, 9264.166225039779, 11221.877812218712, 11685.182037257651, 13045.152065115737, 13181.60455186346, 8901.33240007015, 9373.906260937303, 10886.40124793144, 11049.33703977764, 11208.327500350006, 10933.45332733376, 11163.162762792661, 11211.66365009051, 11384.316940983568, 11390.886091138982, 10187.949060254949, 10504.78990420172, 10822.458877056273, 11021.669349919515, 11392.974632283134, 8045.0, 8127.634256458592, 8281.130481299377, 8406.07533171363, 8434.493930364199, 9503.429794212369, 10862.424948382599, 10940.75714478514, 11000.449977501254, 11123.388213648002], "value": 9649.31141727984, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1512.2741084279544, 1531.287951102737, 1645.1198608744323, 1783.7324401339797, 1868.9532761680948, 7704.6918123275, 7937.563434011127, 8094.271515563598, 8647.048824629286, 8655.61049752761, 8110.837783244331, 8422.452540584858, 8427.494350338984, 8447.0, 8539.615717292721, 2347.7652234776556, 2375.821813364005, 2394.916177933773, 2395.568797616428, 2409.819263555232, 2408.6989126359204, 2414.311921102486, 2426.8786560671983, 2430.2466235466973, 2455.9017639294407, 2284.006457191119, 2292.33522278539, 2309.3072078376504, 2319.0375993962484, 2345.777151170636, 2372.430616652008, 2376.239603326936, 2401.2196036288187, 2401.4956859059616, 2409.229046705055, 2057.9915841237835, 2412.8914198861094, 2431.8297719159664, 2435.0, 2440.2679196241056, 6498.837529061764, 7305.707771689179, 8196.877046844302, 8203.58982050895, 8359.414840961135, 5197.870053248699, 5295.708736019517, 5324.866878328034, 5327.493888080626, 5418.322709661293, 3402.897913062612, 3436.518887355772, 3457.2566898116775, 3497.318022985519, 3544.468329750532, 1029.0995379043336, 1037.9013993670665, 1082.5345101606306, 1109.3732041396586, 1116.609186784627, 10973.451327433597, 11078.0, 11135.05352045077, 11155.609553665663, 11174.776504469946, 4607.193741095295, 4740.170470167775, 4798.184308667535, 4824.34871292371, 4837.37114175155, 9709.902900971041, 9724.0, 9737.172340351008, 9742.123208911233, 9842.114209721016, 5291.947080529222, 5353.0, 5371.328583927009, 5379.35447746273, 5384.865378365494, 4367.344898265285, 4406.537313582092, 4571.0, 4634.675572709945, 4848.272759086165, 3906.2187562487047, 3976.840926362971, 4020.8190631421658, 4044.3933409988736, 4088.1194650460525, 1288.1948782011243, 1319.4260496683974, 1321.6761893336002, 1327.860574639649, 1329.1559859489294, 786.7443080998698, 910.961503885571, 915.54680433186, 925.6898938855425, 938.1603464898877, 5509.614326997072, 5548.0845660466375, 5606.971965140148, 5609.186667933209, 5720.971395143081, 355.4171159298752, 454.6726357022938, 455.53307859444334, 458.601017115112, 460.0109764007411, 3561.4479755637917, 3622.8913132606062, 3677.9080522987138, 3755.9436608450897, 3820.67524260441, 9943.950280248553, 10249.282550221413, 10318.329308594917, 10374.844377334195, 10391.636292729718, 9904.801903962025, 9996.700098997042, 10219.0, 10280.742981425525, 10348.948255258676, 7939.20607939213, 7968.800779980547, 8035.638396272182, 8037.196280371916, 8107.108218096005, 5446.618736688394, 5525.668459892419, 5544.334679838284, 5611.859703507445, 5618.915716264341, 9209.86185207209, 9448.149666530046, 9556.092171243721, 9583.568739406883, 9697.272704547046, 9208.263338932735, 9474.0, 9534.141927226583, 9554.187894029086, 9598.472084035166, 6760.290169532128, 6919.515633905578, 7143.107111611049, 7251.1298644163035, 7410.777676669709, 7928.603569821601, 7983.481073730189, 8037.879431808645, 8063.233992770677, 8139.023317201973, 7165.498415110892, 7217.639118043971, 7321.084864391951, 7381.077365329334, 7413.073365829271, 7063.540869843444, 7114.0396046535125, 7148.499605027599, 7152.821179470555, 7172.426205903411, 6716.3955244028275, 6786.8642627148165, 6855.862882742417, 6877.3810357068105, 6883.311668833277, 4785.353977213032, 4838.177509823268, 4840.927386089141, 4889.315495830658, 4916.139675556835, 4782.234842425195, 4809.44691360498, 4809.711417314972, 4911.557959783494, 4915.336429581961], "value": 4396.246159071848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.70807298300286, 286.91392582225336, 310.27705446310085, 319.14469222483734, 321.43106701139, 4629.3334399616115, 5476.479734425223, 5592.328920529532, 5652.604317697763, 5787.855303617406, 4780.952190478094, 5408.378036525801, 5416.268803711498, 5534.723263836802, 5548.500634942854, 3543.114221444639, 3688.944665830015, 4211.557786432427, 4216.241076606209, 4220.409142720014, 3499.777641197845, 3976.7216294859372, 4126.442930204414, 4237.131388065454, 4273.893152671177, 3462.7402944779114, 3474.8783792567265, 4115.176964607091, 4166.333386658135, 4170.582941705836, 4148.543660197376, 4154.896127596804, 4159.0, 4159.147374788176, 4202.394515400471, 3021.6071910651694, 3273.6398996110647, 3458.7375607902936, 3623.8188090595363, 3655.342038433081, 4592.611841842257, 5173.17229243323, 5391.487808658171, 5393.865153371158, 5415.783368665248, 3953.723239373245, 4741.359916411275, 4770.546798054179, 4774.116788394158, 4785.234362501983, 5048.318477005594, 5829.737661805187, 5837.357890632027, 5862.006917647977, 5891.882162356729, 3857.7492462989817, 3924.9018774530578, 3933.409988501719, 3970.2456533258587, 3984.183242435323, 6107.603005804653, 6113.877722445526, 6188.938110618927, 6191.721372538292, 6199.566030377875, 3117.1116231873807, 3369.966300336965, 4038.9798051010143, 4040.3535434330365, 4090.1615168890307, 3578.5526809148855, 4036.2129384769687, 4070.2266569351727, 4119.9176016479505, 4139.482564679415, 4641.280601506772, 4703.836235331717, 4708.599769019604, 4734.07685501324, 4757.452892917289, 4861.708297502161, 4882.511748825089, 5741.712914354265, 5761.596688231783, 5792.972459639092, 1136.909047276216, 1235.7899157143306, 1294.4239813283186, 1300.6928037322618, 1332.9200247985152, 779.4037561265636, 882.3338379523547, 889.9943064337258, 902.359324879343, 903.679193886161, 4668.626509879202, 4868.196747536616, 5203.383648364351, 5216.58267338612, 5242.659227150223, 4647.536026151819, 4963.528464795911, 5243.711595862188, 5283.471652834686, 5484.848181881849, 4365.432493775789, 4467.906203822134, 4837.565328616652, 4941.257602030907, 4993.800247990112, 2638.5910183921524, 2765.502209602251, 2969.3615872587498, 3008.3231272963408, 3015.5374643297982, 5247.580193584504, 5432.713208508874, 5949.9107513387335, 6000.969995150083, 6060.787872424444, 5069.873253168626, 5914.231149950565, 5922.851928701731, 5922.8815423691285, 5963.910541341883, 5022.59819214462, 5541.556675465953, 5640.661560306313, 5932.139839723302, 5963.612365196248, 5068.808829924891, 5147.9227811582105, 5734.942650573441, 5777.682227477361, 5782.352029671523, 4568.680192386501, 4576.725396476222, 4814.6870453420415, 5273.472652734849, 5606.439356064361, 5262.97368513155, 5624.846906384263, 5962.1951036611235, 6006.498375406149, 6016.886875927968, 3700.1869084148034, 3833.3866581347966, 3847.576766555733, 3866.2654095721723, 3874.070223146481, 4296.871093867189, 4947.734495233129, 5436.238926550207, 5695.544356451557, 5875.383084776037, 5857.47282744555, 5877.118432235028, 5877.794277200277, 5884.116782776494, 5895.29256489224, 4598.0, 5679.744411501575, 5826.436367998067, 5855.619384739978, 5895.498882595028, 5588.972055139861, 5723.3418156912485, 5743.4543718346695, 5780.710964451845, 5782.6988927490975, 5874.765009399576, 5878.000739874317, 5886.705664716661, 5910.965581023218, 5913.231279933753, 5042.814938489525, 5461.5357694596405, 5491.258680078298, 5755.309362876482, 5772.451617096369], "value": 4117.4026555265855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.7024619905836, 766.2184571736829, 789.4158322841092, 793.5913004802526, 848.7411339541442, 14610.780838287434, 15040.247987600604, 15373.692526149498, 15422.768658470131, 15653.217339133027, 13904.652383690387, 13964.581062568113, 14050.64873378166, 14106.365213565388, 14193.574192774233, 669.8526324208678, 854.9700760473385, 858.720915702396, 859.944103633265, 860.4450129666361, 834.7996480844614, 845.6828689241535, 879.0330636300051, 881.8765372847806, 888.1695614600337, 877.0484024833064, 879.520661239626, 901.5266984832949, 914.9268058555333, 915.0620613870769, 877.2105105405154, 901.5762591581946, 902.7833320003185, 910.9979023074665, 911.6034524981588, 649.9155109835739, 652.2922628947598, 673.8921772516422, 674.2684187656399, 676.4655921821754, 14471.0, 14743.0, 14993.700125997528, 15074.472393466176, 15194.696106077927, 12628.747425051448, 13868.372395182609, 13999.501619735858, 14111.435542578387, 14150.646233844132, 10811.513481893255, 11710.76578468426, 11855.762884742344, 11915.344656043913, 11945.03963199929, 3444.689977901976, 3511.613722490524, 3707.277080969212, 3759.868404605853, 3800.410936304877, 78968.21031789666, 92170.15659686769, 93126.06873931241, 94279.64300892464, 95002.0499795, 8079.353651707849, 9712.223022158352, 10198.643047493302, 10202.744931376626, 10220.846687299696, 19118.808811911982, 19150.0, 19169.04154792255, 19255.614887702166, 19380.321688740827, 7642.312191902644, 7820.178881217504, 7827.647755850887, 7881.487703299267, 7927.128015918244, 4379.956200438019, 4467.597916187558, 4564.315352697122, 4574.405327307495, 4587.610053145453, 3361.3781450431748, 3477.3393055319407, 3477.51314815926, 3624.2751449710163, 3804.5434547854434, 575.6891278709534, 597.6832278892194, 685.3146853146853, 693.303230253602, 702.357343031131, 571.4742436958009, 639.9104125422444, 643.4787821864211, 659.7427003468651, 693.3101563943908, 9149.63401463934, 9293.3330002748, 9351.579178936829, 9595.856062159073, 9712.854307185398, 626.8150895485843, 648.18652590998, 672.4284358295507, 676.0940339944498, 697.4315932514984, 4175.976885663032, 4416.227160247008, 4494.5505449454795, 4712.811487540528, 4713.622910167179, 78737.85048598106, 98964.50517747509, 117611.0, 117905.0, 123099.0, 83956.0, 93890.53054734683, 105224.94775052305, 109522.45238773915, 110212.0, 65471.67264163648, 66441.00338494926, 82302.58848705718, 92588.53705731429, 93200.0, 8785.560721963748, 9145.817083658423, 9158.908410916072, 9176.944651365178, 9300.302477314364, 51921.48078519318, 58066.129008065756, 58133.70933145307, 58515.70742146262, 59219.70390148194, 53992.73003634957, 57562.7121864388, 74521.0, 77036.61481692556, 80560.59719701365, 11485.540578377106, 12038.518459261531, 12734.490076145301, 12910.289934053342, 13171.0, 14213.289335533389, 14474.710505790035, 14720.0, 14850.183239921907, 14943.028703134261, 13383.665408364868, 13852.722945541234, 14075.718485630025, 14444.350004250044, 14477.638059048608, 13547.322633868464, 13818.239996800272, 13819.654508636962, 14407.0, 14455.927720361331, 13384.93307533456, 13501.0, 13525.458981640624, 14180.929095354457, 14226.14643121416, 11382.658520244406, 11480.770384592428, 11524.078073753912, 11596.826047609462, 11923.642290731292, 9676.806463870824, 11371.507850163363, 11638.410227904364, 11723.237989530653, 11757.176997610428], "value": 7472.5232632787, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 117.0, 116.0, 120.0, 116.0, 119.0, 113.0, 118.0, 118.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 109.0, 145.0, 115.0, 116.0, 110.0, 111.0, 138.0, 112.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 238.0, 274.0, 195.0, 196.0, 197.0, 192.0, 193.0, 228.0, 278.0], "value": 218.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 50.0, 63.0, 74.0, 79.0, 76.0, 84.0, 84.0, 71.0, 76.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 91.0, 70.0, 70.0, 69.0, 84.0, 67.0, 70.0, 88.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 89.0, 86.0, 90.0, 87.0, 89.0, 106.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 100.0, 100.0, 100.0, 98.0, 100.0, 100.0, 134.0, 99.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 86.0, 88.0, 119.0, 142.0, 92.0, 90.0, 89.0, 89.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 57.0, 60.0, 61.0, 68.0, 58.0, 59.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 67.0, 80.0, 77.0, 71.0, 63.0, 67.0, 66.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 100.0, 101.0, 104.0, 73.0, 96.0, 95.0, 101.0, 86.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 153.0, 149.0, 173.0, 155.0, 159.0, 133.0, 145.0, 180.0, 138.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 164.0, 164.0, 169.0, 165.0, 164.0, 162.0, 167.0, 163.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 67.0, 67.0, 64.0, 66.0, 67.0, 64.0, 66.0], "value": 66.3, "unit": "score"}]}]} 10:06:49 INFO - Return code: 0 10:06:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:06:49 ERROR - # TBPL SUCCESS # 10:06:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:06:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:06:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:06:49 INFO - 2017-02-14 10:06:49.315 screenresolution[1670:10445] starting screenresolution argv=screenresolution get 10:06:49 INFO - 2017-02-14 10:06:49.318 screenresolution[1670:10445] Display 0: 1600x1200x32@60 10:06:49 INFO - 2017-02-14 10:06:49.324 screenresolution[1671:10448] starting screenresolution argv=screenresolution list 10:06:49 INFO - Available Modes on Display 0 10:06:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:06:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:06:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:06:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:06:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:06:49 INFO - Intel Iris: 10:06:49 INFO - Chipset Model: Intel Iris 10:06:49 INFO - Type: GPU 10:06:49 INFO - Bus: Built-In 10:06:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:06:49 INFO - Vendor: Intel (0x8086) 10:06:49 INFO - Device ID: 0x0a2e 10:06:49 INFO - Revision ID: 0x0009 10:06:49 INFO - Displays: 10:06:49 INFO - Display: 10:06:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:06:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:06:49 INFO - Display Serial Number: 1600x1200 60 10:06:49 INFO - Main Display: Yes 10:06:49 INFO - Mirror: Off 10:06:49 INFO - Online: Yes 10:06:49 INFO - Rotation: Supported 10:06:49 INFO - Return code: 0 10:06:49 INFO - Running post-action listener: _resource_record_post_action 10:06:49 INFO - [mozharness: 2017-02-14 18:06:49.562781Z] Finished run-tests step (success) 10:06:49 INFO - Running post-run listener: _resource_record_post_run 10:06:49 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39356416; Write bytes: 503135232; Read time: 251; Write time: 1590 10:06:49 INFO - TinderboxPrint: CPU usage
54.1% 10:06:49 INFO - TinderboxPrint: I/O read bytes / time
39,356,416 / 251 10:06:49 INFO - TinderboxPrint: I/O write bytes / time
503,135,232 / 1,590 10:06:49 INFO - TinderboxPrint: CPU idle
2,197.7 (45.9%) 10:06:49 INFO - TinderboxPrint: CPU system
965.7 (20.2%) 10:06:49 INFO - TinderboxPrint: CPU user
1,622.3 (33.9%) 10:06:49 INFO - TinderboxPrint: Swap in / out
230,928,384 / 0 10:06:49 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 177250816; Write bytes: 177031168; Read time: 16678; Write time: 373 10:06:49 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 38381568; Write bytes: 308368384; Read time: 235; Write time: 1166 10:06:50 INFO - Running post-run listener: _upload_blobber_files 10:06:50 INFO - Blob upload gear active. 10:06:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:06:50 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 10:06:50 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:06:50 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:06:50 INFO - (blobuploader) - INFO - Open directory for files ... 10:06:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:06:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:06:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:06:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:06:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:06:51 INFO - (blobuploader) - INFO - Done attempting. 10:06:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:06:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:06:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:06:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:06:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:06:52 INFO - (blobuploader) - INFO - Done attempting. 10:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:06:54 INFO - (blobuploader) - INFO - Done attempting. 10:06:54 INFO - (blobuploader) - INFO - Iteration through files over. 10:06:54 INFO - Return code: 0 10:06:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:06:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:06:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2fdb46750933860a714c6bd9213c959172fee9ac093b0434e50f3ae4089b9a3d82fc030d81a13c7637915f876280699ee4f3f36fbd09c50f0a63fe4939e7abbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1607894cc34b93d7e46255d93ec8bf54c31241d34141d209ff4ead0d484de6a3c8b235353556a103983ac99ace93eb4c0047853464fcaeb55cce5b3769d03872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eaeed535433c0ea6da0871ab8d754fd4cfd4ceff97567315a6dc4bcdaa866f94f6cfeb1a1686cc3cf2e0db4f97c25f13f8f4564398b68d66f6fa0836521060e8"} 10:06:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:06:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:06:54 INFO - Contents: 10:06:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2fdb46750933860a714c6bd9213c959172fee9ac093b0434e50f3ae4089b9a3d82fc030d81a13c7637915f876280699ee4f3f36fbd09c50f0a63fe4939e7abbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1607894cc34b93d7e46255d93ec8bf54c31241d34141d209ff4ead0d484de6a3c8b235353556a103983ac99ace93eb4c0047853464fcaeb55cce5b3769d03872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eaeed535433c0ea6da0871ab8d754fd4cfd4ceff97567315a6dc4bcdaa866f94f6cfeb1a1686cc3cf2e0db4f97c25f13f8f4564398b68d66f6fa0836521060e8"} 10:06:54 INFO - Running post-run listener: copy_logs_to_upload_dir 10:06:54 INFO - Copying logs to upload dir... 10:06:54 INFO - mkdir: /builds/slave/test/build/upload/logs 10:06:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.225529 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-02-14 10:06:54.941054) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 10:06:54.944895) ========= 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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2fdb46750933860a714c6bd9213c959172fee9ac093b0434e50f3ae4089b9a3d82fc030d81a13c7637915f876280699ee4f3f36fbd09c50f0a63fe4939e7abbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1607894cc34b93d7e46255d93ec8bf54c31241d34141d209ff4ead0d484de6a3c8b235353556a103983ac99ace93eb4c0047853464fcaeb55cce5b3769d03872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eaeed535433c0ea6da0871ab8d754fd4cfd4ceff97567315a6dc4bcdaa866f94f6cfeb1a1686cc3cf2e0db4f97c25f13f8f4564398b68d66f6fa0836521060e8"} build_url:https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011004 build_url: 'https://queue.taskcluster.net/v1/task/BdPrrjp6Q5SBpW1w-D7YyQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2fdb46750933860a714c6bd9213c959172fee9ac093b0434e50f3ae4089b9a3d82fc030d81a13c7637915f876280699ee4f3f36fbd09c50f0a63fe4939e7abbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1607894cc34b93d7e46255d93ec8bf54c31241d34141d209ff4ead0d484de6a3c8b235353556a103983ac99ace93eb4c0047853464fcaeb55cce5b3769d03872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eaeed535433c0ea6da0871ab8d754fd4cfd4ceff97567315a6dc4bcdaa866f94f6cfeb1a1686cc3cf2e0db4f97c25f13f8f4564398b68d66f6fa0836521060e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 10:06:54.979303) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 10:06:54.979641) ========= 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.lPWUFfCKVS/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.sQbEHNkXcj/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.005103 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 10:06:55.028064) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 10:06:55.028350) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 10:06:55.028633) ========= ========= Total master_lag: 0.55 =========