builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0006 starttime: 1478695950.99 results: success (0) buildid: 20161109022623 builduid: 895ccb49025445e2a2bd141e71060333 revision: 533b6efe3528fcb022c857e84bc432b77b5507ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:30.993806) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:30.994223) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:30.994492) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:31.018216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:31.018546) ========= 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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-09 04:52:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.26M=0.001s 2016-11-09 04:52:31 (8.26 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098263 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:31.142476) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:31.143259) ========= 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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.020059 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:31.195202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-09 04:52:31.195514) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 533b6efe3528fcb022c857e84bc432b77b5507ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 533b6efe3528fcb022c857e84bc432b77b5507ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-09 04:52:31,269 truncating revision to first 12 chars 2016-11-09 04:52:31,269 Setting DEBUG logging. 2016-11-09 04:52:31,269 attempt 1/10 2016-11-09 04:52:31,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/533b6efe3528?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-09 04:52:32,392 unpacking tar archive at: cedar-533b6efe3528/testing/mozharness/ program finished with exit code 0 elapsedTime=1.380575 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-09 04:52:32.601303) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:32.601676) ========= script_repo_revision: 533b6efe3528fcb022c857e84bc432b77b5507ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:32.602152) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:32.602466) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 04:52:32.622521) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-09 04:52:32.623285) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:52:32 INFO - MultiFileLogger online at 20161109 04:52:32 in /builds/slave/test 04:52:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 04:52:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:32 INFO - {'append_to_log': False, 04:52:32 INFO - 'base_work_dir': '/builds/slave/test', 04:52:32 INFO - 'blob_upload_branch': 'Cedar', 04:52:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:32 INFO - 'branch': 'Cedar', 04:52:32 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:32 INFO - 'config_files': ('talos/mac_config.py',), 04:52:32 INFO - 'default_actions': ('clobber', 04:52:32 INFO - 'read-buildbot-config', 04:52:32 INFO - 'download-and-extract', 04:52:32 INFO - 'populate-webroot', 04:52:32 INFO - 'create-virtualenv', 04:52:32 INFO - 'install', 04:52:32 INFO - 'run-tests'), 04:52:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:32 INFO - 'download_minidump_stackwalk': True, 04:52:32 INFO - 'download_symbols': 'ondemand', 04:52:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:32 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:32 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:32 INFO - 'installer_path': 'installer.exe', 04:52:32 INFO - 'log_level': 'info', 04:52:32 INFO - 'log_name': 'talos', 04:52:32 INFO - 'log_to_console': True, 04:52:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:52:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:52:32 INFO - 'opt_config_files': (), 04:52:32 INFO - 'pip_index': False, 04:52:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:32 INFO - 'cmd': ('bash', 04:52:32 INFO - '-c', 04:52:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:32 INFO - 'enabled': True, 04:52:32 INFO - 'halt_on_failure': False, 04:52:32 INFO - 'name': 'check_screen_resolution'},), 04:52:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:32 INFO - 'cmd': ('bash', 04:52:32 INFO - '-c', 04:52:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:32 INFO - 'enabled': True, 04:52:32 INFO - 'halt_on_failure': False, 04:52:32 INFO - 'name': 'check_screen_resolution'},), 04:52:32 INFO - 'run_cmd_checks_enabled': True, 04:52:32 INFO - 'sps_profile': False, 04:52:32 INFO - 'sps_profile_interval': 0, 04:52:32 INFO - 'suite': 'dromaeojs-e10s', 04:52:32 INFO - 'system_bits': '32', 04:52:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:32 INFO - 'title': 't-yosemite-r7-0006', 04:52:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:32 INFO - 'use_talos_json': True, 04:52:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:32 INFO - 'work_dir': 'build'} 04:52:32 INFO - [mozharness: 2016-11-09 12:52:32.823571Z] Running clobber step. 04:52:32 INFO - Running pre-action listener: _resource_record_pre_action 04:52:32 INFO - Running main action method: clobber 04:52:32 INFO - rmtree: /builds/slave/test/build 04:52:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:34 INFO - Running post-action listener: _resource_record_post_action 04:52:34 INFO - [mozharness: 2016-11-09 12:52:34.427844Z] Finished clobber step (success) 04:52:34 INFO - [mozharness: 2016-11-09 12:52:34.427969Z] Running read-buildbot-config step. 04:52:34 INFO - Running pre-action listener: _resource_record_pre_action 04:52:34 INFO - Running main action method: read_buildbot_config 04:52:34 INFO - Using buildbot properties: 04:52:34 INFO - { 04:52:34 INFO - "project": "", 04:52:34 INFO - "product": "firefox", 04:52:34 INFO - "script_repo_revision": "default", 04:52:34 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 04:52:34 INFO - "repository": "", 04:52:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 04:52:34 INFO - "buildid": "20161109022623", 04:52:34 INFO - "pgo_build": "False", 04:52:34 INFO - "basedir": "/builds/slave/test", 04:52:34 INFO - "buildnumber": 1, 04:52:34 INFO - "slavename": "t-yosemite-r7-0006", 04:52:34 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:52:34 INFO - "platform": "yosemite_r7", 04:52:34 INFO - "scheduler": "tests-cedar-macosx64-talos", 04:52:34 INFO - "branch": "cedar", 04:52:34 INFO - "repo_path": "projects/cedar", 04:52:34 INFO - "revision": "533b6efe3528fcb022c857e84bc432b77b5507ef", 04:52:34 INFO - "stage_platform": "macosx64", 04:52:34 INFO - "builduid": "895ccb49025445e2a2bd141e71060333", 04:52:34 INFO - "slavebuilddir": "test" 04:52:34 INFO - } 04:52:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:52:34 INFO - Running post-action listener: _resource_record_post_action 04:52:34 INFO - [mozharness: 2016-11-09 12:52:34.431481Z] Finished read-buildbot-config step (success) 04:52:34 INFO - [mozharness: 2016-11-09 12:52:34.431623Z] Running download-and-extract step. 04:52:34 INFO - Running pre-action listener: _resource_record_pre_action 04:52:34 INFO - Running main action method: download_and_extract 04:52:34 INFO - mkdir: /builds/slave/test/build/tests 04:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:34 INFO - trying https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:52:34 INFO - Downloading https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:52:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 04:52:35 INFO - Downloaded 1199 bytes. 04:52:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:52:35 INFO - Using the following test package requirements: 04:52:35 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:52:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:52:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:52:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'jsshell-mac.zip'], 04:52:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:52:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:52:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:52:35 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:52:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:52:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:52:35 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:52:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:52:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:52:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:35 INFO - Fetch https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:52:36 INFO - Http code: 200 04:52:36 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 04:52:36 INFO - x-amz-cf-id: crJflH8SawUYEB9k8c0O6m_buSom_7gOF-oSls7yBiYF44GiCvPUbQ== 04:52:36 INFO - x-amz-version-id: w_8lZipQRrfjEoqViEV2_o7Vb6P8NV9L 04:52:36 INFO - Expected file size: 16318213 04:52:36 INFO - Obtained file size: 16318213 04:52:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:52:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:52:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:43 INFO - Fetch https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:52:44 INFO - Http code: 200 04:52:44 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 04:52:44 INFO - x-amz-cf-id: O7w786VeYIjgegMihzCoIfyc4WiyMGnvY6gAAYvhBNYicoOFlaw7WA== 04:52:44 INFO - x-amz-version-id: w_8lZipQRrfjEoqViEV2_o7Vb6P8NV9L 04:52:44 INFO - Expected file size: 16318213 04:52:44 INFO - Obtained file size: 16318213 04:52:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:52:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:52:50 INFO - Fetch https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:52:51 INFO - Http code: 200 04:52:51 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 04:52:51 INFO - x-amz-cf-id: SFGcc3mBFK3y-yHPW8j_nL_Cs3PySi5Xx_A-vAcnWFBhpAMi77TLwA== 04:52:51 INFO - x-amz-version-id: 5ST5RRKVa1NPLDN9XuTJw5gs5sx.FCOG 04:52:52 INFO - Expected file size: 14409038 04:52:52 INFO - Obtained file size: 14409038 04:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:52 INFO - trying https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:52:52 INFO - Downloading https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:52:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 04:52:55 INFO - Downloaded 111558996 bytes. 04:52:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:52:55 INFO - mkdir: /builds/slave/test/properties 04:52:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:52:55 INFO - Writing to file /builds/slave/test/properties/build_url 04:52:55 INFO - Contents: 04:52:55 INFO - build_url:https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:52:56 INFO - Running post-action listener: _resource_record_post_action 04:52:56 INFO - Running post-action listener: set_extra_try_arguments 04:52:56 INFO - [mozharness: 2016-11-09 12:52:56.743496Z] Finished download-and-extract step (success) 04:52:56 INFO - [mozharness: 2016-11-09 12:52:56.743621Z] Running populate-webroot step. 04:52:56 INFO - Running pre-action listener: _resource_record_pre_action 04:52:56 INFO - Running main action method: populate_webroot 04:52:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:52:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:52:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:52:56 INFO - '--tppagecycles', 04:52:56 INFO - '7'], 04:52:56 INFO - 'tests': ['tsvgm']}}, 04:52:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:52:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:52:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:52:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:52:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['damp', 'tps']}, 04:52:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'tests': ['damp', 'tps']}, 04:52:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['dromaeo_dom']}, 04:52:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:52:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['basic_compositor_video']}, 04:52:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:52:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['a11yr', 04:52:56 INFO - 'ts_paint', 04:52:56 INFO - 'tpaint', 04:52:56 INFO - 'sessionrestore', 04:52:56 INFO - 'sessionrestore_no_auto_restore', 04:52:56 INFO - 'tabpaint']}, 04:52:56 INFO - 'other-e10s': {'tests': ['a11yr', 04:52:56 INFO - 'ts_paint', 04:52:56 INFO - 'tpaint', 04:52:56 INFO - 'sessionrestore', 04:52:56 INFO - 'sessionrestore_no_auto_restore', 04:52:56 INFO - 'tabpaint']}, 04:52:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:52:56 INFO - 'ts_paint', 04:52:56 INFO - 'tpaint', 04:52:56 INFO - 'sessionrestore', 04:52:56 INFO - 'sessionrestore_no_auto_restore', 04:52:56 INFO - 'tabpaint']}, 04:52:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:52:56 INFO - 'ts_paint', 04:52:56 INFO - 'tpaint', 04:52:56 INFO - 'sessionrestore', 04:52:56 INFO - 'sessionrestore_no_auto_restore', 04:52:56 INFO - 'tabpaint']}, 04:52:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['a11yr', 04:52:56 INFO - 'ts_paint', 04:52:56 INFO - 'tpaint', 04:52:56 INFO - 'sessionrestore', 04:52:56 INFO - 'sessionrestore_no_auto_restore', 04:52:56 INFO - 'tabpaint']}, 04:52:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['a11yr', 04:52:56 INFO - 'ts_paint', 04:52:56 INFO - 'tpaint', 04:52:56 INFO - 'sessionrestore', 04:52:56 INFO - 'sessionrestore_no_auto_restore', 04:52:56 INFO - 'tabpaint']}, 04:52:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['tsvgx', 04:52:56 INFO - 'tsvgr_opacity', 04:52:56 INFO - 'tart', 04:52:56 INFO - 'tscrollx', 04:52:56 INFO - 'cart']}, 04:52:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:52:56 INFO - 'tsvgr_opacity', 04:52:56 INFO - 'tart', 04:52:56 INFO - 'tscrollx', 04:52:56 INFO - 'cart']}, 04:52:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'talos_options': ['--disable-e10s'], 04:52:56 INFO - 'tests': ['tp5o']}, 04:52:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'tests': ['tp5o']}, 04:52:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'talos_options': ['--disable-e10s', 04:52:56 INFO - '--xperf_path', 04:52:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:56 INFO - 'tests': ['tp5n']}, 04:52:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:56 INFO - 'talos_options': ['--xperf_path', 04:52:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:56 INFO - 'tests': ['tp5n']}}, 04:52:56 INFO - 'talos.zip': {'path': '', 04:52:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:52:56 INFO - Running post-action listener: _resource_record_post_action 04:52:56 INFO - [mozharness: 2016-11-09 12:52:56.762387Z] Finished populate-webroot step (success) 04:52:56 INFO - [mozharness: 2016-11-09 12:52:56.762516Z] Running create-virtualenv step. 04:52:56 INFO - Running pre-action listener: _resource_record_pre_action 04:52:56 INFO - Running main action method: create_virtualenv 04:52:56 INFO - Creating virtualenv /builds/slave/test/build/venv 04:52:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:52:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:52:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:56 INFO - Using real prefix '/tools/python27' 04:52:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:57 INFO - Installing distribute.............................................................................................................................................................................................done. 04:53:00 INFO - Installing pip.................done. 04:53:00 INFO - Return code: 0 04:53:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:53:00 INFO - Reading from file tmpfile_stdout 04:53:00 INFO - Output received: 04:53:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:53:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:00 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:00 INFO - 'HOME': '/Users/cltbld', 04:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:00 INFO - 'LOGNAME': 'cltbld', 04:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:00 INFO - 'MOZ_NO_REMOTE': '1', 04:53:00 INFO - 'NO_EM_RESTART': '1', 04:53:00 INFO - 'PAGER': '/bin/cat', 04:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:00 INFO - 'PWD': '/builds/slave/test', 04:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:00 INFO - 'SHELL': '/bin/bash', 04:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:00 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:00 INFO - 'USER': 'cltbld', 04:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:00 INFO - 'XPC_FLAGS': '0x0', 04:53:00 INFO - 'XPC_SERVICE_NAME': '0', 04:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:53:01 INFO - Cleaning up... 04:53:01 INFO - Return code: 0 04:53:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:01 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:01 INFO - 'HOME': '/Users/cltbld', 04:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:01 INFO - 'LOGNAME': 'cltbld', 04:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:01 INFO - 'MOZ_NO_REMOTE': '1', 04:53:01 INFO - 'NO_EM_RESTART': '1', 04:53:01 INFO - 'PAGER': '/bin/cat', 04:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:01 INFO - 'PWD': '/builds/slave/test', 04:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:01 INFO - 'SHELL': '/bin/bash', 04:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:01 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:01 INFO - 'USER': 'cltbld', 04:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:01 INFO - 'XPC_FLAGS': '0x0', 04:53:01 INFO - 'XPC_SERVICE_NAME': '0', 04:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:01 INFO - Downloading/unpacking psutil>=3.1.1 04:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:53:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:04 INFO - Installing collected packages: psutil 04:53:04 INFO - Running setup.py install for psutil 04:53:04 INFO - building 'psutil._psutil_osx' extension 04:53:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:53:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:53:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:53:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:53:05 INFO - building 'psutil._psutil_posix' extension 04:53:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:53:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:53:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:53:05 INFO - ^ 04:53:05 INFO - 1 warning generated. 04:53:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:53:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:05 INFO - Successfully installed psutil 04:53:05 INFO - Cleaning up... 04:53:05 INFO - Return code: 0 04:53:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:05 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:05 INFO - 'HOME': '/Users/cltbld', 04:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:05 INFO - 'LOGNAME': 'cltbld', 04:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:05 INFO - 'MOZ_NO_REMOTE': '1', 04:53:05 INFO - 'NO_EM_RESTART': '1', 04:53:05 INFO - 'PAGER': '/bin/cat', 04:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:05 INFO - 'PWD': '/builds/slave/test', 04:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:05 INFO - 'SHELL': '/bin/bash', 04:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:05 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:05 INFO - 'USER': 'cltbld', 04:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:05 INFO - 'XPC_FLAGS': '0x0', 04:53:05 INFO - 'XPC_SERVICE_NAME': '0', 04:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:53:08 INFO - Installing collected packages: mozsystemmonitor 04:53:08 INFO - Running setup.py install for mozsystemmonitor 04:53:08 INFO - Successfully installed mozsystemmonitor 04:53:08 INFO - Cleaning up... 04:53:08 INFO - Return code: 0 04:53:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:08 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:08 INFO - 'HOME': '/Users/cltbld', 04:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:08 INFO - 'LOGNAME': 'cltbld', 04:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:08 INFO - 'MOZ_NO_REMOTE': '1', 04:53:08 INFO - 'NO_EM_RESTART': '1', 04:53:08 INFO - 'PAGER': '/bin/cat', 04:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:08 INFO - 'PWD': '/builds/slave/test', 04:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:08 INFO - 'SHELL': '/bin/bash', 04:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:08 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:08 INFO - 'USER': 'cltbld', 04:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:08 INFO - 'XPC_FLAGS': '0x0', 04:53:08 INFO - 'XPC_SERVICE_NAME': '0', 04:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:09 INFO - Downloading/unpacking jsonschema==2.5.1 04:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:12 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:12 INFO - warning: no files found matching '*.txt' 04:53:12 INFO - no previously-included directories found matching 'build' 04:53:12 INFO - no previously-included directories found matching 'dist' 04:53:12 INFO - no previously-included directories found matching '.git*' 04:53:12 INFO - Installing collected packages: jsonschema, functools32 04:53:12 INFO - Running setup.py install for functools32 04:53:12 INFO - warning: no files found matching '*.txt' 04:53:12 INFO - no previously-included directories found matching 'build' 04:53:12 INFO - no previously-included directories found matching 'dist' 04:53:12 INFO - no previously-included directories found matching '.git*' 04:53:12 INFO - Successfully installed jsonschema functools32 04:53:12 INFO - Cleaning up... 04:53:12 INFO - Return code: 0 04:53:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:53:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:12 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:53:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:12 INFO - 'HOME': '/Users/cltbld', 04:53:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:12 INFO - 'LOGNAME': 'cltbld', 04:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:12 INFO - 'MOZ_NO_REMOTE': '1', 04:53:12 INFO - 'NO_EM_RESTART': '1', 04:53:12 INFO - 'PAGER': '/bin/cat', 04:53:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:12 INFO - 'PWD': '/builds/slave/test', 04:53:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:12 INFO - 'SHELL': '/bin/bash', 04:53:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:12 INFO - 'USER': 'cltbld', 04:53:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:12 INFO - 'XPC_FLAGS': '0x0', 04:53:12 INFO - 'XPC_SERVICE_NAME': '0', 04:53:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:53:12 INFO - Cleaning up... 04:53:12 INFO - Return code: 0 04:53:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:53:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:12 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:53:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:12 INFO - 'HOME': '/Users/cltbld', 04:53:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:12 INFO - 'LOGNAME': 'cltbld', 04:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:12 INFO - 'MOZ_NO_REMOTE': '1', 04:53:12 INFO - 'NO_EM_RESTART': '1', 04:53:12 INFO - 'PAGER': '/bin/cat', 04:53:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:12 INFO - 'PWD': '/builds/slave/test', 04:53:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:12 INFO - 'SHELL': '/bin/bash', 04:53:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:12 INFO - 'USER': 'cltbld', 04:53:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:12 INFO - 'XPC_FLAGS': '0x0', 04:53:12 INFO - 'XPC_SERVICE_NAME': '0', 04:53:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:12 INFO - Downloading/unpacking blobuploader==1.2.4 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:15 INFO - Downloading blobuploader-1.2.4.tar.gz 04:53:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:53:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:53:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:16 INFO - Downloading docopt-0.6.1.tar.gz 04:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:53:16 INFO - Installing collected packages: blobuploader, requests, docopt 04:53:16 INFO - Running setup.py install for blobuploader 04:53:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:53:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:53:16 INFO - Running setup.py install for requests 04:53:17 INFO - Running setup.py install for docopt 04:53:17 INFO - Successfully installed blobuploader requests docopt 04:53:17 INFO - Cleaning up... 04:53:17 INFO - Return code: 0 04:53:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:17 INFO - 'HOME': '/Users/cltbld', 04:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:17 INFO - 'LOGNAME': 'cltbld', 04:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:17 INFO - 'MOZ_NO_REMOTE': '1', 04:53:17 INFO - 'NO_EM_RESTART': '1', 04:53:17 INFO - 'PAGER': '/bin/cat', 04:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:17 INFO - 'PWD': '/builds/slave/test', 04:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:17 INFO - 'SHELL': '/bin/bash', 04:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:17 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:17 INFO - 'USER': 'cltbld', 04:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:17 INFO - 'XPC_FLAGS': '0x0', 04:53:17 INFO - 'XPC_SERVICE_NAME': '0', 04:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zUXcnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-R_eGO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Bn8Lkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-f3baKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-mdvQkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-u8icwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-xoXor_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-JXrpDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-OvipZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0Xwjmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-id1DeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-eKhGX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lHuNwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6XH6zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lR1f1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-mc8jkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Hgrv1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:53:19 INFO - Running setup.py install for manifestparser 04:53:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:53:19 INFO - Running setup.py install for mozcrash 04:53:20 INFO - Running setup.py install for mozdebug 04:53:20 INFO - Running setup.py install for mozdevice 04:53:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Running setup.py install for mozfile 04:53:20 INFO - Running setup.py install for mozhttpd 04:53:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Running setup.py install for mozinfo 04:53:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Running setup.py install for mozInstall 04:53:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:53:20 INFO - Running setup.py install for mozleak 04:53:21 INFO - Running setup.py install for mozlog 04:53:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:53:21 INFO - Running setup.py install for moznetwork 04:53:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:53:21 INFO - Running setup.py install for mozprocess 04:53:21 INFO - Running setup.py install for mozprofile 04:53:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:53:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:53:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:53:21 INFO - Running setup.py install for mozrunner 04:53:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:53:21 INFO - Running setup.py install for mozscreenshot 04:53:22 INFO - Running setup.py install for moztest 04:53:22 INFO - Running setup.py install for mozversion 04:53:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:53:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:53:22 INFO - Cleaning up... 04:53:22 INFO - Return code: 0 04:53:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:22 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:53:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:22 INFO - 'HOME': '/Users/cltbld', 04:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:22 INFO - 'LOGNAME': 'cltbld', 04:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:22 INFO - 'MOZ_NO_REMOTE': '1', 04:53:22 INFO - 'NO_EM_RESTART': '1', 04:53:22 INFO - 'PAGER': '/bin/cat', 04:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:22 INFO - 'PWD': '/builds/slave/test', 04:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:22 INFO - 'SHELL': '/bin/bash', 04:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:22 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:22 INFO - 'USER': 'cltbld', 04:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:22 INFO - 'XPC_FLAGS': '0x0', 04:53:22 INFO - 'XPC_SERVICE_NAME': '0', 04:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-piUHes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-HdwtBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:22 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)) 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-IYUaAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6Cl1P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-UYTJ5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-_uwFh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-rjq7GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-OxHCGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-iSDAnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-nA_cUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-BOUjEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jEpk0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-yWvW3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5GcLLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:24 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)) 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-EBM9Gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-1Ndkg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-VYmdFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:53:24 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)) 04:53:24 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)) 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:53:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:53: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 04:53: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 04:53: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 04:53: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 04:53: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 04:53: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 04:53:26 INFO - Downloading blessings-1.6.tar.gz 04:53:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:53:27 INFO - Installing collected packages: blessings 04:53:27 INFO - Running setup.py install for blessings 04:53:27 INFO - Successfully installed blessings 04:53:27 INFO - Cleaning up... 04:53:27 INFO - Return code: 0 04:53:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:53:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:27 INFO - Reading from file tmpfile_stdout 04:53:27 INFO - Current package versions: 04:53:27 INFO - blessings == 1.6 04:53:27 INFO - blobuploader == 1.2.4 04:53:27 INFO - docopt == 0.6.1 04:53:27 INFO - functools32 == 3.2.3-2 04:53:27 INFO - jsonschema == 2.5.1 04:53:27 INFO - manifestparser == 1.1 04:53:27 INFO - mozInstall == 1.12 04:53:27 INFO - mozcrash == 1.0 04:53:27 INFO - mozdebug == 0.1 04:53:27 INFO - mozdevice == 0.48 04:53:27 INFO - mozfile == 1.2 04:53:27 INFO - mozhttpd == 0.7 04:53:27 INFO - mozinfo == 0.9 04:53:27 INFO - mozleak == 0.1 04:53:27 INFO - mozlog == 3.4 04:53:27 INFO - moznetwork == 0.27 04:53:27 INFO - mozprocess == 0.23 04:53:27 INFO - mozprofile == 0.28 04:53:27 INFO - mozrunner == 6.13 04:53:27 INFO - mozscreenshot == 0.1 04:53:27 INFO - mozsystemmonitor == 0.3 04:53:27 INFO - moztest == 0.7 04:53:27 INFO - mozversion == 1.4 04:53:27 INFO - psutil == 3.1.1 04:53:27 INFO - requests == 1.2.3 04:53:27 INFO - wsgiref == 0.1.2 04:53:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53: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')]} 04:53:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:28 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:53:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:53:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:28 INFO - 'HOME': '/Users/cltbld', 04:53:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:28 INFO - 'LOGNAME': 'cltbld', 04:53:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:28 INFO - 'MOZ_NO_REMOTE': '1', 04:53:28 INFO - 'NO_EM_RESTART': '1', 04:53:28 INFO - 'PAGER': '/bin/cat', 04:53:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:28 INFO - 'PWD': '/builds/slave/test', 04:53:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:28 INFO - 'SHELL': '/bin/bash', 04:53:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:28 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:28 INFO - 'USER': 'cltbld', 04:53:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:28 INFO - 'XPC_FLAGS': '0x0', 04:53:28 INFO - 'XPC_SERVICE_NAME': '0', 04:53:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:53:29 INFO - Cleaning up... 04:53:29 INFO - Return code: 0 04:53:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:29 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 0x10d0d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d04f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdaea49dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, '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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:53:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:53:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:53:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:29 INFO - 'HOME': '/Users/cltbld', 04:53:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:29 INFO - 'LOGNAME': 'cltbld', 04:53:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:29 INFO - 'MOZ_NO_REMOTE': '1', 04:53:29 INFO - 'NO_EM_RESTART': '1', 04:53:29 INFO - 'PAGER': '/bin/cat', 04:53:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:29 INFO - 'PWD': '/builds/slave/test', 04:53:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:29 INFO - 'SHELL': '/bin/bash', 04:53:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:53:29 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:53:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:29 INFO - 'USER': 'cltbld', 04:53:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:29 INFO - 'XPC_FLAGS': '0x0', 04:53:29 INFO - 'XPC_SERVICE_NAME': '0', 04:53:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:53:29 INFO - Cleaning up... 04:53:29 INFO - Return code: 0 04:53:29 INFO - Running post-action listener: _resource_record_post_action 04:53:29 INFO - Running post-action listener: _start_resource_monitoring 04:53:29 INFO - Starting resource monitoring. 04:53:29 INFO - [mozharness: 2016-11-09 12:53:29.828646Z] Finished create-virtualenv step (success) 04:53:29 INFO - [mozharness: 2016-11-09 12:53:29.829628Z] Running install step. 04:53:29 INFO - Running pre-action listener: _resource_record_pre_action 04:53:29 INFO - Running main action method: install 04:53:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:30 INFO - Reading from file tmpfile_stdout 04:53:30 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:30 INFO - Reading from file tmpfile_stdout 04:53:30 INFO - Output received: 04:53:30 INFO - Usage: mozinstall [options] installer 04:53:30 INFO - Options: 04:53:30 INFO - -h, --help show this help message and exit 04:53:30 INFO - -d DEST, --destination=DEST 04:53:30 INFO - Directory to install application into. [default: 04:53:30 INFO - "/builds/slave/test"] 04:53:30 INFO - --app=APP Application being installed. [default: firefox] 04:53:30 INFO - mkdir: /builds/slave/test/build/application 04:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:54:05 INFO - Reading from file tmpfile_stdout 04:54:05 INFO - Output received: 04:54:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:54:05 INFO - Running post-action listener: _resource_record_post_action 04:54:05 INFO - [mozharness: 2016-11-09 12:54:05.042767Z] Finished install step (success) 04:54:05 INFO - [mozharness: 2016-11-09 12:54:05.042945Z] Running run-tests step. 04:54:05 INFO - Running pre-action listener: _resource_record_pre_action 04:54:05 INFO - Running main action method: run_tests 04:54:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:54:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:54:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:54:05 INFO - 2016-11-09 04:54:05.058 screenresolution[1924:15078] starting screenresolution argv=screenresolution get 04:54:05 INFO - 2016-11-09 04:54:05.060 screenresolution[1924:15078] Display 0: 1600x1200x32@60 04:54:05 INFO - 2016-11-09 04:54:05.066 screenresolution[1925:15080] starting screenresolution argv=screenresolution list 04:54:05 INFO - Available Modes on Display 0 04:54:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:54:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:54:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:54:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:54:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:54:05 INFO - Intel Iris: 04:54:05 INFO - Chipset Model: Intel Iris 04:54:05 INFO - Type: GPU 04:54:05 INFO - Bus: Built-In 04:54:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:54:05 INFO - Vendor: Intel (0x8086) 04:54:05 INFO - Device ID: 0x0a2e 04:54:05 INFO - Revision ID: 0x0009 04:54:05 INFO - Displays: 04:54:05 INFO - Display: 04:54:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:54:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:54:05 INFO - Display Serial Number: 1600x1200 60 04:54:05 INFO - Main Display: Yes 04:54:05 INFO - Mirror: Off 04:54:05 INFO - Online: Yes 04:54:05 INFO - Rotation: Supported 04:54:05 INFO - Return code: 0 04:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:54:05 INFO - Python 2.7.3 04:54:05 INFO - Return code: 0 04:54:05 INFO - grabbing minidump binary from tooltool 04:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:05 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 0x10ceedd30>, '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 0x10cd13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cee5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:54:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:54:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:54:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:54:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:54:05 INFO - Return code: 0 04:54:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:54:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:54:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:54:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:54:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 04:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --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 04:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNOzKIlf5J/Render', 04:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:05 INFO - 'HOME': '/Users/cltbld', 04:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:05 INFO - 'LOGNAME': 'cltbld', 04:54:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:54:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:05 INFO - 'MOZ_NO_REMOTE': '1', 04:54:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:54:05 INFO - 'NO_EM_RESTART': '1', 04:54:05 INFO - 'PAGER': '/bin/cat', 04:54:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:05 INFO - 'PWD': '/builds/slave/test', 04:54:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:05 INFO - 'SHELL': '/bin/bash', 04:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rh0W8rQi0d/Listeners', 04:54:05 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 04:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:05 INFO - 'USER': 'cltbld', 04:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:05 INFO - 'XPC_FLAGS': '0x0', 04:54:05 INFO - 'XPC_SERVICE_NAME': '0', 04:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 04:54:05 INFO - mozversion application_buildid: 20161109022623 04:54:05 INFO - mozversion application_changeset: 533b6efe3528fcb022c857e84bc432b77b5507ef 04:54:05 INFO - mozversion application_display_name: Nightly 04:54:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:54:05 INFO - mozversion application_name: Firefox 04:54:05 INFO - mozversion application_remotingname: firefox 04:54:05 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 04:54:05 INFO - mozversion application_vendor: Mozilla 04:54:05 INFO - mozversion application_version: 52.0a1 04:54:05 INFO - mozversion platform_buildid: 20161109022623 04:54:05 INFO - mozversion platform_changeset: 533b6efe3528fcb022c857e84bc432b77b5507ef 04:54:05 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 04:54:05 INFO - mozversion platform_version: 52.0a1 04:54:05 INFO - using testdate: 1478696045 04:54:05 INFO - actual date: 1478696045 04:54:05 INFO - starting webserver on 'localhost:51613' 04:54:05 INFO - SUITE-START | Running 2 tests 04:54:05 INFO - TEST-START | dromaeo_css 04:54:05 INFO - operating with platform_type : mac_ 04:54:05 INFO - Initialising browser for dromaeo_css test... 04:54:05 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpA30Yrh/profile http://localhost:51613/getInfo.html) 04:54:08 INFO - PROCESS | 1935 | 2016-11-09 04:54:08.425 plugin-container[1937:15237] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c57, name = 'com.apple.tsm.portname' 04:54:08 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:08 INFO - PROCESS | 1935 | 2016-11-09 04:54:08.427 plugin-container[1937:15237] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 04:54:08 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:08 INFO - PROCESS | 1935 | 2016-11-09 04:54:08.427 plugin-container[1937:15237] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:54:08 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 04:54:08 INFO - PROCESS | 1935 | colorDepth:24 04:54:08 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 04:54:08 INFO - PROCESS | 1935 | __metrics 04:54:08 ERROR - PROCESS | 1935 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 04:54:09 INFO - TEST-INFO | 1935: exit 0 04:54:09 INFO - Browser initialized. 04:54:09 INFO - Running cycle 1/1 for dromaeo_css test... 04:54:09 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpA30Yrh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:11 INFO - PROCESS | 1938 | 2016-11-09 04:54:11.515 plugin-container[1939:15353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872f, name = 'com.apple.tsm.portname' 04:54:11 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:11 INFO - PROCESS | 1938 | 2016-11-09 04:54:11.516 plugin-container[1939:15353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 04:54:11 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:11 INFO - PROCESS | 1938 | 2016-11-09 04:54:11.516 plugin-container[1939:15353] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:54:21 INFO - PROCESS | 1938 | RSS: Main: 238592000 04:54:21 INFO - PROCESS | 1938 | 04:55:10 INFO - PROCESS | 1938 | 1478696110863 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:57:23 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51613/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51613/tests/dromaeo/cssquery-ext.html) 04:57:26 INFO - PROCESS | 1938 | RSS: Main: 253440000 04:57:26 INFO - PROCESS | 1938 | 05:00:28 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51613/tests/dromaeo/cssquery-ext.html (next: http://localhost:51613/tests/dromaeo/cssquery-jquery.html) 05:00:29 INFO - PROCESS | 1938 | RSS: Main: 262369280 05:00:29 INFO - PROCESS | 1938 | 05:03:31 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51613/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51613/tests/dromaeo/cssquery-mootools.html) 05:03:32 INFO - PROCESS | 1938 | RSS: Main: 266776576 05:03:32 INFO - PROCESS | 1938 | 05:06:33 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51613/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51613/tests/dromaeo/cssquery-prototype.html) 05:06:34 INFO - PROCESS | 1938 | RSS: Main: 265416704 05:06:34 INFO - PROCESS | 1938 | 05:09:37 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51613/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51613/tests/dromaeo/cssquery-yui.html) 05:09:38 INFO - PROCESS | 1938 | RSS: Main: 270213120 05:09:38 INFO - PROCESS | 1938 | 05:12:40 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51613/tests/dromaeo/cssquery-yui.html (next: http://localhost:51613/tests/dromaeo/cssquery-dojo.html) 05:12:41 INFO - PROCESS | 1938 | RSS: Main: 272371712 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | __start_tp_report 05:12:41 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 05:12:41 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 05:12:41 INFO - PROCESS | 1938 | |i|pagename|runs| 05:12:41 INFO - PROCESS | 1938 | |0;dojo.html;3382.5771778527683;3384.9323013539724;3423.1442139465134;3427.417339052361;3439.071450708309;5007.524285192901;5538.9169162462595;5739.913901291484;5747.453991870771;5768.913466298004;5663.971680141603;5714.257146570951;5779.757352169283;5808.041673123929;5815.127730840376;4820.842997680549;5342.946570534294;5364.839054828361;5401.135818269077;5448.318960129984;4193.329067349225;5326.467353264662;5392.703401312926;5420.620556561043;5433.049216387136;4568.588827005568;5395.946040539584;5412.810551630694;5442.619016668835;5452.127659574468;4522.7964741586375;5211.869703257411;5230.476952304777;5276;5326.520613144797;5214.895702085937;5221.190715439075;5253.86865328366;5299.205119232146;5351.25082488452;4668.953310466886;5534.1975413565215;5871.060630299132;5888.352281249059;5892.911606325909;6168.660723660197;6180.2892667343385;6243.719032643542;6275.278342990523;6291.591046581958;5154.948450515484;6117.877642447171;6258.030005349178;6260.593061451037;6281.340459251758;5288.81489147878;5622.297212848394;5900.1739756434135;5924.992751232301;5928.7628494860155;6166.352532984062;6231.532635052389;6362.745490180341;6371.776987805497;6401.55189136756;6141.117811339996;6161.969190154021;6186;6216.129741836146;6262.968685156637;6262.718177682015;6283.622982621057;6289.528285378615;6297.023961286008;6304.558680892385;6112.503480924558;6152.046432802923;6164.291106522807;6253.249610046823;6287.402696743802;6200.224971878515;6228.4705800007505;6274.9686251569365;6276;6292.937070629327;4957.801687932442;4986.301917731521;5012.423571289275;5029.899402011939;5046.697198168122;4422.115576884636;4456.153330867125;4480.61914737251;4506.233940230103;4516.9774151129695;5783.306003279633;5902.645841249453;5938.376470470538;6009;6043.637381757108;6243.504676632817;6276.46650034743;6293.181886354744;6317.96841015792;6318.368163183737;5894.145348924382;5942.70286485674;5971.98476259037;5974.4622983930785;5990.28116625999;5355.30381050461;5355.812546560852;5424.05079111154;5440.809571664972;5457.126859702508;6299.622022678654;6361.173047503888;6378.043293506012;6394.424501794861;6482.935170648233;4724.574788269072;5157.716325602128;5280.917411930622;6299.905501417391;6323.841903952438;6457.515686323451;6512.902306465501;6520.347965203442;6524.184476940382;6540.444062254572;6123.84690382744;6143.296949342275;6146.754129834757;6174.104754810617;6177.227846519185;6110.419510146529;6141.2098563120835;6229.12792209082;6281.214848143982;6285.340039295808;6084.14821924922;6171.814845554641;6224.782132625336;6234.470070044097;6258.937410625836;6077.6961151943115;6141.170941922784;6195.101710251898;6238.282597501164;6245.437910588069;5097.776533631976;5951.55363347742;6024.518038556993;6075.817725468066;6096.420840020191;5011.523905228997;6132.969335153474;6141.240538582634;6181.660008699565;6240.095186198067;6065.939340606537;6070.757169713162;6077.726502307319;6105.603135796159;6138.447539721446 05:12:41 INFO - PROCESS | 1938 | |1;ext.html;445.1897546465433;449.18472971556645;506.6276286929109;507.74358948730884;516.9121249387605;29574.112776616683;31212.375752484964;37869.81065094674;39374.0156496087;39544.80227598865;39558.802205988926;39881.601183988154;40012.79993600028;40026.7998660007;40049.59950400495;9047.63809447623;9222.769430764218;9236.907630923706;9255.490947997858;9265.72202833916;9155.992840787541;9189.908100918972;9216.677416290431;9218.953905230464;9226.892772867266;7711.807204819868;9113.3165012624;9195.816083678323;9226.169644731974;9261.30540209487;9144.45133291998;9167.991520932692;9213.67752128679;9223.723288301362;9227.26181905446;8967.641294348217;9038.231750301233;9042.23141033013;9055.637774489012;9057.954710226406;37401.251974960345;37782.24435511274;37922;37995.62004379976;38303.23393532142;3419.4870769384543;3431.5367425397503;3465.0297916583145;3783.4892289540835;3791.526059242595;28392.574111388345;29453.26366840846;29587.40825183506;29745.702542974508;30301.393972120433;2935.8238505689515;3114.3304189599116;3155.148110010284;3187.6652951439996;3251.8048917065053;365713.1714341411;459075;461229;462738;462871;51513.484865151615;53843.730781346625;54126.45873541214;54421;54453.72773136109;48494.515054849;53399;56540.43459565352;56612;56701;14899.329530171168;14929.40282388714;15054.849451505565;15169.772453412987;15176.544703658908;11759.353235571954;11802.88197118035;11852.644420667393;11895.16733828641;11919.702007449883;14019.719605608036;14482.710345793024;14497;14568.053076550203;14691.11853288806;3127;3257.8371081446307;3261.4618587932723;5936.970315148396;5955.52355811534;863.9654413823376;866.900306464752;887.8002449448823;969.8351280282369;1041.598984391015;28903.7109628901;29103;29289.414211715648;29407.85296073506;29646.40707185844;1010.9140723038624;1033.2199189611795;1039.9688009359731;1092.0444610965405;1104.536094840169;12863.742725145632;13034;13523.188608683511;14721.337539810735;15079.472218472301;656722;659730;662119;666853;667761.6611917006;54179.91640167175;54711;54764.45235547673;54847;54868.72565637146;1302.7459645369252;1327.0511584217331;1331.5206525650758;1347.1243691600341;1347.9595612131652;27819.582706259018;28871.85564072166;28903;29245.561316579842;29432.411351773273;46935.765321173174;46998;47124;47173.528264716915;47376.7631161842;593050;595581;596668;598190;608156;25184.49631007406;25470.853811578152;25513.489730205663;25531.106411276258;25537.361565961;43484.130317394105;44109.77945110254;44262.89342766457;44512.33231501465;46909.2963605553;28244.43511129807;29204.853975729984;32190.03429897107;37987.620123798406;38140.618593813706;32522.512162318057;36459;36678;37410.43884341791;37722.868313950625;28364.85817570899;30142.69857301399;30398.93603723859;36136.81931590325;37178.628213717515;30510.627021783628;31761.523577145897;32908.67091329056;32978.34043319072;33135.00594982154;24283.635745463478;24434.14480493174;24446.877765611058;24962.251132466055;25412.491750164525 05:12:41 INFO - PROCESS | 1938 | |2;jquery.html;339.8691503771048;357.9534660494135;363.93994990826513;372.8769506062996;395.5312954149333;15786.921065394685;17919.193636286367;18557.350492732756;19591.510212244684;19839;11425.371604561746;14840.851591484083;18679.066046697677;19652.9017354913;19664;5141;5364.91952620711;5366.4633536646215;5381.112116500773;5387.164989426626;5239.240310155044;5257.815976440826;5365.114756065244;5765.827025189251;5934.940650593504;5857.6778277194735;5863.58954873159;5863.882722345551;5866.9706651466895;5868.09044598088;5692.003899317603;5810.680412577285;5841.64950102993;5849.590528662995;5903.0555111182;5183.067047931371;5190.339091490724;5191.506806853343;5193.766280517386;5212.921806172911;12509.186902851285;16847.91576042124;23498.882505587535;24413.145539906018;24648.50702985948;2063.319104695447;2088.1542975095113;2091.759447663523;2179.8474106812528;2651.7878569714376;10094.747631309203;10383.688489345332;12815.615531533977;13714.17714937097;15162.090274583561;1584.318742940535;1664.4143248936753;1704.9914750426292;1734.6877562038828;1774.8047714751367;81741.18258817395;125864;126935.3653231728;127078;129734.35132824464;11007.559697612165;11238.831417528743;11531.942340288246;11559.190856639954;11744.94127529374;14377.712445751027;14391.136531808124;14405.85594144066;14437.927810360881;14466.27668616565;7952.681892724342;8251.561774355445;8314.210150035737;8409.95795021021;8426.368022398345;7068.187158476841;8031.196880312039;8093.676252949816;8113.432059755761;8276.875846862302;4371.713366297721;4512.52618475062;4533.274676051882;4607;4613.630909527297;1619.809440061554;1684.2252563820534;1868.4581471373149;1934.8742331748392;1941.0779879557097;862.9525376104375;888.1118881118881;888.8800011998297;907.8002839375326;918.3479729392207;16564.420245291356;17775.489083428052;18589.814101858807;19349.4195174145;20951.266705665228;825.859603867344;910.6949172027445;939.8120375924842;977.3256453047375;1006.9496525173859;9858.30991830565;11450.370229637283;11477.885221147682;11699.590514331958;11788.941055294668;569806;578558;580926.0953695205;594352.1694349176;609845;123094.38452807858;123334;129030;134936.32531837473;139391;872.075599864146;916.8395530782086;981.7153025622489;1031.7265924530157;1049.338916482611;19122;19165.425037248904;19219.32732354361;19294.90352548228;19308.8069119307;43016;52983;54078;54815;54852.72573637106;49368.506314937826;54493;55685.44314556965;67630.64738705297;79843.60078199572;7568.243175682388;7778.649960751666;7936.127026027017;8022.197780221931;8153.551554664313;9025.819483610423;12091.53911352638;12202.938985305016;12288.877111228774;12680.873191267969;8966.68616598416;10334.069933705701;10512.684619461428;10887.401192934465;10988.780224395627;10386.636467723594;10408.947955260175;10543.472826358804;10952.28810127371;11177.944110279397;10441;10462.738431539272;10631.043206111488;11129.054030407506;11253.606123785628;7894.368450524059;8068.798280043045;8111.634976426193;8250.587470626564;8277.213664702085;9894.208463322773;10073.748156296151;10204;10430.37417754937;10597.788044238918 05:12:41 INFO - PROCESS | 1938 | |3;mootools.html;1456.1336005076982;1498.8725958293546;1665.4254282272611;1752.8334808193233;1813.4559632110372;7600.5439673619485;7919.604019799016;8169.87745183822;8639.39524233304;8664.220220180183;8067.636956336964;8377.95811020944;8389.580520973943;8405.075441701423;8479.448835825668;2279.8290128240365;2322.9883850580723;2328.5459335429596;2340.3447034830274;2344.202970989863;2322.8722420266877;2353.6940197774265;2372.146027430124;2385.272491889973;2389.438481956739;2306.6194077977198;2317.0500094960985;2331.5803155432013;2332.7783860533223;2336.696229490164;2289.919852805152;2294.4263934016494;2323.93028209154;2324.360800779799;2329.697139371879;2343.425860664131;2346.16711067572;2353.6116540770754;2359.1624973134635;2364.1370899621515;8439.324854011726;8505.447145935555;8523.701670441505;8527.530985795778;8535.82928341436;4929.580985616227;5116.462771408986;5279.498447647467;5292.391374991848;5315.813946511892;3340.5824271966003;3450.7929524228375;3579.1410061585293;3589.4974703541525;3593.4070878305047;1023.3655133817081;1032.1123833503182;1077.9892201077967;1095.906847917928;1097.0510508410168;10324.535395907202;10792.974667406585;10918;11021.614243501439;11049.17131215162;3628.401313783196;4441.178381999341;4683.578477937002;4713.269443236304;4716.716996980193;9512.95243523778;9535.52322383878;9564.56959436827;9580.0898914603;9593.040695930491;5232.73836308183;5245.842624721265;5263.210518422267;5263.23683065961;5298.788048478018;4317.784110794448;4345.804438800262;4689.835855745033;4732.503087175865;4757.785899634525;3752.4558938954247;3849.2493963677375;3856.7107466940092;3907.257621051991;3937.1338305572726;1244.4151248912997;1262.9873701263054;1266.2529107826242;1276.1258537901567;1281.5130250504749;793.714262865368;869.4913475616709;882.7793051737066;909.2453263791037;912.8767616371838;5550;5575.080111781511;5698.0598201296325;5770.336411312669;6002.249718785152;408.5975314315397;418.9015581338383;433.71158179810703;435.7211384713786;436.6506794564336;3787.2614840105916;3829.7319187657154;3859.6912247020173;3973.4834471518516;3987.2424239394422;8466.957665211634;8968.654701794652;10113.797724045478;10281.794364112826;10326.48367581633;10174.186065114773;10180.440075795754;10197.388156710473;10309.123724483354;10401.479926003669;7452.627368631655;7742.806429839299;7830.8433831323055;7891.329237014918;7963.1638677939145;4825.203841366135;4966.577840883565;5560.082586373203;5762.683052432157;5920.911186332122;8062.435629505878;9346.878374595091;9431.434113953184;9446.811063778548;9463.810723785347;7004.334588214111;7618.69525218985;9489.335746497678;9528.094830991045;9530.904690953279;6179.660118693515;7083.291670832876;7689.769306920802;7749.92250077492;7938.722144724907;7883.5664038478435;7938.126806051445;8170.42807003504;8181.222783835526;8202.712905048294;7170;7186.245444228281;7278.235785242473;7294.307040831112;7415.443841711785;6990.545614535039;7072.222055573986;7091.468139889425;7196.0645116134565;7204.675789589586;6568.868622627616;6831.4876384272375;6845.520813543203;6857.965710171617;6862.451003919774;4837.419509658864;4847.61219102478;4935.087008903364;5043.52086551777;5047.570956468741;4920.852374428773;4980.203167493184;5029.195328747529;5102.744862756922;5141 05:12:41 INFO - PROCESS | 1938 | |4;prototype.html;276.51195639695936;294.21738176450646;305.197331019419;306.1428001595532;308.48175065889296;4819.734914579705;5105.502730629512;5248.239005344223;5280.9207861882105;5356.143017117257;5068;5075.213341932006;5263.184206448007;5287.154055351145;5309.044372013036;3433.759636825423;3626.4197728363465;3829.5978922213253;3869.6323849234277;4084.530279017921;3639.472276519903;3879.864204752834;3916.2754890345377;4089.20260549193;4114.8971275718195;3284.3102948380747;4039.454673619053;4039.6970227233073;4045.352743561031;4098.446709694183;3346.0798280472823;3820.2932457495313;3921.745086569364;4023.939640905389;4054.3107671695884;2941.9556057599457;3052.771042171824;3526.272126657919;3534.770239934396;3562.305350456662;3736.8692095776787;4684.976575117136;4690.906181876378;4892.437369702494;5174.171113376075;4587.380703605004;4626.83806066786;4640.60554852838;4656.511066338019;4657.254839225708;4516.735314112054;4896.2410826321975;5377.623566350357;5416.283808077995;5607.355154157283;3218.806871587712;3372.8482218300237;3607.6753092221825;3686.944695829565;3707.29561383339;5374.381946076212;5815.15680226369;5844.603712477864;5853.073463268366;5855.795047173328;3421.863125474953;3922.3920292354733;3925.21495700861;3931.312020396419;3945.64489195968;3936.8177225794984;3997.400389941532;4025.094353770437;4026.939595906064;4050.736702114353;4490.236659767847;4525.456945166601;4576.931346029813;4601.67788254819;4627.9074418511445;4859.538343857328;4905.705657660552;5573.27547418833;5618;5619.466150715675;1153.5097583526967;1167.7839599674087;1236.5038303652682;1270;1293.8188653588504;719.7876626395226;768.7808974442257;876.5091548732603;879.8693678622939;904.7333732774168;4551.429756733922;4591.885202869888;4931.975340123348;4943.901121977612;4991.126552853236;4298.849540266076;5103.795848166032;5350.384705758887;5437.918431223535;5536.833894983157;4602.401687780634;5153.304303918923;5286.3392075990505;5299.284596579412;5324.866878328073;2345.612973859328;2440.219129878422;2622.803289753276;2895.4353900989104;2901.2746813296676;4741.566635196103;4808.663393562487;5084.923726144037;5716.884752641466;5959.4934430573885;5273.7829923746485;5532.640378375393;5949.434803693643;5964.55640547786;5974.761009559569;5237.6071794615555;6019.217501724747;6057.697115144313;6060.969695151496;6116.755329786848;5586.441355864381;5785.008344242459;5805.680687562056;5807.970960145172;5808.73860676279;5048.621353398583;5245.973770131278;5777.508911742549;5778.537716982547;5786.990764802364;5696.54427645779;6050.485708714808;6053.153788094497;6070.18052562916;6071.51427885777;3115.5482455044344;3837.232553489369;3860.6525412711867;3881.9223615528094;3883.2815929053218;5789.710514474344;5800.301755577995;5806.893312078018;5828.708564571839;5871.442212989759;4899.04468628614;5270.288511050959;5423.020597481969;5627.8874422512135;5656.575756818337;4935.679180853233;4992.900141997213;5104.162501499532;5638.887222255614;5644.435556444323;4431.800568974469;5163.27714120031;5275.314209152939;5522.2544956431975;5542.171083542341;4649;4865.70805751656;5301.655392399482;5468.960897429476;5583.804566840304;5242.423333433396;5470.495613706263;5497.021072414008;5512.3385193777;5530.336359636869 05:12:41 INFO - PROCESS | 1938 | |5;yui.html;686.5571706249468;706.6254884910999;709.6877373955463;755.2824816424395;799.3684988858804;12288.877111228896;13173.604791856236;13241.404136813842;13250.668733281673;13279.136856104347;12041.458134383953;12430.689232769186;12515;12534.623961281177;12544.686382840433;788.5308241596256;805.2832978649008;807.096052421289;809.2635701511625;809.2918696140877;648.4099469482771;667.9866402671944;793.4326956226297;805.2470939671397;806.822499050208;858.7638399440173;862.5213006781233;873.3973558244822;874.1389731114847;876.0494863073573;866.9523176225305;867.3841572483544;867.4665080975186;867.5098569308352;869.4261787220404;625.2902955145896;632.7816903168399;641.9069234960951;641.9760482031153;642.9648266291267;12493.500259989589;12771.61685149447;12822.487100515968;12864.807027894589;12965.87034129656;11982.70043248917;12012.63962081139;12094.697632559168;12178.51285948561;12199.268043917305;9479.099485548932;10072.546735396925;10314.535845886952;10690.51892664832;11195.944020279929;3032.9090127296217;3223.564818749462;3285.0801775502896;3370.0563842124243;3386.779859309137;90342;91206.54396727974;91607;91923.54038229834;93588;9185.586648600829;9216.484380442447;9252.90747092534;9336.813263734823;9454.101860323259;16436.424725134562;17056.914715426345;18628;18682.532936676693;18808.81191188098;5694.772209111589;6328.430441260309;6698.095757072733;6704.631245281556;6917.89623155653;3226.2257058305836;3738.140227747633;3781.1681430085337;3895.552011518655;3929.9214015719526;2384;2586.5473542130053;3032.9536309973464;3060.2808340040074;3070.4473194825146;587.7883961773758;599.1103211730576;602.728772052578;603.5624172475008;613.0344707086357;591.763294682122;634.8000379880323;645.8708258348349;648.4131860666105;659.6371995402509;7752.689892404241;8222.093441509161;8271.867999781509;8522.74431767048;8670.47977121362;607.1954660075418;626.1578177351455;633.1990032608753;651.6481100205835;652.3085529338821;4107.219628270619;4133.276676581646;4165.729227600196;4223.746575205498;4233.915321693549;49724.751376242886;110748;112218.43890780494;113239.43380283046;114320;76653;95578.52210738901;95977;96825;98111;88729;88731.11268887228;89923.55038224767;90102.64846027315;91746;7752.92247077522;7846.646900889359;7851.685932562862;8305.95847020761;8999.370044097112;40363.79818101008;46220.76889615531;49027;51417.742911285204;52589.7370513145;50137.74655633638;51016;67649.29402823832;74599;77338.2266177331;9090.909090909006;11644.068474521848;11716.882831171579;11759.353235572125;12029.398530073286;12598.685032874251;12839.871601283867;12936.547220847602;12946.611601651588;13101.73796524045;10152.695419137437;10336.276460647683;10348.379097253888;10556.366618002945;10664.84002739975;11573.479193436147;11925.045996320447;11944.104192185447;11972.281663099893;12205.267683938991;9982.850257246002;12132.514699411924;12211.938940305241;12607.369631518204;12662.176958497668;10236.618056562269;10281.485925703835;10359.96400359958;10610.734731631463;10619.681409557727;8979.775505612412;9918.801623967625;10000.799984000425;10218.744531386776;10436.686899393031 05:12:41 INFO - PROCESS | 1938 | __end_tp_report 05:12:41 INFO - PROCESS | 1938 | __start_cc_report 05:12:41 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,368 05:12:41 INFO - PROCESS | 1938 | __end_cc_report 05:12:41 INFO - PROCESS | 1938 | __startTimestamp1478697161243__endTimestamp 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | ------- Summary: start ------- 05:12:41 INFO - PROCESS | 1938 | Number of tests: 6 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5749.11 Median:6043.64 stddev:676.87 (11.2%) stddev-sans-first:652.99 05:12:41 INFO - PROCESS | 1938 | Values: 3382.6 3384.9 3423.1 3427.4 3439.1 5007.5 5538.9 5739.9 5747.5 5768.9 5664.0 5714.3 5779.8 5808.0 5815.1 4820.8 5342.9 5364.8 5401.1 5448.3 4193.3 5326.5 5392.7 5420.6 5433.0 4568.6 5395.9 5412.8 5442.6 5452.1 4522.8 5211.9 5230.5 5276.0 5326.5 5214.9 5221.2 5253.9 5299.2 5351.3 4669.0 5534.2 5871.1 5888.4 5892.9 6168.7 6180.3 6243.7 6275.3 6291.6 5154.9 6117.9 6258.0 6260.6 6281.3 5288.8 5622.3 5900.2 5925.0 5928.8 6166.4 6231.5 6362.7 6371.8 6401.6 6141.1 6162.0 6186.0 6216.1 6263.0 6262.7 6283.6 6289.5 6297.0 6304.6 6112.5 6152.0 6164.3 6253.2 6287.4 6200.2 6228.5 6275.0 6276.0 6292.9 4957.8 4986.3 5012.4 5029.9 5046.7 4422.1 4456.2 4480.6 4506.2 4517.0 5783.3 5902.6 5938.4 6009.0 6043.6 6243.5 6276.5 6293.2 6318.0 6318.4 5894.1 5942.7 5972.0 5974.5 5990.3 5355.3 5355.8 5424.1 5440.8 5457.1 6299.6 6361.2 6378.0 6394.4 6482.9 4724.6 5157.7 5280.9 6299.9 6323.8 6457.5 6512.9 6520.3 6524.2 6540.4 6123.8 6143.3 6146.8 6174.1 6177.2 6110.4 6141.2 6229.1 6281.2 6285.3 6084.1 6171.8 6224.8 6234.5 6258.9 6077.7 6141.2 6195.1 6238.3 6245.4 5097.8 5951.6 6024.5 6075.8 6096.4 5011.5 6133.0 6141.2 6181.7 6240.1 6065.9 6070.8 6077.7 6105.6 6138.4 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:68288.76 Median:20826.65 stddev:154387.55 (741.3%) stddev-sans-first:154736.62 05:12:41 INFO - PROCESS | 1938 | Values: 445.2 449.2 506.6 507.7 516.9 29574.1 31212.4 37869.8 39374.0 39544.8 39558.8 39881.6 40012.8 40026.8 40049.6 9047.6 9222.8 9236.9 9255.5 9265.7 9156.0 9189.9 9216.7 9219.0 9226.9 7711.8 9113.3 9195.8 9226.2 9261.3 9144.5 9168.0 9213.7 9223.7 9227.3 8967.6 9038.2 9042.2 9055.6 9058.0 37401.3 37782.2 37922.0 37995.6 38303.2 3419.5 3431.5 3465.0 3783.5 3791.5 28392.6 29453.3 29587.4 29745.7 30301.4 2935.8 3114.3 3155.1 3187.7 3251.8 365713.2 459075.0 461229.0 462738.0 462871.0 51513.5 53843.7 54126.5 54421.0 54453.7 48494.5 53399.0 56540.4 56612.0 56701.0 14899.3 14929.4 15054.8 15169.8 15176.5 11759.4 11802.9 11852.6 11895.2 11919.7 14019.7 14482.7 14497.0 14568.1 14691.1 3127.0 3257.8 3261.5 5937.0 5955.5 864.0 866.9 887.8 969.8 1041.6 28903.7 29103.0 29289.4 29407.9 29646.4 1010.9 1033.2 1040.0 1092.0 1104.5 12863.7 13034.0 13523.2 14721.3 15079.5 656722.0 659730.0 662119.0 666853.0 667761.7 54179.9 54711.0 54764.5 54847.0 54868.7 1302.7 1327.1 1331.5 1347.1 1348.0 27819.6 28871.9 28903.0 29245.6 29432.4 46935.8 46998.0 47124.0 47173.5 47376.8 593050.0 595581.0 596668.0 598190.0 608156.0 25184.5 25470.9 25513.5 25531.1 25537.4 43484.1 44109.8 44262.9 44512.3 46909.3 28244.4 29204.9 32190.0 37987.6 38140.6 32522.5 36459.0 36678.0 37410.4 37722.9 28364.9 30142.7 30398.9 36136.8 37178.6 30510.6 31761.5 32908.7 32978.3 33135.0 24283.6 24434.1 24446.9 24962.3 25412.5 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:33841.14 Median:24530.83 stddev:98094.91 (399.9%) stddev-sans-first:98337.84 05:12:41 INFO - PROCESS | 1938 | Values: 339.9 358.0 363.9 372.9 395.5 15786.9 17919.2 18557.4 19591.5 19839.0 11425.4 14840.9 18679.1 19652.9 19664.0 5141.0 5364.9 5366.5 5381.1 5387.2 5239.2 5257.8 5365.1 5765.8 5934.9 5857.7 5863.6 5863.9 5867.0 5868.1 5692.0 5810.7 5841.6 5849.6 5903.1 5183.1 5190.3 5191.5 5193.8 5212.9 12509.2 16847.9 23498.9 24413.1 24648.5 2063.3 2088.2 2091.8 2179.8 2651.8 10094.7 10383.7 12815.6 13714.2 15162.1 1584.3 1664.4 1705.0 1734.7 1774.8 81741.2 125864.0 126935.4 127078.0 129734.4 11007.6 11238.8 11531.9 11559.2 11744.9 14377.7 14391.1 14405.9 14437.9 14466.3 7952.7 8251.6 8314.2 8410.0 8426.4 7068.2 8031.2 8093.7 8113.4 8276.9 4371.7 4512.5 4533.3 4607.0 4613.6 1619.8 1684.2 1868.5 1934.9 1941.1 863.0 888.1 888.9 907.8 918.3 16564.4 17775.5 18589.8 19349.4 20951.3 825.9 910.7 939.8 977.3 1006.9 9858.3 11450.4 11477.9 11699.6 11788.9 569806.0 578558.0 580926.1 594352.2 609845.0 123094.4 123334.0 129030.0 134936.3 139391.0 872.1 916.8 981.7 1031.7 1049.3 19122.0 19165.4 19219.3 19294.9 19308.8 43016.0 52983.0 54078.0 54815.0 54852.7 49368.5 54493.0 55685.4 67630.6 79843.6 7568.2 7778.6 7936.1 8022.2 8153.6 9025.8 12091.5 12202.9 12288.9 12680.9 8966.7 10334.1 10512.7 10887.4 10988.8 10386.6 10408.9 10543.5 10952.3 11177.9 10441.0 10462.7 10631.0 11129.1 11253.6 7894.4 8068.8 8111.6 8250.6 8277.2 9894.2 10073.7 10204.0 10430.4 10597.8 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5450.32 Median:5004.70 stddev:2987.45 (59.7%) stddev-sans-first:2980.75 05:12:41 INFO - PROCESS | 1938 | Values: 1456.1 1498.9 1665.4 1752.8 1813.5 7600.5 7919.6 8169.9 8639.4 8664.2 8067.6 8378.0 8389.6 8405.1 8479.4 2279.8 2323.0 2328.5 2340.3 2344.2 2322.9 2353.7 2372.1 2385.3 2389.4 2306.6 2317.1 2331.6 2332.8 2336.7 2289.9 2294.4 2323.9 2324.4 2329.7 2343.4 2346.2 2353.6 2359.2 2364.1 8439.3 8505.4 8523.7 8527.5 8535.8 4929.6 5116.5 5279.5 5292.4 5315.8 3340.6 3450.8 3579.1 3589.5 3593.4 1023.4 1032.1 1078.0 1095.9 1097.1 10324.5 10793.0 10918.0 11021.6 11049.2 3628.4 4441.2 4683.6 4713.3 4716.7 9513.0 9535.5 9564.6 9580.1 9593.0 5232.7 5245.8 5263.2 5263.2 5298.8 4317.8 4345.8 4689.8 4732.5 4757.8 3752.5 3849.2 3856.7 3907.3 3937.1 1244.4 1263.0 1266.3 1276.1 1281.5 793.7 869.5 882.8 909.2 912.9 5550.0 5575.1 5698.1 5770.3 6002.2 408.6 418.9 433.7 435.7 436.7 3787.3 3829.7 3859.7 3973.5 3987.2 8467.0 8968.7 10113.8 10281.8 10326.5 10174.2 10180.4 10197.4 10309.1 10401.5 7452.6 7742.8 7830.8 7891.3 7963.2 4825.2 4966.6 5560.1 5762.7 5920.9 8062.4 9346.9 9431.4 9446.8 9463.8 7004.3 7618.7 9489.3 9528.1 9530.9 6179.7 7083.3 7689.8 7749.9 7938.7 7883.6 7938.1 8170.4 8181.2 8202.7 7170.0 7186.2 7278.2 7294.3 7415.4 6990.5 7072.2 7091.5 7196.1 7204.7 6568.9 6831.5 6845.5 6858.0 6862.5 4837.4 4847.6 4935.1 5043.5 5047.6 4920.9 4980.2 5029.2 5102.7 5141.0 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4465.77 Median:4939.79 stddev:1412.96 (28.6%) stddev-sans-first:1381.50 05:12:41 INFO - PROCESS | 1938 | Values: 276.5 294.2 305.2 306.1 308.5 4819.7 5105.5 5248.2 5280.9 5356.1 5068.0 5075.2 5263.2 5287.2 5309.0 3433.8 3626.4 3829.6 3869.6 4084.5 3639.5 3879.9 3916.3 4089.2 4114.9 3284.3 4039.5 4039.7 4045.4 4098.4 3346.1 3820.3 3921.7 4023.9 4054.3 2942.0 3052.8 3526.3 3534.8 3562.3 3736.9 4685.0 4690.9 4892.4 5174.2 4587.4 4626.8 4640.6 4656.5 4657.3 4516.7 4896.2 5377.6 5416.3 5607.4 3218.8 3372.8 3607.7 3686.9 3707.3 5374.4 5815.2 5844.6 5853.1 5855.8 3421.9 3922.4 3925.2 3931.3 3945.6 3936.8 3997.4 4025.1 4026.9 4050.7 4490.2 4525.5 4576.9 4601.7 4627.9 4859.5 4905.7 5573.3 5618.0 5619.5 1153.5 1167.8 1236.5 1270.0 1293.8 719.8 768.8 876.5 879.9 904.7 4551.4 4591.9 4932.0 4943.9 4991.1 4298.8 5103.8 5350.4 5437.9 5536.8 4602.4 5153.3 5286.3 5299.3 5324.9 2345.6 2440.2 2622.8 2895.4 2901.3 4741.6 4808.7 5084.9 5716.9 5959.5 5273.8 5532.6 5949.4 5964.6 5974.8 5237.6 6019.2 6057.7 6061.0 6116.8 5586.4 5785.0 5805.7 5808.0 5808.7 5048.6 5246.0 5777.5 5778.5 5787.0 5696.5 6050.5 6053.2 6070.2 6071.5 3115.5 3837.2 3860.7 3881.9 3883.3 5789.7 5800.3 5806.9 5828.7 5871.4 4899.0 5270.3 5423.0 5627.9 5656.6 4935.7 4992.9 5104.2 5638.9 5644.4 4431.8 5163.3 5275.3 5522.3 5542.2 4649.0 4865.7 5301.7 5469.0 5583.8 5242.4 5470.5 5497.0 5512.3 5530.3 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:19343.26 Median:52003.74 stddev:29497.55 (56.7%) stddev-sans-first:29547.03 05:12:41 INFO - PROCESS | 1938 | Values: 686.6 706.6 709.7 755.3 799.4 12288.9 13173.6 13241.4 13250.7 13279.1 12041.5 12430.7 12515.0 12534.6 12544.7 788.5 805.3 807.1 809.3 809.3 648.4 668.0 793.4 805.2 806.8 858.8 862.5 873.4 874.1 876.0 867.0 867.4 867.5 867.5 869.4 625.3 632.8 641.9 642.0 643.0 12493.5 12771.6 12822.5 12864.8 12965.9 11982.7 12012.6 12094.7 12178.5 12199.3 9479.1 10072.5 10314.5 10690.5 11195.9 3032.9 3223.6 3285.1 3370.1 3386.8 90342.0 91206.5 91607.0 91923.5 93588.0 9185.6 9216.5 9252.9 9336.8 9454.1 16436.4 17056.9 18628.0 18682.5 18808.8 5694.8 6328.4 6698.1 6704.6 6917.9 3226.2 3738.1 3781.2 3895.6 3929.9 2384.0 2586.5 3033.0 3060.3 3070.4 587.8 599.1 602.7 603.6 613.0 591.8 634.8 645.9 648.4 659.6 7752.7 8222.1 8271.9 8522.7 8670.5 607.2 626.2 633.2 651.6 652.3 4107.2 4133.3 4165.7 4223.7 4233.9 49724.8 110748.0 112218.4 113239.4 114320.0 76653.0 95578.5 95977.0 96825.0 98111.0 88729.0 88731.1 89923.6 90102.6 91746.0 7752.9 7846.6 7851.7 8306.0 8999.4 40363.8 46220.8 49027.0 51417.7 52589.7 50137.7 51016.0 67649.3 74599.0 77338.2 9090.9 11644.1 11716.9 11759.4 12029.4 12598.7 12839.9 12936.5 12946.6 13101.7 10152.7 10336.3 10348.4 10556.4 10664.8 11573.5 11925.0 11944.1 11972.3 12205.3 9982.9 12132.5 12211.9 12607.4 12662.2 10236.6 10281.5 10360.0 10610.7 10619.7 8979.8 9918.8 10000.8 10218.7 10436.7 05:12:41 INFO - PROCESS | 1938 | -------- Summary: end -------- 05:12:41 INFO - PROCESS | 1938 | 05:12:41 INFO - TEST-INFO | 1938: exit 0 05:12:41 INFO - TEST-OK | dromaeo_css | took 1116270ms 05:12:41 INFO - TEST-START | kraken 05:12:41 INFO - operating with platform_type : mac_ 05:12:41 INFO - Initialising browser for kraken test... 05:12:41 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpGSazqg/profile http://localhost:51613/getInfo.html) 05:12:44 INFO - PROCESS | 1944 | 2016-11-09 05:12:44.696 plugin-container[1945:17361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:12:44 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:44 INFO - PROCESS | 1944 | 2016-11-09 05:12:44.700 plugin-container[1945:17361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:12:44 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:44 INFO - PROCESS | 1944 | 2016-11-09 05:12:44.700 plugin-container[1945:17361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:12:44 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 05:12:44 INFO - PROCESS | 1944 | colorDepth:24 05:12:44 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 05:12:44 INFO - PROCESS | 1944 | __metrics 05:12:44 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 05:12:45 INFO - TEST-INFO | 1944: exit 0 05:12:45 INFO - Browser initialized. 05:12:45 INFO - Running cycle 1/1 for kraken test... 05:12:45 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpGSazqg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:47 INFO - PROCESS | 1946 | 2016-11-09 05:12:47.950 plugin-container[1947:17478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 05:12:47 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:47 INFO - PROCESS | 1946 | 2016-11-09 05:12:47.951 plugin-container[1947:17478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 05:12:47 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:47 INFO - PROCESS | 1946 | 2016-11-09 05:12:47.951 plugin-container[1947:17478] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:12:57 INFO - PROCESS | 1946 | RSS: Main: 238845952 05:12:57 INFO - PROCESS | 1946 | 05:13:00 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51613/tests/kraken/driver.html?testName=audio-beat-detection) 05:13:01 INFO - PROCESS | 1946 | RSS: Main: 250085376 05:13:01 INFO - PROCESS | 1946 | 05:13:04 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51613/tests/kraken/driver.html?testName=audio-dft) 05:13:04 INFO - PROCESS | 1946 | RSS: Main: 257712128 05:13:04 INFO - PROCESS | 1946 | 05:13:08 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51613/tests/kraken/driver.html?testName=audio-fft) 05:13:08 INFO - PROCESS | 1946 | RSS: Main: 260399104 05:13:08 INFO - PROCESS | 1946 | 05:13:10 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51613/tests/kraken/driver.html?testName=audio-oscillator) 05:13:11 INFO - PROCESS | 1946 | RSS: Main: 261578752 05:13:11 INFO - PROCESS | 1946 | 05:13:13 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51613/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:13:13 INFO - PROCESS | 1946 | RSS: Main: 261898240 05:13:13 INFO - PROCESS | 1946 | 05:13:16 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51613/tests/kraken/driver.html?testName=imaging-darkroom) 05:13:17 INFO - PROCESS | 1946 | RSS: Main: 262090752 05:13:17 INFO - PROCESS | 1946 | 05:13:20 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51613/tests/kraken/driver.html?testName=imaging-desaturate) 05:13:20 INFO - PROCESS | 1946 | RSS: Main: 263442432 05:13:20 INFO - PROCESS | 1946 | 05:13:24 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51613/tests/kraken/driver.html?testName=json-parse-financial) 05:13:24 INFO - PROCESS | 1946 | RSS: Main: 264015872 05:13:24 INFO - PROCESS | 1946 | 05:13:26 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51613/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:13:26 INFO - PROCESS | 1946 | RSS: Main: 263438336 05:13:26 INFO - PROCESS | 1946 | 05:13:28 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:13:29 INFO - PROCESS | 1946 | RSS: Main: 263553024 05:13:29 INFO - PROCESS | 1946 | 05:13:31 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:13:32 INFO - PROCESS | 1946 | RSS: Main: 263487488 05:13:32 INFO - PROCESS | 1946 | 05:13:35 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:36 INFO - PROCESS | 1946 | RSS: Main: 263786496 05:13:36 INFO - PROCESS | 1946 | 05:13:39 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:39 INFO - PROCESS | 1946 | RSS: Main: 263917568 05:13:39 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:51613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51613/tests/kraken/driver.html?testName=ai-astar) 05:13:41 INFO - PROCESS | 1946 | RSS: Main: 265535488 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | __start_tp_report 05:13:41 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 05:13:41 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 05:13:41 INFO - PROCESS | 1946 | |i|pagename|runs| 05:13:41 INFO - PROCESS | 1946 | |0;ai-astar;102;117;117;117;117;118;118;120;118;119 05:13:41 INFO - PROCESS | 1946 | |1;audio-beat-detection;154;113;120;153;116;117;116;114;162;142 05:13:41 INFO - PROCESS | 1946 | |2;audio-dft;200;196;201;238;164;168;258;262;197;198 05:13:41 INFO - PROCESS | 1946 | |3;audio-fft;89;58;59;87;63;74;51;76;93;68 05:13:41 INFO - PROCESS | 1946 | |4;audio-oscillator;84;71;88;72;70;71;87;70;99;73 05:13:41 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;99;90;93;88;93;85;92;91;92;89 05:13:41 INFO - PROCESS | 1946 | |6;imaging-darkroom;97;128;152;98;97;97;98;98;101;99 05:13:41 INFO - PROCESS | 1946 | |7;imaging-desaturate;102;87;133;134;87;78;144;146;86;89 05:13:41 INFO - PROCESS | 1946 | |8;json-parse-financial;47;81;82;82;82;72;60;59;58;60 05:13:41 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;68;74;72;79;77;69;70;71;71;72 05:13:41 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;91;88;90;90;92;89;90;90;89;89 05:13:41 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;152;161;178;150;159;166;174;160;199;185 05:13:41 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;181;180;182;176;182;178;179;179;170;178 05:13:41 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;69;70;67;68;70;68;70;70;69;70 05:13:41 INFO - PROCESS | 1946 | __end_tp_report 05:13:41 INFO - PROCESS | 1946 | __start_cc_report 05:13:41 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,973 05:13:41 INFO - PROCESS | 1946 | __end_cc_report 05:13:41 INFO - PROCESS | 1946 | __startTimestamp1478697221667__endTimestamp 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | ------- Summary: start ------- 05:13:41 INFO - PROCESS | 1946 | Number of tests: 14 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:116.30 Median:118.00 stddev:5.12 (4.3%) stddev-sans-first:1.05 05:13:41 INFO - PROCESS | 1946 | Values: 102.0 117.0 117.0 117.0 117.0 118.0 118.0 120.0 118.0 119.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:131.00 stddev:19.65 (15.0%) stddev-sans-first:18.94 05:13:41 INFO - PROCESS | 1946 | Values: 154.0 113.0 120.0 153.0 116.0 117.0 116.0 114.0 162.0 142.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:208.20 Median:200.50 stddev:33.88 (16.9%) stddev-sans-first:35.80 05:13:41 INFO - PROCESS | 1946 | Values: 200.0 196.0 201.0 238.0 164.0 168.0 258.0 262.0 197.0 198.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:71.80 Median:75.00 stddev:14.44 (19.3%) stddev-sans-first:13.91 05:13:41 INFO - PROCESS | 1946 | Values: 89.0 58.0 59.0 87.0 63.0 74.0 51.0 76.0 93.0 68.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:78.50 stddev:10.23 (13.0%) stddev-sans-first:10.66 05:13:41 INFO - PROCESS | 1946 | Values: 84.0 71.0 88.0 72.0 70.0 71.0 87.0 70.0 99.0 73.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:92.00 stddev:3.71 (4.0%) stddev-sans-first:2.65 05:13:41 INFO - PROCESS | 1946 | Values: 99.0 90.0 93.0 88.0 93.0 85.0 92.0 91.0 92.0 89.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:97.50 stddev:18.58 (19.1%) stddev-sans-first:19.38 05:13:41 INFO - PROCESS | 1946 | Values: 97.0 128.0 152.0 98.0 97.0 97.0 98.0 98.0 101.0 99.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:82.00 stddev:27.29 (33.3%) stddev-sans-first:28.84 05:13:41 INFO - PROCESS | 1946 | Values: 102.0 87.0 133.0 134.0 87.0 78.0 144.0 146.0 86.0 89.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:76.50 stddev:13.00 (17.0%) stddev-sans-first:11.28 05:13:41 INFO - PROCESS | 1946 | Values: 47.0 81.0 82.0 82.0 82.0 72.0 60.0 59.0 58.0 60.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:72.00 stddev:3.47 (4.8%) stddev-sans-first:3.31 05:13:41 INFO - PROCESS | 1946 | Values: 68.0 74.0 72.0 79.0 77.0 69.0 70.0 71.0 71.0 72.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:90.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 05:13:41 INFO - PROCESS | 1946 | Values: 91.0 88.0 90.0 90.0 92.0 89.0 90.0 90.0 89.0 89.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:168.40 Median:170.00 stddev:15.50 (9.1%) stddev-sans-first:15.26 05:13:41 INFO - PROCESS | 1946 | Values: 152.0 161.0 178.0 150.0 159.0 166.0 174.0 160.0 199.0 185.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:179.50 stddev:3.54 (2.0%) stddev-sans-first:3.63 05:13:41 INFO - PROCESS | 1946 | Values: 181.0 180.0 182.0 176.0 182.0 178.0 179.0 179.0 170.0 178.0 05:13:41 INFO - PROCESS | 1946 | 05:13:41 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 05:13:41 INFO - PROCESS | 1946 | Values: 69.0 70.0 67.0 68.0 70.0 68.0 70.0 70.0 69.0 70.0 05:13:41 INFO - PROCESS | 1946 | -------- Summary: end -------- 05:13:41 INFO - PROCESS | 1946 | 05:13:42 INFO - TEST-INFO | 1946: exit 0 05:13:42 INFO - TEST-OK | kraken | took 60640ms 05:13:42 INFO - SUITE-END | took 1176s 05:13:42 INFO - Completed test suite (00:19:37) 05:13:42 INFO - javascript benchmark 05:13:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6963.352091114185, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3382.5771778527683, 3384.9323013539724, 3423.1442139465134, 3427.417339052361, 3439.071450708309, 5007.524285192901, 5538.9169162462595, 5739.913901291484, 5747.453991870771, 5768.913466298004, 5663.971680141603, 5714.257146570951, 5779.757352169283, 5808.041673123929, 5815.127730840376, 4820.842997680549, 5342.946570534294, 5364.839054828361, 5401.135818269077, 5448.318960129984, 4193.329067349225, 5326.467353264662, 5392.703401312926, 5420.620556561043, 5433.049216387136, 4568.588827005568, 5395.946040539584, 5412.810551630694, 5442.619016668835, 5452.127659574468, 4522.7964741586375, 5211.869703257411, 5230.476952304777, 5276.0, 5326.520613144797, 5214.895702085937, 5221.190715439075, 5253.86865328366, 5299.205119232146, 5351.25082488452, 4668.953310466886, 5534.1975413565215, 5871.060630299132, 5888.352281249059, 5892.911606325909, 6168.660723660197, 6180.2892667343385, 6243.719032643542, 6275.278342990523, 6291.591046581958, 5154.948450515484, 6117.877642447171, 6258.030005349178, 6260.593061451037, 6281.340459251758, 5288.81489147878, 5622.297212848394, 5900.1739756434135, 5924.992751232301, 5928.7628494860155, 6166.352532984062, 6231.532635052389, 6362.745490180341, 6371.776987805497, 6401.55189136756, 6141.117811339996, 6161.969190154021, 6186.0, 6216.129741836146, 6262.968685156637, 6262.718177682015, 6283.622982621057, 6289.528285378615, 6297.023961286008, 6304.558680892385, 6112.503480924558, 6152.046432802923, 6164.291106522807, 6253.249610046823, 6287.402696743802, 6200.224971878515, 6228.4705800007505, 6274.9686251569365, 6276.0, 6292.937070629327, 4957.801687932442, 4986.301917731521, 5012.423571289275, 5029.899402011939, 5046.697198168122, 4422.115576884636, 4456.153330867125, 4480.61914737251, 4506.233940230103, 4516.9774151129695, 5783.306003279633, 5902.645841249453, 5938.376470470538, 6009.0, 6043.637381757108, 6243.504676632817, 6276.46650034743, 6293.181886354744, 6317.96841015792, 6318.368163183737, 5894.145348924382, 5942.70286485674, 5971.98476259037, 5974.4622983930785, 5990.28116625999, 5355.30381050461, 5355.812546560852, 5424.05079111154, 5440.809571664972, 5457.126859702508, 6299.622022678654, 6361.173047503888, 6378.043293506012, 6394.424501794861, 6482.935170648233, 4724.574788269072, 5157.716325602128, 5280.917411930622, 6299.905501417391, 6323.841903952438, 6457.515686323451, 6512.902306465501, 6520.347965203442, 6524.184476940382, 6540.444062254572, 6123.84690382744, 6143.296949342275, 6146.754129834757, 6174.104754810617, 6177.227846519185, 6110.419510146529, 6141.2098563120835, 6229.12792209082, 6281.214848143982, 6285.340039295808, 6084.14821924922, 6171.814845554641, 6224.782132625336, 6234.470070044097, 6258.937410625836, 6077.6961151943115, 6141.170941922784, 6195.101710251898, 6238.282597501164, 6245.437910588069, 5097.776533631976, 5951.55363347742, 6024.518038556993, 6075.817725468066, 6096.420840020191, 5011.523905228997, 6132.969335153474, 6141.240538582634, 6181.660008699565, 6240.095186198067, 6065.939340606537, 6070.757169713162, 6077.726502307319, 6105.603135796159, 6138.447539721446], "value": 5708.324171522134, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.1897546465433, 449.18472971556645, 506.6276286929109, 507.74358948730884, 516.9121249387605, 29574.112776616683, 31212.375752484964, 37869.81065094674, 39374.0156496087, 39544.80227598865, 39558.802205988926, 39881.601183988154, 40012.79993600028, 40026.7998660007, 40049.59950400495, 9047.63809447623, 9222.769430764218, 9236.907630923706, 9255.490947997858, 9265.72202833916, 9155.992840787541, 9189.908100918972, 9216.677416290431, 9218.953905230464, 9226.892772867266, 7711.807204819868, 9113.3165012624, 9195.816083678323, 9226.169644731974, 9261.30540209487, 9144.45133291998, 9167.991520932692, 9213.67752128679, 9223.723288301362, 9227.26181905446, 8967.641294348217, 9038.231750301233, 9042.23141033013, 9055.637774489012, 9057.954710226406, 37401.251974960345, 37782.24435511274, 37922.0, 37995.62004379976, 38303.23393532142, 3419.4870769384543, 3431.5367425397503, 3465.0297916583145, 3783.4892289540835, 3791.526059242595, 28392.574111388345, 29453.26366840846, 29587.40825183506, 29745.702542974508, 30301.393972120433, 2935.8238505689515, 3114.3304189599116, 3155.148110010284, 3187.6652951439996, 3251.8048917065053, 365713.1714341411, 459075.0, 461229.0, 462738.0, 462871.0, 51513.484865151615, 53843.730781346625, 54126.45873541214, 54421.0, 54453.72773136109, 48494.515054849, 53399.0, 56540.43459565352, 56612.0, 56701.0, 14899.329530171168, 14929.40282388714, 15054.849451505565, 15169.772453412987, 15176.544703658908, 11759.353235571954, 11802.88197118035, 11852.644420667393, 11895.16733828641, 11919.702007449883, 14019.719605608036, 14482.710345793024, 14497.0, 14568.053076550203, 14691.11853288806, 3127.0, 3257.8371081446307, 3261.4618587932723, 5936.970315148396, 5955.52355811534, 863.9654413823376, 866.900306464752, 887.8002449448823, 969.8351280282369, 1041.598984391015, 28903.7109628901, 29103.0, 29289.414211715648, 29407.85296073506, 29646.40707185844, 1010.9140723038624, 1033.2199189611795, 1039.9688009359731, 1092.0444610965405, 1104.536094840169, 12863.742725145632, 13034.0, 13523.188608683511, 14721.337539810735, 15079.472218472301, 656722.0, 659730.0, 662119.0, 666853.0, 667761.6611917006, 54179.91640167175, 54711.0, 54764.45235547673, 54847.0, 54868.72565637146, 1302.7459645369252, 1327.0511584217331, 1331.5206525650758, 1347.1243691600341, 1347.9595612131652, 27819.582706259018, 28871.85564072166, 28903.0, 29245.561316579842, 29432.411351773273, 46935.765321173174, 46998.0, 47124.0, 47173.528264716915, 47376.7631161842, 593050.0, 595581.0, 596668.0, 598190.0, 608156.0, 25184.49631007406, 25470.853811578152, 25513.489730205663, 25531.106411276258, 25537.361565961, 43484.130317394105, 44109.77945110254, 44262.89342766457, 44512.33231501465, 46909.2963605553, 28244.43511129807, 29204.853975729984, 32190.03429897107, 37987.620123798406, 38140.618593813706, 32522.512162318057, 36459.0, 36678.0, 37410.43884341791, 37722.868313950625, 28364.85817570899, 30142.69857301399, 30398.93603723859, 36136.81931590325, 37178.628213717515, 30510.627021783628, 31761.523577145897, 32908.67091329056, 32978.34043319072, 33135.00594982154, 24283.635745463478, 24434.14480493174, 24446.877765611058, 24962.251132466055, 25412.491750164525], "value": 18511.605157853424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.8691503771048, 357.9534660494135, 363.93994990826513, 372.8769506062996, 395.5312954149333, 15786.921065394685, 17919.193636286367, 18557.350492732756, 19591.510212244684, 19839.0, 11425.371604561746, 14840.851591484083, 18679.066046697677, 19652.9017354913, 19664.0, 5141.0, 5364.91952620711, 5366.4633536646215, 5381.112116500773, 5387.164989426626, 5239.240310155044, 5257.815976440826, 5365.114756065244, 5765.827025189251, 5934.940650593504, 5857.6778277194735, 5863.58954873159, 5863.882722345551, 5866.9706651466895, 5868.09044598088, 5692.003899317603, 5810.680412577285, 5841.64950102993, 5849.590528662995, 5903.0555111182, 5183.067047931371, 5190.339091490724, 5191.506806853343, 5193.766280517386, 5212.921806172911, 12509.186902851285, 16847.91576042124, 23498.882505587535, 24413.145539906018, 24648.50702985948, 2063.319104695447, 2088.1542975095113, 2091.759447663523, 2179.8474106812528, 2651.7878569714376, 10094.747631309203, 10383.688489345332, 12815.615531533977, 13714.17714937097, 15162.090274583561, 1584.318742940535, 1664.4143248936753, 1704.9914750426292, 1734.6877562038828, 1774.8047714751367, 81741.18258817395, 125864.0, 126935.3653231728, 127078.0, 129734.35132824464, 11007.559697612165, 11238.831417528743, 11531.942340288246, 11559.190856639954, 11744.94127529374, 14377.712445751027, 14391.136531808124, 14405.85594144066, 14437.927810360881, 14466.27668616565, 7952.681892724342, 8251.561774355445, 8314.210150035737, 8409.95795021021, 8426.368022398345, 7068.187158476841, 8031.196880312039, 8093.676252949816, 8113.432059755761, 8276.875846862302, 4371.713366297721, 4512.52618475062, 4533.274676051882, 4607.0, 4613.630909527297, 1619.809440061554, 1684.2252563820534, 1868.4581471373149, 1934.8742331748392, 1941.0779879557097, 862.9525376104375, 888.1118881118881, 888.8800011998297, 907.8002839375326, 918.3479729392207, 16564.420245291356, 17775.489083428052, 18589.814101858807, 19349.4195174145, 20951.266705665228, 825.859603867344, 910.6949172027445, 939.8120375924842, 977.3256453047375, 1006.9496525173859, 9858.30991830565, 11450.370229637283, 11477.885221147682, 11699.590514331958, 11788.941055294668, 569806.0, 578558.0, 580926.0953695205, 594352.1694349176, 609845.0, 123094.38452807858, 123334.0, 129030.0, 134936.32531837473, 139391.0, 872.075599864146, 916.8395530782086, 981.7153025622489, 1031.7265924530157, 1049.338916482611, 19122.0, 19165.425037248904, 19219.32732354361, 19294.90352548228, 19308.8069119307, 43016.0, 52983.0, 54078.0, 54815.0, 54852.72573637106, 49368.506314937826, 54493.0, 55685.44314556965, 67630.64738705297, 79843.60078199572, 7568.243175682388, 7778.649960751666, 7936.127026027017, 8022.197780221931, 8153.551554664313, 9025.819483610423, 12091.53911352638, 12202.938985305016, 12288.877111228774, 12680.873191267969, 8966.68616598416, 10334.069933705701, 10512.684619461428, 10887.401192934465, 10988.780224395627, 10386.636467723594, 10408.947955260175, 10543.472826358804, 10952.28810127371, 11177.944110279397, 10441.0, 10462.738431539272, 10631.043206111488, 11129.054030407506, 11253.606123785628, 7894.368450524059, 8068.798280043045, 8111.634976426193, 8250.587470626564, 8277.213664702085, 9894.208463322773, 10073.748156296151, 10204.0, 10430.37417754937, 10597.788044238918], "value": 9160.20412297139, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.1336005076982, 1498.8725958293546, 1665.4254282272611, 1752.8334808193233, 1813.4559632110372, 7600.5439673619485, 7919.604019799016, 8169.87745183822, 8639.39524233304, 8664.220220180183, 8067.636956336964, 8377.95811020944, 8389.580520973943, 8405.075441701423, 8479.448835825668, 2279.8290128240365, 2322.9883850580723, 2328.5459335429596, 2340.3447034830274, 2344.202970989863, 2322.8722420266877, 2353.6940197774265, 2372.146027430124, 2385.272491889973, 2389.438481956739, 2306.6194077977198, 2317.0500094960985, 2331.5803155432013, 2332.7783860533223, 2336.696229490164, 2289.919852805152, 2294.4263934016494, 2323.93028209154, 2324.360800779799, 2329.697139371879, 2343.425860664131, 2346.16711067572, 2353.6116540770754, 2359.1624973134635, 2364.1370899621515, 8439.324854011726, 8505.447145935555, 8523.701670441505, 8527.530985795778, 8535.82928341436, 4929.580985616227, 5116.462771408986, 5279.498447647467, 5292.391374991848, 5315.813946511892, 3340.5824271966003, 3450.7929524228375, 3579.1410061585293, 3589.4974703541525, 3593.4070878305047, 1023.3655133817081, 1032.1123833503182, 1077.9892201077967, 1095.906847917928, 1097.0510508410168, 10324.535395907202, 10792.974667406585, 10918.0, 11021.614243501439, 11049.17131215162, 3628.401313783196, 4441.178381999341, 4683.578477937002, 4713.269443236304, 4716.716996980193, 9512.95243523778, 9535.52322383878, 9564.56959436827, 9580.0898914603, 9593.040695930491, 5232.73836308183, 5245.842624721265, 5263.210518422267, 5263.23683065961, 5298.788048478018, 4317.784110794448, 4345.804438800262, 4689.835855745033, 4732.503087175865, 4757.785899634525, 3752.4558938954247, 3849.2493963677375, 3856.7107466940092, 3907.257621051991, 3937.1338305572726, 1244.4151248912997, 1262.9873701263054, 1266.2529107826242, 1276.1258537901567, 1281.5130250504749, 793.714262865368, 869.4913475616709, 882.7793051737066, 909.2453263791037, 912.8767616371838, 5550.0, 5575.080111781511, 5698.0598201296325, 5770.336411312669, 6002.249718785152, 408.5975314315397, 418.9015581338383, 433.71158179810703, 435.7211384713786, 436.6506794564336, 3787.2614840105916, 3829.7319187657154, 3859.6912247020173, 3973.4834471518516, 3987.2424239394422, 8466.957665211634, 8968.654701794652, 10113.797724045478, 10281.794364112826, 10326.48367581633, 10174.186065114773, 10180.440075795754, 10197.388156710473, 10309.123724483354, 10401.479926003669, 7452.627368631655, 7742.806429839299, 7830.8433831323055, 7891.329237014918, 7963.1638677939145, 4825.203841366135, 4966.577840883565, 5560.082586373203, 5762.683052432157, 5920.911186332122, 8062.435629505878, 9346.878374595091, 9431.434113953184, 9446.811063778548, 9463.810723785347, 7004.334588214111, 7618.69525218985, 9489.335746497678, 9528.094830991045, 9530.904690953279, 6179.660118693515, 7083.291670832876, 7689.769306920802, 7749.92250077492, 7938.722144724907, 7883.5664038478435, 7938.126806051445, 8170.42807003504, 8181.222783835526, 8202.712905048294, 7170.0, 7186.245444228281, 7278.235785242473, 7294.307040831112, 7415.443841711785, 6990.545614535039, 7072.222055573986, 7091.468139889425, 7196.0645116134565, 7204.675789589586, 6568.868622627616, 6831.4876384272375, 6845.520813543203, 6857.965710171617, 6862.451003919774, 4837.419509658864, 4847.61219102478, 4935.087008903364, 5043.52086551777, 5047.570956468741, 4920.852374428773, 4980.203167493184, 5029.195328747529, 5102.744862756922, 5141.0], "value": 4351.235940635345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.51195639695936, 294.21738176450646, 305.197331019419, 306.1428001595532, 308.48175065889296, 4819.734914579705, 5105.502730629512, 5248.239005344223, 5280.9207861882105, 5356.143017117257, 5068.0, 5075.213341932006, 5263.184206448007, 5287.154055351145, 5309.044372013036, 3433.759636825423, 3626.4197728363465, 3829.5978922213253, 3869.6323849234277, 4084.530279017921, 3639.472276519903, 3879.864204752834, 3916.2754890345377, 4089.20260549193, 4114.8971275718195, 3284.3102948380747, 4039.454673619053, 4039.6970227233073, 4045.352743561031, 4098.446709694183, 3346.0798280472823, 3820.2932457495313, 3921.745086569364, 4023.939640905389, 4054.3107671695884, 2941.9556057599457, 3052.771042171824, 3526.272126657919, 3534.770239934396, 3562.305350456662, 3736.8692095776787, 4684.976575117136, 4690.906181876378, 4892.437369702494, 5174.171113376075, 4587.380703605004, 4626.83806066786, 4640.60554852838, 4656.511066338019, 4657.254839225708, 4516.735314112054, 4896.2410826321975, 5377.623566350357, 5416.283808077995, 5607.355154157283, 3218.806871587712, 3372.8482218300237, 3607.6753092221825, 3686.944695829565, 3707.29561383339, 5374.381946076212, 5815.15680226369, 5844.603712477864, 5853.073463268366, 5855.795047173328, 3421.863125474953, 3922.3920292354733, 3925.21495700861, 3931.312020396419, 3945.64489195968, 3936.8177225794984, 3997.400389941532, 4025.094353770437, 4026.939595906064, 4050.736702114353, 4490.236659767847, 4525.456945166601, 4576.931346029813, 4601.67788254819, 4627.9074418511445, 4859.538343857328, 4905.705657660552, 5573.27547418833, 5618.0, 5619.466150715675, 1153.5097583526967, 1167.7839599674087, 1236.5038303652682, 1270.0, 1293.8188653588504, 719.7876626395226, 768.7808974442257, 876.5091548732603, 879.8693678622939, 904.7333732774168, 4551.429756733922, 4591.885202869888, 4931.975340123348, 4943.901121977612, 4991.126552853236, 4298.849540266076, 5103.795848166032, 5350.384705758887, 5437.918431223535, 5536.833894983157, 4602.401687780634, 5153.304303918923, 5286.3392075990505, 5299.284596579412, 5324.866878328073, 2345.612973859328, 2440.219129878422, 2622.803289753276, 2895.4353900989104, 2901.2746813296676, 4741.566635196103, 4808.663393562487, 5084.923726144037, 5716.884752641466, 5959.4934430573885, 5273.7829923746485, 5532.640378375393, 5949.434803693643, 5964.55640547786, 5974.761009559569, 5237.6071794615555, 6019.217501724747, 6057.697115144313, 6060.969695151496, 6116.755329786848, 5586.441355864381, 5785.008344242459, 5805.680687562056, 5807.970960145172, 5808.73860676279, 5048.621353398583, 5245.973770131278, 5777.508911742549, 5778.537716982547, 5786.990764802364, 5696.54427645779, 6050.485708714808, 6053.153788094497, 6070.18052562916, 6071.51427885777, 3115.5482455044344, 3837.232553489369, 3860.6525412711867, 3881.9223615528094, 3883.2815929053218, 5789.710514474344, 5800.301755577995, 5806.893312078018, 5828.708564571839, 5871.442212989759, 4899.04468628614, 5270.288511050959, 5423.020597481969, 5627.8874422512135, 5656.575756818337, 4935.679180853233, 4992.900141997213, 5104.162501499532, 5638.887222255614, 5644.435556444323, 4431.800568974469, 5163.27714120031, 5275.314209152939, 5522.2544956431975, 5542.171083542341, 4649.0, 4865.70805751656, 5301.655392399482, 5468.960897429476, 5583.804566840304, 5242.423333433396, 5470.495613706263, 5497.021072414008, 5512.3385193777, 5530.336359636869], "value": 4008.6625054410088, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.5571706249468, 706.6254884910999, 709.6877373955463, 755.2824816424395, 799.3684988858804, 12288.877111228896, 13173.604791856236, 13241.404136813842, 13250.668733281673, 13279.136856104347, 12041.458134383953, 12430.689232769186, 12515.0, 12534.623961281177, 12544.686382840433, 788.5308241596256, 805.2832978649008, 807.096052421289, 809.2635701511625, 809.2918696140877, 648.4099469482771, 667.9866402671944, 793.4326956226297, 805.2470939671397, 806.822499050208, 858.7638399440173, 862.5213006781233, 873.3973558244822, 874.1389731114847, 876.0494863073573, 866.9523176225305, 867.3841572483544, 867.4665080975186, 867.5098569308352, 869.4261787220404, 625.2902955145896, 632.7816903168399, 641.9069234960951, 641.9760482031153, 642.9648266291267, 12493.500259989589, 12771.61685149447, 12822.487100515968, 12864.807027894589, 12965.87034129656, 11982.70043248917, 12012.63962081139, 12094.697632559168, 12178.51285948561, 12199.268043917305, 9479.099485548932, 10072.546735396925, 10314.535845886952, 10690.51892664832, 11195.944020279929, 3032.9090127296217, 3223.564818749462, 3285.0801775502896, 3370.0563842124243, 3386.779859309137, 90342.0, 91206.54396727974, 91607.0, 91923.54038229834, 93588.0, 9185.586648600829, 9216.484380442447, 9252.90747092534, 9336.813263734823, 9454.101860323259, 16436.424725134562, 17056.914715426345, 18628.0, 18682.532936676693, 18808.81191188098, 5694.772209111589, 6328.430441260309, 6698.095757072733, 6704.631245281556, 6917.89623155653, 3226.2257058305836, 3738.140227747633, 3781.1681430085337, 3895.552011518655, 3929.9214015719526, 2384.0, 2586.5473542130053, 3032.9536309973464, 3060.2808340040074, 3070.4473194825146, 587.7883961773758, 599.1103211730576, 602.728772052578, 603.5624172475008, 613.0344707086357, 591.763294682122, 634.8000379880323, 645.8708258348349, 648.4131860666105, 659.6371995402509, 7752.689892404241, 8222.093441509161, 8271.867999781509, 8522.74431767048, 8670.47977121362, 607.1954660075418, 626.1578177351455, 633.1990032608753, 651.6481100205835, 652.3085529338821, 4107.219628270619, 4133.276676581646, 4165.729227600196, 4223.746575205498, 4233.915321693549, 49724.751376242886, 110748.0, 112218.43890780494, 113239.43380283046, 114320.0, 76653.0, 95578.52210738901, 95977.0, 96825.0, 98111.0, 88729.0, 88731.11268887228, 89923.55038224767, 90102.64846027315, 91746.0, 7752.92247077522, 7846.646900889359, 7851.685932562862, 8305.95847020761, 8999.370044097112, 40363.79818101008, 46220.76889615531, 49027.0, 51417.742911285204, 52589.7370513145, 50137.74655633638, 51016.0, 67649.29402823832, 74599.0, 77338.2266177331, 9090.909090909006, 11644.068474521848, 11716.882831171579, 11759.353235572125, 12029.398530073286, 12598.685032874251, 12839.871601283867, 12936.547220847602, 12946.611601651588, 13101.73796524045, 10152.695419137437, 10336.276460647683, 10348.379097253888, 10556.366618002945, 10664.84002739975, 11573.479193436147, 11925.045996320447, 11944.104192185447, 11972.281663099893, 12205.267683938991, 9982.850257246002, 12132.514699411924, 12211.938940305241, 12607.369631518204, 12662.176958497668, 10236.618056562269, 10281.485925703835, 10359.96400359958, 10610.734731631463, 10619.681409557727, 8979.775505612412, 9918.801623967625, 10000.799984000425, 10218.744531386776, 10436.686899393031], "value": 6751.417149072865, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 120.0, 118.0, 119.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 113.0, 120.0, 153.0, 116.0, 117.0, 116.0, 114.0, 162.0, 142.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 196.0, 201.0, 238.0, 164.0, 168.0, 258.0, 262.0, 197.0, 198.0], "value": 208.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 58.0, 59.0, 87.0, 63.0, 74.0, 51.0, 76.0, 93.0, 68.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 88.0, 72.0, 70.0, 71.0, 87.0, 70.0, 99.0, 73.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 90.0, 93.0, 88.0, 93.0, 85.0, 92.0, 91.0, 92.0, 89.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 128.0, 152.0, 98.0, 97.0, 97.0, 98.0, 98.0, 101.0, 99.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 87.0, 133.0, 134.0, 87.0, 78.0, 144.0, 146.0, 86.0, 89.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 81.0, 82.0, 82.0, 82.0, 72.0, 60.0, 59.0, 58.0, 60.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 72.0, 79.0, 77.0, 69.0, 70.0, 71.0, 71.0, 72.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 90.0, 90.0, 92.0, 89.0, 90.0, 90.0, 89.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 161.0, 178.0, 150.0, 159.0, 166.0, 174.0, 160.0, 199.0, 185.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 180.0, 182.0, 176.0, 182.0, 178.0, 179.0, 179.0, 170.0, 178.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 67.0, 68.0, 70.0, 68.0, 70.0, 70.0, 69.0, 70.0], "value": 69.1, "unit": "score"}]}]} 05:13:42 INFO - Return code: 0 05:13:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:13:42 ERROR - # TBPL SUCCESS # 05:13:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:43 INFO - 2016-11-09 05:13:43.010 screenresolution[1951:17839] starting screenresolution argv=screenresolution get 05:13:43 INFO - 2016-11-09 05:13:43.013 screenresolution[1951:17839] Display 0: 1600x1200x32@60 05:13:43 INFO - 2016-11-09 05:13:43.019 screenresolution[1952:17842] starting screenresolution argv=screenresolution list 05:13:43 INFO - Available Modes on Display 0 05:13:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:43 INFO - Intel Iris: 05:13:43 INFO - Chipset Model: Intel Iris 05:13:43 INFO - Type: GPU 05:13:43 INFO - Bus: Built-In 05:13:43 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:43 INFO - Vendor: Intel (0x8086) 05:13:43 INFO - Device ID: 0x0a2e 05:13:43 INFO - Revision ID: 0x0009 05:13:43 INFO - Displays: 05:13:43 INFO - Display: 05:13:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:43 INFO - Display Serial Number: 1600x1200 60 05:13:43 INFO - Main Display: Yes 05:13:43 INFO - Mirror: Off 05:13:43 INFO - Online: Yes 05:13:43 INFO - Rotation: Supported 05:13:43 INFO - Return code: 0 05:13:43 INFO - Running post-action listener: _resource_record_post_action 05:13:43 INFO - [mozharness: 2016-11-09 13:13:43.261304Z] Finished run-tests step (success) 05:13:43 INFO - Running post-run listener: _resource_record_post_run 05:13:43 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1693184; Write bytes: 705025024; Read time: 20; Write time: 2048 05:13:43 INFO - TinderboxPrint: CPU usage
54.3% 05:13:43 INFO - TinderboxPrint: I/O read bytes / time
1,693,184 / 20 05:13:43 INFO - TinderboxPrint: I/O write bytes / time
705,025,024 / 2,048 05:13:43 INFO - TinderboxPrint: CPU idle
2,214.4 (45.7%) 05:13:43 INFO - TinderboxPrint: CPU system
979.1 (20.2%) 05:13:43 INFO - TinderboxPrint: CPU user
1,656.2 (34.2%) 05:13:43 INFO - TinderboxPrint: Swap in / out
405,929,984 / 0 05:13:43 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402987520; Write bytes: 398272512; Read time: 30709; Write time: 821 05:13:43 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1635840; Write bytes: 296705024; Read time: 19; Write time: 1190 05:13:43 INFO - Running post-run listener: _upload_blobber_files 05:13:43 INFO - Blob upload gear active. 05:13:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:13:43 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 05:13:43 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:13:43 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:13:44 INFO - (blobuploader) - INFO - Open directory for files ... 05:13:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:13:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:13:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:45 INFO - (blobuploader) - INFO - Done attempting. 05:13:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:13:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:13:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:46 INFO - (blobuploader) - INFO - Done attempting. 05:13:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:13:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:13:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:48 INFO - (blobuploader) - INFO - Done attempting. 05:13:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:13:48 INFO - Return code: 0 05:13:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:13:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:13:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/903400cba01e4904e9e5497f0cb59ee624119ccba14bb1d9a24c948d417bd503605eeee7ec4e88687775be681318c419acd6b70a3517da62db20c7c335e308e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/97e250344c2023144fe43b3f2bf335582c1b35c0f34f9351b9ad6cda6b9778252024581d38c69b2c87b6d93ab7669348b6e1bf0c8b04ab411467f84b80dea942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1eea3b92ece0d6ecf46c41f8e508ae8bbb6fdc025cc9b6b1aa1a87c0d8b7b82cfefac0b99f711d0da402cebff9efc62f2276fd3bee94737e19bd8808030d3ca5"} 05:13:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:13:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:13:48 INFO - Contents: 05:13:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/903400cba01e4904e9e5497f0cb59ee624119ccba14bb1d9a24c948d417bd503605eeee7ec4e88687775be681318c419acd6b70a3517da62db20c7c335e308e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/97e250344c2023144fe43b3f2bf335582c1b35c0f34f9351b9ad6cda6b9778252024581d38c69b2c87b6d93ab7669348b6e1bf0c8b04ab411467f84b80dea942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1eea3b92ece0d6ecf46c41f8e508ae8bbb6fdc025cc9b6b1aa1a87c0d8b7b82cfefac0b99f711d0da402cebff9efc62f2276fd3bee94737e19bd8808030d3ca5"} 05:13:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:13:48 INFO - Copying logs to upload dir... 05:13:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:13:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.239674 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-09 05:13:48.906245) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:48.909476) ========= 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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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/Cedar/sha512/903400cba01e4904e9e5497f0cb59ee624119ccba14bb1d9a24c948d417bd503605eeee7ec4e88687775be681318c419acd6b70a3517da62db20c7c335e308e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/97e250344c2023144fe43b3f2bf335582c1b35c0f34f9351b9ad6cda6b9778252024581d38c69b2c87b6d93ab7669348b6e1bf0c8b04ab411467f84b80dea942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1eea3b92ece0d6ecf46c41f8e508ae8bbb6fdc025cc9b6b1aa1a87c0d8b7b82cfefac0b99f711d0da402cebff9efc62f2276fd3bee94737e19bd8808030d3ca5"} build_url:https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/EG8VtTFORLy1SQ_CDotMUQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/903400cba01e4904e9e5497f0cb59ee624119ccba14bb1d9a24c948d417bd503605eeee7ec4e88687775be681318c419acd6b70a3517da62db20c7c335e308e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/97e250344c2023144fe43b3f2bf335582c1b35c0f34f9351b9ad6cda6b9778252024581d38c69b2c87b6d93ab7669348b6e1bf0c8b04ab411467f84b80dea942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1eea3b92ece0d6ecf46c41f8e508ae8bbb6fdc025cc9b6b1aa1a87c0d8b7b82cfefac0b99f711d0da402cebff9efc62f2276fd3bee94737e19bd8808030d3ca5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:48.944793) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:48.945180) ========= 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.LNOzKIlf5J/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.Rh0W8rQi0d/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005468 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:13:48.996250) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 05:13:48.996589) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 05:13:48.996959) ========= ========= Total master_lag: 0.20 =========