builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0160 starttime: 1478785541.34 results: success (0) buildid: 20161110030211 builduid: ac7da91f27a24f2ca32ee2d549ab595b revision: 336759fad4621dfcd0a3293840edbed67018accd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.336509) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.336992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.337272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.362280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.362622) ========= 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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-10 05:45:41-- 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% 7.23M=0.002s 2016-11-10 05:45:41 (7.23 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107380 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.492693) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.493031) ========= 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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.027122 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:41.541968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-10 05:45:41.542333) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 336759fad4621dfcd0a3293840edbed67018accd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 336759fad4621dfcd0a3293840edbed67018accd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uOCyIS7W2e/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.1dRp1w2A9i/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-10 05:45:41,619 truncating revision to first 12 chars 2016-11-10 05:45:41,619 Setting DEBUG logging. 2016-11-10 05:45:41,619 attempt 1/10 2016-11-10 05:45:41,620 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/336759fad462?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-10 05:45:42,673 unpacking tar archive at: mozilla-central-336759fad462/testing/mozharness/ program finished with exit code 0 elapsedTime=1.298673 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-10 05:45:42.863244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:42.863564) ========= script_repo_revision: 336759fad4621dfcd0a3293840edbed67018accd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:42.863952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:42.864216) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 05:45:42.882436) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-10 05:45:42.882757) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uOCyIS7W2e/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.1dRp1w2A9i/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:45:43 INFO - MultiFileLogger online at 20161110 05:45:43 in /builds/slave/test 05:45:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:45:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:45:43 INFO - {'append_to_log': False, 05:45:43 INFO - 'base_work_dir': '/builds/slave/test', 05:45:43 INFO - 'blob_upload_branch': 'Firefox', 05:45:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:45:43 INFO - 'branch': 'Firefox', 05:45:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:43 INFO - 'config_files': ('talos/mac_config.py',), 05:45:43 INFO - 'default_actions': ('clobber', 05:45:43 INFO - 'read-buildbot-config', 05:45:43 INFO - 'download-and-extract', 05:45:43 INFO - 'populate-webroot', 05:45:43 INFO - 'create-virtualenv', 05:45:43 INFO - 'install', 05:45:43 INFO - 'run-tests'), 05:45:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:43 INFO - 'download_minidump_stackwalk': True, 05:45:43 INFO - 'download_symbols': 'ondemand', 05:45:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:43 INFO - 'installer_path': 'installer.exe', 05:45:43 INFO - 'log_level': 'info', 05:45:43 INFO - 'log_name': 'talos', 05:45:43 INFO - 'log_to_console': True, 05:45:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:45:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:45:43 INFO - 'opt_config_files': (), 05:45:43 INFO - 'pip_index': False, 05:45:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:43 INFO - 'cmd': ('bash', 05:45:43 INFO - '-c', 05:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:43 INFO - 'enabled': True, 05:45:43 INFO - 'halt_on_failure': False, 05:45:43 INFO - 'name': 'check_screen_resolution'},), 05:45:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:43 INFO - 'cmd': ('bash', 05:45:43 INFO - '-c', 05:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:43 INFO - 'enabled': True, 05:45:43 INFO - 'halt_on_failure': False, 05:45:43 INFO - 'name': 'check_screen_resolution'},), 05:45:43 INFO - 'run_cmd_checks_enabled': True, 05:45:43 INFO - 'sps_profile': False, 05:45:43 INFO - 'sps_profile_interval': 0, 05:45:43 INFO - 'suite': 'dromaeojs-e10s', 05:45:43 INFO - 'system_bits': '32', 05:45:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:43 INFO - 'title': 't-yosemite-r7-0160', 05:45:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:43 INFO - 'use_talos_json': True, 05:45:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:45:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:43 INFO - 'work_dir': 'build'} 05:45:43 INFO - [mozharness: 2016-11-10 13:45:43.093464Z] Running clobber step. 05:45:43 INFO - Running pre-action listener: _resource_record_pre_action 05:45:43 INFO - Running main action method: clobber 05:45:43 INFO - rmtree: /builds/slave/test/build 05:45:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:45 INFO - Running post-action listener: _resource_record_post_action 05:45:45 INFO - [mozharness: 2016-11-10 13:45:45.540078Z] Finished clobber step (success) 05:45:45 INFO - [mozharness: 2016-11-10 13:45:45.540308Z] Running read-buildbot-config step. 05:45:45 INFO - Running pre-action listener: _resource_record_pre_action 05:45:45 INFO - Running main action method: read_buildbot_config 05:45:45 INFO - Using buildbot properties: 05:45:45 INFO - { 05:45:45 INFO - "project": "", 05:45:45 INFO - "product": "firefox", 05:45:45 INFO - "script_repo_revision": "production", 05:45:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:45:45 INFO - "repository": "", 05:45:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:45:45 INFO - "buildid": "20161110030211", 05:45:45 INFO - "stage_platform": "macosx64", 05:45:45 INFO - "basedir": "/builds/slave/test", 05:45:45 INFO - "nightly_build": "True", 05:45:45 INFO - "buildnumber": 13, 05:45:45 INFO - "slavename": "t-yosemite-r7-0160", 05:45:45 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:45:45 INFO - "platform": "yosemite_r7", 05:45:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:45:45 INFO - "branch": "mozilla-central", 05:45:45 INFO - "repo_path": "mozilla-central", 05:45:45 INFO - "revision": "336759fad4621dfcd0a3293840edbed67018accd", 05:45:45 INFO - "pgo_build": "False", 05:45:45 INFO - "builduid": "ac7da91f27a24f2ca32ee2d549ab595b", 05:45:45 INFO - "slavebuilddir": "test" 05:45:45 INFO - } 05:45:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:45:45 INFO - Running post-action listener: _resource_record_post_action 05:45:45 INFO - [mozharness: 2016-11-10 13:45:45.545864Z] Finished read-buildbot-config step (success) 05:45:45 INFO - [mozharness: 2016-11-10 13:45:45.545996Z] Running download-and-extract step. 05:45:45 INFO - Running pre-action listener: _resource_record_pre_action 05:45:45 INFO - Running main action method: download_and_extract 05:45:45 INFO - mkdir: /builds/slave/test/build/tests 05:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:45 INFO - trying https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:45:45 INFO - Downloading https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/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 05:45:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/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 05:45:46 INFO - Downloaded 1199 bytes. 05:45:46 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:45:46 INFO - Using the following test package requirements: 05:45:46 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:45:46 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:45:46 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:45:46 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'jsshell-mac.zip'], 05:45:46 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:45:46 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:45:46 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:45:46 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:45:46 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:45:46 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:46 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:45:46 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:45:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:45:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:46 INFO - Fetch https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:45:47 INFO - Http code: 200 05:45:47 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 05:45:47 INFO - x-amz-cf-id: FKOpSxc3_pctwchss3VBZdCDnb27kan4XgTZAVUjPY4jnvTj-RqveA== 05:45:47 INFO - x-amz-version-id: nXT2ZxqTfG2qJIXhbs0.Ot91lqDfrf7H 05:45:47 INFO - Expected file size: 16320495 05:45:47 INFO - Obtained file size: 16320495 05:45:54 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 05:45:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:45:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:54 INFO - Fetch https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:45:55 INFO - Http code: 200 05:45:55 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 05:45:55 INFO - x-amz-cf-id: YeOAP-ogt-L09ymZnJHGAahDE-xfGKKac6qLYLyP9E1syvqkmgy2nw== 05:45:55 INFO - x-amz-version-id: nXT2ZxqTfG2qJIXhbs0.Ot91lqDfrf7H 05:45:55 INFO - Expected file size: 16320495 05:45:55 INFO - Obtained file size: 16320495 05:46:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:46:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:46:01 INFO - Fetch https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:46:02 INFO - Http code: 200 05:46:02 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 05:46:02 INFO - x-amz-cf-id: a3X87V2IOmlP-J2wki1zcjvaAa4doXFBQeAEEMhwrKAdaD_vCwJ66w== 05:46:02 INFO - x-amz-version-id: SsDCzDAayeOZwF57KZvgfqLSWhtzWH0R 05:46:03 INFO - Expected file size: 14409038 05:46:03 INFO - Obtained file size: 14409038 05:46:03 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')]} 05:46:03 INFO - trying https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:46:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:46:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/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 05:46:06 INFO - Downloaded 111113261 bytes. 05:46:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:46:06 INFO - mkdir: /builds/slave/test/properties 05:46:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:46:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:46:06 INFO - Contents: 05:46:06 INFO - build_url:https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:46:06 INFO - Running post-action listener: _resource_record_post_action 05:46:06 INFO - Running post-action listener: set_extra_try_arguments 05:46:06 INFO - [mozharness: 2016-11-10 13:46:06.996650Z] Finished download-and-extract step (success) 05:46:06 INFO - [mozharness: 2016-11-10 13:46:06.996801Z] Running populate-webroot step. 05:46:06 INFO - Running pre-action listener: _resource_record_pre_action 05:46:06 INFO - Running main action method: populate_webroot 05:46:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:07 INFO - '--tppagecycles', 05:46:07 INFO - '7'], 05:46:07 INFO - 'tests': ['tsvgm']}}, 05:46:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:46:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:46:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:46:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['damp', 'tps']}, 05:46:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'tests': ['damp', 'tps']}, 05:46:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['dromaeo_dom']}, 05:46:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['basic_compositor_video']}, 05:46:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:46:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['a11yr', 05:46:07 INFO - 'ts_paint', 05:46:07 INFO - 'tpaint', 05:46:07 INFO - 'sessionrestore', 05:46:07 INFO - 'sessionrestore_no_auto_restore', 05:46:07 INFO - 'tabpaint']}, 05:46:07 INFO - 'other-e10s': {'tests': ['a11yr', 05:46:07 INFO - 'ts_paint', 05:46:07 INFO - 'tpaint', 05:46:07 INFO - 'sessionrestore', 05:46:07 INFO - 'sessionrestore_no_auto_restore', 05:46:07 INFO - 'tabpaint']}, 05:46:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:46:07 INFO - 'ts_paint', 05:46:07 INFO - 'tpaint', 05:46:07 INFO - 'sessionrestore', 05:46:07 INFO - 'sessionrestore_no_auto_restore', 05:46:07 INFO - 'tabpaint']}, 05:46:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:46:07 INFO - 'ts_paint', 05:46:07 INFO - 'tpaint', 05:46:07 INFO - 'sessionrestore', 05:46:07 INFO - 'sessionrestore_no_auto_restore', 05:46:07 INFO - 'tabpaint']}, 05:46:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['a11yr', 05:46:07 INFO - 'ts_paint', 05:46:07 INFO - 'tpaint', 05:46:07 INFO - 'sessionrestore', 05:46:07 INFO - 'sessionrestore_no_auto_restore', 05:46:07 INFO - 'tabpaint']}, 05:46:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['a11yr', 05:46:07 INFO - 'ts_paint', 05:46:07 INFO - 'tpaint', 05:46:07 INFO - 'sessionrestore', 05:46:07 INFO - 'sessionrestore_no_auto_restore', 05:46:07 INFO - 'tabpaint']}, 05:46:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['tsvgx', 05:46:07 INFO - 'tsvgr_opacity', 05:46:07 INFO - 'tart', 05:46:07 INFO - 'tscrollx', 05:46:07 INFO - 'cart']}, 05:46:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:46:07 INFO - 'tsvgr_opacity', 05:46:07 INFO - 'tart', 05:46:07 INFO - 'tscrollx', 05:46:07 INFO - 'cart']}, 05:46:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'talos_options': ['--disable-e10s'], 05:46:07 INFO - 'tests': ['tp5o']}, 05:46:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'tests': ['tp5o']}, 05:46:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'talos_options': ['--disable-e10s', 05:46:07 INFO - '--xperf_path', 05:46:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:07 INFO - 'tests': ['tp5n']}, 05:46:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:07 INFO - 'talos_options': ['--xperf_path', 05:46:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:07 INFO - 'tests': ['tp5n']}}, 05:46:07 INFO - 'talos.zip': {'path': '', 05:46:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:07 INFO - Running post-action listener: _resource_record_post_action 05:46:07 INFO - [mozharness: 2016-11-10 13:46:07.015649Z] Finished populate-webroot step (success) 05:46:07 INFO - [mozharness: 2016-11-10 13:46:07.015777Z] Running create-virtualenv step. 05:46:07 INFO - Running pre-action listener: _resource_record_pre_action 05:46:07 INFO - Running main action method: create_virtualenv 05:46:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:46:07 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 05:46:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:46:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:07 INFO - Using real prefix '/tools/python27' 05:46:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:46:07 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:10 INFO - Installing pip.................done. 05:46:10 INFO - Return code: 0 05:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:46:11 INFO - Reading from file tmpfile_stdout 05:46:11 INFO - Output received: 05:46:11 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) 05:46:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:46:11 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')]} 05:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 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')]} 05:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:11 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 05:46:11 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 05:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:11 INFO - 'HOME': '/Users/cltbld', 05:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:11 INFO - 'LOGNAME': 'cltbld', 05:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:11 INFO - 'MOZ_NO_REMOTE': '1', 05:46:11 INFO - 'NO_EM_RESTART': '1', 05:46:11 INFO - 'PAGER': '/bin/cat', 05:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:11 INFO - 'PWD': '/builds/slave/test', 05:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:11 INFO - 'SHELL': '/bin/bash', 05:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:11 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:11 INFO - 'USER': 'cltbld', 05:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:11 INFO - 'XPC_FLAGS': '0x0', 05:46:11 INFO - 'XPC_SERVICE_NAME': '0', 05:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:11 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 05:46:11 INFO - Cleaning up... 05:46:11 INFO - Return code: 0 05:46:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:46:11 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')]} 05:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 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')]} 05:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:11 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 05:46:11 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 05:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:11 INFO - 'HOME': '/Users/cltbld', 05:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:11 INFO - 'LOGNAME': 'cltbld', 05:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:11 INFO - 'MOZ_NO_REMOTE': '1', 05:46:11 INFO - 'NO_EM_RESTART': '1', 05:46:11 INFO - 'PAGER': '/bin/cat', 05:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:11 INFO - 'PWD': '/builds/slave/test', 05:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:11 INFO - 'SHELL': '/bin/bash', 05:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:11 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:11 INFO - 'USER': 'cltbld', 05:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:11 INFO - 'XPC_FLAGS': '0x0', 05:46:11 INFO - 'XPC_SERVICE_NAME': '0', 05:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:11 INFO - Downloading/unpacking psutil>=3.1.1 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:14 INFO - Installing collected packages: psutil 05:46:14 INFO - Running setup.py install for psutil 05:46:15 INFO - building 'psutil._psutil_osx' extension 05:46:15 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 05:46:15 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 05:46:15 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 05:46:15 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 05:46:15 INFO - building 'psutil._psutil_posix' extension 05:46:15 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 05:46:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:46:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:46:15 INFO - ^ 05:46:16 INFO - 1 warning generated. 05:46:16 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 05:46:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:16 INFO - Successfully installed psutil 05:46:16 INFO - Cleaning up... 05:46:16 INFO - Return code: 0 05:46:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:46:16 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')]} 05:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:16 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')]} 05:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:16 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:16 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 05:46:16 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 05:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:16 INFO - 'HOME': '/Users/cltbld', 05:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:16 INFO - 'LOGNAME': 'cltbld', 05:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:16 INFO - 'MOZ_NO_REMOTE': '1', 05:46:16 INFO - 'NO_EM_RESTART': '1', 05:46:16 INFO - 'PAGER': '/bin/cat', 05:46:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:16 INFO - 'PWD': '/builds/slave/test', 05:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:16 INFO - 'SHELL': '/bin/bash', 05:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:16 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:16 INFO - 'USER': 'cltbld', 05:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:16 INFO - 'XPC_FLAGS': '0x0', 05:46:16 INFO - 'XPC_SERVICE_NAME': '0', 05:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:46:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:46:19 INFO - Installing collected packages: mozsystemmonitor 05:46:19 INFO - Running setup.py install for mozsystemmonitor 05:46:19 INFO - Successfully installed mozsystemmonitor 05:46:19 INFO - Cleaning up... 05:46:19 INFO - Return code: 0 05:46:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:46:19 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')]} 05:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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')]} 05:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:19 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 05:46:19 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 05:46:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:19 INFO - 'HOME': '/Users/cltbld', 05:46:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:19 INFO - 'LOGNAME': 'cltbld', 05:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:19 INFO - 'MOZ_NO_REMOTE': '1', 05:46:19 INFO - 'NO_EM_RESTART': '1', 05:46:19 INFO - 'PAGER': '/bin/cat', 05:46:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:19 INFO - 'PWD': '/builds/slave/test', 05:46:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:19 INFO - 'SHELL': '/bin/bash', 05:46:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:19 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:19 INFO - 'USER': 'cltbld', 05:46:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:19 INFO - 'XPC_FLAGS': '0x0', 05:46:19 INFO - 'XPC_SERVICE_NAME': '0', 05:46:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:19 INFO - Downloading/unpacking jsonschema==2.5.1 05:46:19 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 05:46:19 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 05:46:19 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 05:46:19 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 05:46:19 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 05:46:19 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 05:46:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:22 INFO - warning: no files found matching '*.txt' 05:46:22 INFO - no previously-included directories found matching 'build' 05:46:22 INFO - no previously-included directories found matching 'dist' 05:46:22 INFO - no previously-included directories found matching '.git*' 05:46:22 INFO - Installing collected packages: jsonschema, functools32 05:46:22 INFO - Running setup.py install for functools32 05:46:22 INFO - warning: no files found matching '*.txt' 05:46:22 INFO - no previously-included directories found matching 'build' 05:46:22 INFO - no previously-included directories found matching 'dist' 05:46:22 INFO - no previously-included directories found matching '.git*' 05:46:22 INFO - Successfully installed jsonschema functools32 05:46:22 INFO - Cleaning up... 05:46:23 INFO - Return code: 0 05:46:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:23 INFO - 'HOME': '/Users/cltbld', 05:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:23 INFO - 'LOGNAME': 'cltbld', 05:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:23 INFO - 'MOZ_NO_REMOTE': '1', 05:46:23 INFO - 'NO_EM_RESTART': '1', 05:46:23 INFO - 'PAGER': '/bin/cat', 05:46:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:23 INFO - 'PWD': '/builds/slave/test', 05:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:23 INFO - 'SHELL': '/bin/bash', 05:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:23 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:23 INFO - 'USER': 'cltbld', 05:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:23 INFO - 'XPC_FLAGS': '0x0', 05:46:23 INFO - 'XPC_SERVICE_NAME': '0', 05:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:46:23 INFO - Cleaning up... 05:46:23 INFO - Return code: 0 05:46:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:23 INFO - 'HOME': '/Users/cltbld', 05:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:23 INFO - 'LOGNAME': 'cltbld', 05:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:23 INFO - 'MOZ_NO_REMOTE': '1', 05:46:23 INFO - 'NO_EM_RESTART': '1', 05:46:23 INFO - 'PAGER': '/bin/cat', 05:46:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:23 INFO - 'PWD': '/builds/slave/test', 05:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:23 INFO - 'SHELL': '/bin/bash', 05:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:23 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:23 INFO - 'USER': 'cltbld', 05:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:23 INFO - 'XPC_FLAGS': '0x0', 05:46:23 INFO - 'XPC_SERVICE_NAME': '0', 05:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:23 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:23 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 05:46:23 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 05:46:23 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 05:46:23 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 05:46:23 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 05:46:23 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 05:46:26 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:46:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 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 05:46:26 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 05:46:27 INFO - Downloading docopt-0.6.1.tar.gz 05:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:27 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:27 INFO - Running setup.py install for blobuploader 05:46:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:46:27 INFO - Running setup.py install for requests 05:46:28 INFO - Running setup.py install for docopt 05:46:28 INFO - Successfully installed blobuploader requests docopt 05:46:28 INFO - Cleaning up... 05:46:28 INFO - Return code: 0 05:46:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:28 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:28 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 05:46:28 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 05:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:28 INFO - 'HOME': '/Users/cltbld', 05:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:28 INFO - 'LOGNAME': 'cltbld', 05:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:28 INFO - 'MOZ_NO_REMOTE': '1', 05:46:28 INFO - 'NO_EM_RESTART': '1', 05:46:28 INFO - 'PAGER': '/bin/cat', 05:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:28 INFO - 'PWD': '/builds/slave/test', 05:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:28 INFO - 'SHELL': '/bin/bash', 05:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:28 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:28 INFO - 'USER': 'cltbld', 05:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:28 INFO - 'XPC_FLAGS': '0x0', 05:46:28 INFO - 'XPC_SERVICE_NAME': '0', 05:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:28 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-hMmifk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:28 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-RAjndg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:28 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MMuHDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:28 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-hDmgKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:28 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-z0rFOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-duSPvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-y8LlsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-VgiQ88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-rT5wWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-kVdW_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-A2OBqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MKQNhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-M8U_es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:29 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-1J06kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MYbVtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-3935mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:30 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-5Lzwqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:30 INFO - Running setup.py install for manifestparser 05:46:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:46:30 INFO - Running setup.py install for mozcrash 05:46:30 INFO - Running setup.py install for mozdebug 05:46:30 INFO - Running setup.py install for mozdevice 05:46:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:46:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:46:30 INFO - Running setup.py install for mozfile 05:46:31 INFO - Running setup.py install for mozhttpd 05:46:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Running setup.py install for mozinfo 05:46:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Running setup.py install for mozInstall 05:46:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Running setup.py install for mozleak 05:46:31 INFO - Running setup.py install for mozlog 05:46:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Running setup.py install for moznetwork 05:46:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Running setup.py install for mozprocess 05:46:32 INFO - Running setup.py install for mozprofile 05:46:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:46:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:46:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:46:32 INFO - Running setup.py install for mozrunner 05:46:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:46:32 INFO - Running setup.py install for mozscreenshot 05:46:32 INFO - Running setup.py install for moztest 05:46:32 INFO - Running setup.py install for mozversion 05:46:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:46:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:32 INFO - Cleaning up... 05:46:32 INFO - Return code: 0 05:46:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:32 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')]} 05:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:32 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')]} 05:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:32 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:32 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 05:46:32 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 05:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:32 INFO - 'HOME': '/Users/cltbld', 05:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:32 INFO - 'LOGNAME': 'cltbld', 05:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:32 INFO - 'MOZ_NO_REMOTE': '1', 05:46:32 INFO - 'NO_EM_RESTART': '1', 05:46:32 INFO - 'PAGER': '/bin/cat', 05:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:32 INFO - 'PWD': '/builds/slave/test', 05:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:32 INFO - 'SHELL': '/bin/bash', 05:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:32 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:32 INFO - 'USER': 'cltbld', 05:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:32 INFO - 'XPC_FLAGS': '0x0', 05:46:32 INFO - 'XPC_SERVICE_NAME': '0', 05:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-w28ckM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ty1lw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-oq8EQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Z_fFJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-VOWFug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-s9Fzvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-e9cgUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:33 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)) 05:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:33 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-BUBFRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-2R9hbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-jJUTIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ms4GIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-FuoN3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-bXhO9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-AUzL7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-YQwi63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-mP6zqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:34 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)) 05:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:34 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-XRKMJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:35 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)) 05:46:35 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)) 05:46:35 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)) 05:46:35 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)) 05:46:35 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)) 05:46:35 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)) 05:46:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:35 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 05:46:35 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 05:46:35 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 05:46:35 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 05:46:35 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 05:46:35 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 05:46:37 INFO - Downloading blessings-1.6.tar.gz 05:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:37 INFO - Installing collected packages: blessings 05:46:37 INFO - Running setup.py install for blessings 05:46:37 INFO - Successfully installed blessings 05:46:37 INFO - Cleaning up... 05:46:37 INFO - Return code: 0 05:46:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:46:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:38 INFO - Reading from file tmpfile_stdout 05:46:38 INFO - Current package versions: 05:46:38 INFO - blessings == 1.6 05:46:38 INFO - blobuploader == 1.2.4 05:46:38 INFO - docopt == 0.6.1 05:46:38 INFO - functools32 == 3.2.3-2 05:46:38 INFO - jsonschema == 2.5.1 05:46:38 INFO - manifestparser == 1.1 05:46:38 INFO - mozInstall == 1.12 05:46:38 INFO - mozcrash == 1.0 05:46:38 INFO - mozdebug == 0.1 05:46:38 INFO - mozdevice == 0.48 05:46:38 INFO - mozfile == 1.2 05:46:38 INFO - mozhttpd == 0.7 05:46:38 INFO - mozinfo == 0.9 05:46:38 INFO - mozleak == 0.1 05:46:38 INFO - mozlog == 3.4 05:46:38 INFO - moznetwork == 0.27 05:46:38 INFO - mozprocess == 0.23 05:46:38 INFO - mozprofile == 0.28 05:46:38 INFO - mozrunner == 6.13 05:46:38 INFO - mozscreenshot == 0.1 05:46:38 INFO - mozsystemmonitor == 0.3 05:46:38 INFO - moztest == 0.7 05:46:38 INFO - mozversion == 1.4 05:46:38 INFO - psutil == 3.1.1 05:46:38 INFO - requests == 1.2.3 05:46:38 INFO - wsgiref == 0.1.2 05:46:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:38 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')]} 05:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:38 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')]} 05:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:39 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 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:39 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 05:46:39 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 05:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:39 INFO - 'HOME': '/Users/cltbld', 05:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:39 INFO - 'LOGNAME': 'cltbld', 05:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:39 INFO - 'MOZ_NO_REMOTE': '1', 05:46:39 INFO - 'NO_EM_RESTART': '1', 05:46:39 INFO - 'PAGER': '/bin/cat', 05:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:39 INFO - 'PWD': '/builds/slave/test', 05:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:39 INFO - 'SHELL': '/bin/bash', 05:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:39 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:39 INFO - 'USER': 'cltbld', 05:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:39 INFO - 'XPC_FLAGS': '0x0', 05:46:39 INFO - 'XPC_SERVICE_NAME': '0', 05:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 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)) 05:46:39 INFO - Cleaning up... 05:46:39 INFO - Return code: 0 05:46:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102de1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d57570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3e8d1bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, '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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 05:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:39 INFO - 'HOME': '/Users/cltbld', 05:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:39 INFO - 'LOGNAME': 'cltbld', 05:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:39 INFO - 'MOZ_NO_REMOTE': '1', 05:46:39 INFO - 'NO_EM_RESTART': '1', 05:46:39 INFO - 'PAGER': '/bin/cat', 05:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:39 INFO - 'PWD': '/builds/slave/test', 05:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:39 INFO - 'SHELL': '/bin/bash', 05:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:46:39 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:39 INFO - 'USER': 'cltbld', 05:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:39 INFO - 'XPC_FLAGS': '0x0', 05:46:39 INFO - 'XPC_SERVICE_NAME': '0', 05:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:46:40 INFO - Cleaning up... 05:46:40 INFO - Return code: 0 05:46:40 INFO - Running post-action listener: _resource_record_post_action 05:46:40 INFO - Running post-action listener: _start_resource_monitoring 05:46:40 INFO - Starting resource monitoring. 05:46:40 INFO - [mozharness: 2016-11-10 13:46:40.134376Z] Finished create-virtualenv step (success) 05:46:40 INFO - [mozharness: 2016-11-10 13:46:40.135347Z] Running install step. 05:46:40 INFO - Running pre-action listener: _resource_record_pre_action 05:46:40 INFO - Running main action method: install 05:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:40 INFO - Reading from file tmpfile_stdout 05:46:40 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:46:40 INFO - Reading from file tmpfile_stdout 05:46:40 INFO - Output received: 05:46:40 INFO - Usage: mozinstall [options] installer 05:46:40 INFO - Options: 05:46:40 INFO - -h, --help show this help message and exit 05:46:40 INFO - -d DEST, --destination=DEST 05:46:40 INFO - Directory to install application into. [default: 05:46:40 INFO - "/builds/slave/test"] 05:46:40 INFO - --app=APP Application being installed. [default: firefox] 05:46:40 INFO - mkdir: /builds/slave/test/build/application 05:46:40 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'] 05:46:40 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 05:47:14 INFO - Reading from file tmpfile_stdout 05:47:14 INFO - Output received: 05:47:14 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:47:14 INFO - Running post-action listener: _resource_record_post_action 05:47:14 INFO - [mozharness: 2016-11-10 13:47:14.674983Z] Finished install step (success) 05:47:14 INFO - [mozharness: 2016-11-10 13:47:14.675147Z] Running run-tests step. 05:47:14 INFO - Running pre-action listener: _resource_record_pre_action 05:47:14 INFO - Running main action method: run_tests 05:47:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:47:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:47:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:47:14 INFO - 2016-11-10 05:47:14.689 screenresolution[1644:7544] starting screenresolution argv=screenresolution get 05:47:14 INFO - 2016-11-10 05:47:14.692 screenresolution[1644:7544] Display 0: 1600x1200x32@60 05:47:14 INFO - 2016-11-10 05:47:14.698 screenresolution[1645:7546] starting screenresolution argv=screenresolution list 05:47:14 INFO - Available Modes on Display 0 05:47:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:47:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:47:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:47:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:47:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:47:14 INFO - Intel Iris: 05:47:14 INFO - Chipset Model: Intel Iris 05:47:14 INFO - Type: GPU 05:47:14 INFO - Bus: Built-In 05:47:14 INFO - VRAM (Dynamic, Max): 1536 MB 05:47:14 INFO - Vendor: Intel (0x8086) 05:47:14 INFO - Device ID: 0x0a2e 05:47:14 INFO - Revision ID: 0x0009 05:47:14 INFO - Displays: 05:47:14 INFO - Display: 05:47:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:47:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:47:14 INFO - Display Serial Number: 1600x1200 60 05:47:14 INFO - Main Display: Yes 05:47:14 INFO - Mirror: Off 05:47:14 INFO - Online: Yes 05:47:14 INFO - Rotation: Supported 05:47:14 INFO - Return code: 0 05:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:47:14 INFO - Python 2.7.3 05:47:14 INFO - Return code: 0 05:47:14 INFO - grabbing minidump binary from tooltool 05:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:14 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf5d30>, '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 0x102a1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bedb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:47:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:47:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:47:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:47:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:47:15 INFO - Return code: 0 05:47:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:47:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:47:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:47:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:47:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 05:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --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 05:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOCyIS7W2e/Render', 05:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:15 INFO - 'HOME': '/Users/cltbld', 05:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:15 INFO - 'LOGNAME': 'cltbld', 05:47:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:47:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:15 INFO - 'MOZ_NO_REMOTE': '1', 05:47:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:47:15 INFO - 'NO_EM_RESTART': '1', 05:47:15 INFO - 'PAGER': '/bin/cat', 05:47:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:15 INFO - 'PWD': '/builds/slave/test', 05:47:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:15 INFO - 'SHELL': '/bin/bash', 05:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1dRp1w2A9i/Listeners', 05:47:15 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 05:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:15 INFO - 'USER': 'cltbld', 05:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:15 INFO - 'XPC_FLAGS': '0x0', 05:47:15 INFO - 'XPC_SERVICE_NAME': '0', 05:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 05:47:15 INFO - mozversion application_buildid: 20161110030211 05:47:15 INFO - mozversion application_changeset: 336759fad4621dfcd0a3293840edbed67018accd 05:47:15 INFO - mozversion application_display_name: Nightly 05:47:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:47:15 INFO - mozversion application_name: Firefox 05:47:15 INFO - mozversion application_remotingname: firefox 05:47:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:47:15 INFO - mozversion application_vendor: Mozilla 05:47:15 INFO - mozversion application_version: 52.0a1 05:47:15 INFO - mozversion platform_buildid: 20161110030211 05:47:15 INFO - mozversion platform_changeset: 336759fad4621dfcd0a3293840edbed67018accd 05:47:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:47:15 INFO - mozversion platform_version: 52.0a1 05:47:15 INFO - using testdate: 1478785635 05:47:15 INFO - actual date: 1478785635 05:47:15 INFO - starting webserver on 'localhost:49280' 05:47:15 INFO - SUITE-START | Running 2 tests 05:47:15 INFO - TEST-START | dromaeo_css 05:47:15 INFO - operating with platform_type : mac_ 05:47:15 INFO - Initialising browser for dromaeo_css test... 05:47:15 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpMt7ixo/profile http://localhost:49280/getInfo.html) 05:47:18 INFO - PROCESS | 1655 | 2016-11-10 05:47:18.635 plugin-container[1658:7720] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 05:47:18 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:18 INFO - PROCESS | 1655 | 2016-11-10 05:47:18.636 plugin-container[1658:7720] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 05:47:18 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:18 INFO - PROCESS | 1655 | 2016-11-10 05:47:18.636 plugin-container[1658:7720] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:18 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:47:18 INFO - PROCESS | 1655 | colorDepth:24 05:47:18 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:47:18 INFO - PROCESS | 1655 | __metrics 05:47:18 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 05:47:19 INFO - TEST-INFO | 1655: exit 0 05:47:19 INFO - Browser initialized. 05:47:19 INFO - Running cycle 1/1 for dromaeo_css test... 05:47:19 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpMt7ixo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:22 INFO - PROCESS | 1662 | 2016-11-10 05:47:22.033 plugin-container[1663:7907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 05:47:22 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:22 INFO - PROCESS | 1662 | 2016-11-10 05:47:22.034 plugin-container[1663:7907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 05:47:22 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:22 INFO - PROCESS | 1662 | 2016-11-10 05:47:22.034 plugin-container[1663:7907] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:31 INFO - PROCESS | 1662 | RSS: Main: 237305856 05:47:31 INFO - PROCESS | 1662 | 05:48:21 INFO - PROCESS | 1662 | 1478785701390 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] 05:50:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 05:50:35 INFO - PROCESS | 1662 | RSS: Main: 252067840 05:50:35 INFO - PROCESS | 1662 | 05:53:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 05:53:38 INFO - PROCESS | 1662 | RSS: Main: 260845568 05:53:38 INFO - PROCESS | 1662 | 05:56:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 05:56:41 INFO - PROCESS | 1662 | RSS: Main: 266412032 05:56:41 INFO - PROCESS | 1662 | 05:59:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 05:59:43 INFO - PROCESS | 1662 | RSS: Main: 265220096 05:59:43 INFO - PROCESS | 1662 | 06:02:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 06:02:46 INFO - PROCESS | 1662 | RSS: Main: 270573568 06:02:46 INFO - PROCESS | 1662 | 06:05:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 06:05:50 INFO - PROCESS | 1662 | RSS: Main: 273235968 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | __start_tp_report 06:05:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 06:05:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 06:05:50 INFO - PROCESS | 1662 | |i|pagename|runs| 06:05:50 INFO - PROCESS | 1662 | |0;dojo.html;2776.819506732062;3377.5102610121535;3379.239671074007;3396.60938992016;3430.056734398041;4593.265077587586;5689.658620482774;5728.94014607298;5738.856528586787;5764.25064741583;4793.808247670089;5559.8888022239535;5642.605017648766;5683.5169010634145;5722.828315150542;5118.692878427288;5139.922901156486;5177.249298851683;5236.136037553799;5273.472652734734;5192.091384007803;5239.738013099349;5270.762815673284;5285.920711189316;5310.867228319285;198.03289952347723;5206.37523497179;5234.293370394986;5270.552003079723;5295.659336544938;4214.346776249687;4321.373400856858;5061.949380506185;5102.056119617882;5105.591552675777;5022.924656130161;5061.114304996648;5080.644354895159;5082.542571168576;5114.769835357418;4650.976745116287;5424.457554244584;5547.72261386933;5602.887942241173;5616.382197958222;5092.618053645954;6112.5946701277735;6113.755449782004;6126.761967422298;6144.893919094561;6263.185785847811;6265.906011409833;6266.467350275187;6275.8744825103695;6292.024736165901;5866.296044474648;5879.709609450697;5885.79399721012;5920.31916329619;5924.022536281508;6107.370879189147;6122.032243765608;6133.334663918993;6232.3144454110015;6264.59280146789;6076.058210977306;6111.083337499411;6153.323134455206;6160.9383906160365;6203.100550420164;6135.754569817246;6189.690515474208;6195;6206.596571222856;6223.253209614784;6085.5435842312;6135.753292002367;6172;6174.876502470015;6188.226471691039;6146.309764814622;6181.567290289727;6233.34549872266;6260.373962603794;6260.561760676709;4768.1417344878255;4805.567498925114;4851.781669824866;4922.9753851231235;4952.702837829742;4349.499807522112;4379.715318504287;4407.272799987966;4420.160169567772;4422.690411671217;5444.128939369682;5687.40282270364;5738.655680659173;5795.884082318415;5820.592558520948;6179.134921111048;6214.720337584819;6226.813195604139;6238.438540531374;6261.5616906816995;4953;4954.504549545089;5133.435322114565;5845.386234445322;5919.6152250103605;5314.990151871132;5427.755750991215;5447.727613619382;5454.10007348783;5529.447055294519;6135.5082808396355;6152.600080994721;6228.781992630236;6260.843478913063;6269.96865015672;6020.698965051817;6048.455638992512;6051.364606716407;6055.303640081447;6064.939350606437;6241.96879015602;6253.437190652863;6296.464800492009;6301;6320.399562041599;5066.645334826527;6059.242594675666;6111.480524155497;6163.291221509405;6248.5313601479165;6188.659623720739;6209.534284928739;6255.906161407674;6261.405166509175;6261.812145635638;5500.50495455411;5969.835882003142;6150.723217455315;6158.538109641884;6161.938380616137;6077.969610151921;6158.661273629814;6173;6194.566380353332;6220.595661282002;5720.48515633595;5766.308043034863;5922.851928701644;6007.969960150172;6029;6069.453749162597;6086.878262434638;6097.085437184458;6135.355787642234;6137.202163718688;5993.460588547052;6040.75836966534;6047.909281360871;6082.087686847009;6092 06:05:50 INFO - PROCESS | 1662 | |1;ext.html;459.99032124486746;466.1609103613496;485.38598672679046;515.0574448758771;547.5236544206541;35827;38959.61040389595;39027.414588781154;39147;39185.80407097968;35221.64778352216;39898.80050599743;39947.80026099866;40033.39949900754;40124.79937600315;8991.730248092552;9141.131592498701;9172.082791720808;9188.8621670675;9192.034836342187;9146.588403521824;9244.306676999218;9250.167484926355;9251.53742312885;9259.907400926006;9223.354365194438;9239.445633261992;9248.907510924872;9252;9282.582283797245;9197.448153110801;9220.262379009662;9239.399439036508;9240.122188392126;9254.907450925539;8996.280297576179;9082.228010619105;9084.954575227148;9086.591103400362;9095.181433671001;37156.44265336022;37388.626113738785;37420.62579374199;37440.43839342412;37481;3197.7441804655805;3379.509971054208;3648.7263455240964;3879.437481565157;3911.8826435206706;29034.983775567754;29322.706772932423;29525.557116643267;29713.70286297131;30224;3197.888073917425;3201.119692084672;3203.5034569641743;3265.4122257993554;3301.4882693182594;427164.86417568027;487006;498381;502554;508236;58838.41161588329;59002;59055.70472147612;59357.70321148453;60018.69990650106;61289;61925.690371547855;61996;62052.68973655103;62361.376386236465;15521.922390387976;15639.68720625581;15789.526314210592;15822.52532424029;15842.52472425829;11447.942760286312;11832.408379580986;12167.817482737766;12187.939060304641;12206.75586488283;16319.755203671955;16348.427805026768;16414.589635259214;16497;16508.266632003466;5763.452472015152;6375.553711240169;6410.8076757697345;6419.042192131351;6431.839204019937;710.6446776611695;847.9660813567389;945.9101385368378;953.3231405702029;970.7427531704104;29420.852895735385;29511.55732664012;30049.248768780955;30199.547006794917;30205;1039.2361614213546;1041.1722680469045;1052.1950707708609;1068.962586309491;1081.7511972246427;15305.46430874914;15373.769393459108;15374.461893833663;15488.567039064419;15721.68556628861;663469.6826515837;665873;666267;673024;673116;56428.717856410454;56886.43113568894;57447.42552574504;57468.71265643645;57720;1314.3559655768604;1328.110166188656;1348.4740951029303;1353.3706826325608;1358.0900796466194;29254.561181581867;29290.56064159082;29430.852845735633;29953.850230748707;30073.54889676613;49755;49903.25145122754;50632.24051639302;50664.49335506598;50690.493095068574;616950;617309;619042;619329;621379;25517.61723574185;25908.740912591387;26178.476430471666;26185.476290474464;26276.8686156568;36417.453738194476;45318;45692.77153614211;46397.072058559315;46742;33487.49768753422;37812.62187378091;38376.6162338373;38665.97289328257;39097.02257443587;31154.844225778725;32393.51409728903;33754;34719.65280347265;34897.12757181091;24930.750692492842;30192.09423717292;31877.840610796797;36431.635683642824;36853;31248.750049997747;33394.33211335808;33608.83195584006;33717;33742;24572.754272457045;24787.008519659732;24878.89013873266;25419.237422877344;25647 06:05:50 INFO - PROCESS | 1662 | |2;jquery.html;341.75222963351575;357.8461261657488;365.3241503219044;378.9071677439028;383.8042598274879;18125.728114078283;18634;19171.520711982208;19664.50838729031;19890.403287901387;16742.828002039863;19538.511537211576;19577.923214223247;19805.207791688314;20185.09167087481;5600;5800.796972105977;5890.116482527612;5890.410958904118;5913.497352725023;4904.215325547913;5389.326334208224;5784.219130417403;5786.913196302059;5800.303963524362;5706.885862282752;5737.512311453511;5741.368449470555;5745.655260684352;5756.222909907172;5114.974425127888;5887.941120588782;5981.37195594465;5985.760569577212;6012.759489620454;4879.780409881564;5559.610827242094;5777.768889244425;5780.971095144498;5803.245578074865;12803.871961280454;13048.086633935629;18836.434906952814;20710.275140370166;24288;2110.6095372356162;2169.6853956176265;2294.300238427279;2402.027178992511;2754.504189245935;9827;9898;9923.057309555581;10420.478976051167;10459.320144190604;1740.712782390904;1776.404904357042;1789.168036862852;1825.6622524832949;1871.2234422714694;102864.48567757114;126768;127172.36413817965;127973;128624.35687821594;10158.17152912468;10698.732531686614;11092.33445993243;11205.655321361488;11318.60384886525;14014.719705606034;14058.22679752603;14060.296985150702;14070.155790652594;14116.22360770147;7198.208197098315;8085.676572937016;8232.917670823215;8245.711400100967;8301.460405073773;6911.5853048817235;8034.076081250731;8044;8138.511689298661;8145.959270203611;4325.848595299212;4359.455068116486;4416.6245869101485;4453.51011388747;4484.529124441952;1846.5291350705484;1915.8946257955668;1916.1951980168362;1919.5201199700075;1925.3261358524405;785.7878372839301;801.8757092650649;882.7925437522177;904.9547522623842;936.681528280388;19103.713444298348;20758;20792;20812.687809682866;21086.05112769929;798.6845196147489;809.9311558517592;906.428949761646;941.110650435332;976.4580657735025;8229.79425514367;8847.955760221157;9900.702978910644;10872.61945831892;10891.455427228766;588138;588316;596483;602308;602693;74373;114203;116299.41850290695;117813.23280150804;118262;924.5100096948628;957.5116690487902;971.7910649210454;973.5813600151861;1008.5713571731984;16179.59551011187;18091.81908180954;18959.957202353453;19004;19068;50637.74681126571;52459.47540524699;52704;53200;53593;71234;71604;71835.20411989742;72188.63905680439;73845.56926584423;5956;7720.53676779395;7840.1975042103395;8002;8072.152423995631;12411.62765117048;12418.75162496763;12432.254064756144;12760.4257808397;12833.486660533474;10329.535170917177;10907.127429805756;10942.56229750833;11009;11084.279521831055;10181.829089654488;10273.434961076913;10750;10783.946080269863;11138.220324577202;10922.50848711794;11084.61203857861;11232.831507527557;11270;11546.42267886619;7748.380129589506;7874.724384646739;7915.643796029078;8169.836603268021;8186.017677878693;10024.398536087858;10470;10476.214283928583;10720.624778132727;10763.94618026905 06:05:50 INFO - PROCESS | 1662 | |3;mootools.html;1418.7659036259017;1470.3677418709954;1498.7002599480104;1752.9298828046878;1787.5173703100163;7517.361024312926;7822.608869556528;8035.919640803598;8485.872711909326;8498.872516912243;8070.798230044252;8387.748367548968;8390.874136887951;8522.57387130644;8541.402101852873;2284.3489605462446;2322.1756276521846;2328.906843726249;2336.7429582745885;2337.649352597107;2319.8840057997118;2360.7639236076425;2363.1374548289837;2366.9171578994738;2371.513839662865;2306.6655334976417;2307.9423014424688;2333.5216280662507;2334.299710086967;2342.847714898526;2300.620397634388;2304.2741536416142;2327.0924339507606;2329.1032952313312;2332.265336419023;2326.685897403846;2342.2153578551206;2351.729551101628;2351.9647205291935;2353.85876847388;8472.195141461612;8505.872411913826;8564.571771411405;8568;8646.610902509403;4575.290829921368;5001.349824522788;5232.293640358541;5233.581313494911;5248.790048398059;3253.3655937092276;3395.439752440844;3515.7011654009184;3576.034470692898;3583.4624806279007;968.8546717992288;1004.6483730694199;1045.513836066233;1061.124572227915;1061.7080302916681;10995.450227488594;11157;11219.551217951272;11235.60675376366;11258.986691197832;4008.378701301303;4501;4597.494275629678;4699.976500117477;4702.623790096853;8750.299976002032;9295.698602195698;9473.336866419422;9561;9567.234621230285;5203.713795741271;5216.7913283469;5245.239440281138;5246.396664383645;5249.317588713443;4286.957130428656;4289.356596510548;4729.55069268419;4771.785269662873;4826.0830442215865;3123.6095488063993;3766.7928263945746;3831.8084095795098;3855.325318069327;3880.84476620932;1062.984055239172;1250.5435516036582;1274.9043821713408;1278.0861683895873;1280.788669869461;708.3377042465312;775.5656832173888;778.4122987144669;871.4684042733927;902.3863772634671;5329.866753331198;5593.272874526286;5693.544516438675;5734.7132643368495;5762.9135562966585;407.5048815688952;419.53431690823163;435.5296280017573;437.76579529951323;443.0120830547896;3155.8422078896424;3557.3418917500344;3692.390755525362;3725.1432170600915;3785.5835858055593;9857.605695772232;10145.847812282822;10252.846207306897;10295.073443389982;10300.278980471296;9588.56851441673;10142.797144057078;10156.085952264331;10208.336458130198;10231.181505479544;7750.0699916010435;7972.481788683717;7981.840363192588;7991;8002.119766825645;5685.658860468385;5722.942770572407;5743.10981797822;5788.855278618068;6054.515638748801;9412.294077944045;9571.856422153813;9585;9594.808103838024;9612.18296444782;9011.909880901107;9405.670801521914;9409;9561.282903782105;9607.951960240154;6413.903791443039;6498.837529061811;7077.893831592427;7262.419006479363;7595.088589369311;7779.883301750365;7804.648790804314;7976.7208147714555;7982.321502672338;7999.520028798291;6796.320367963362;6806;7099.538529995534;7242.529235599669;7279.4540409468445;6976.127984002;7013.333733295329;7071.257517960746;7083.893741593984;7203.135623725187;6540.50946179048;6709.698063587248;6763.019362192356;6777.423918966943;6831.863362732817;4756.381670382828;4881.877953051202;4887.584555312838;4939.629527785502;4992.176290911959;4818.951810481991;4833.3958255218095;4914.778834952364;4976.2286845539;5001.899962000667 06:05:50 INFO - PROCESS | 1662 | |4;prototype.html;260.65593416689967;278.3931030353829;299.8620634508126;307.47728860936405;311.8347275943748;5188.118019936611;5300.443453437381;5397.999999999995;5409;5425.864353391163;4640.814367425307;4841.91057012172;5272.710000949946;5316.946830531693;5326.3075800146025;3933.940990885139;4024.3359845625437;4052.4326594276827;4086.5300490443683;4097.487814023247;3600.0299752703995;3973.8609148679802;4097.446844675976;4112.128981313495;4112.97943510282;4044.979775101135;4057.918841623166;4063.674906007527;4072.816723247446;4075.8777236682945;3417.8037686809666;3544.521489598897;3874.883753487386;4035.0921042765435;4081.53064896637;3476.461148521983;3592.5868525119686;3595.7662751921043;3605.873794417209;3610.530631017977;4130.772807495617;4587.853036740815;4650.279396623271;5173.068847607429;5265.183896496049;4013.478247827793;4035.9910022494378;4635.188841952679;4637.489876113625;4658.4642766082;4744.122337367598;4940.962397896446;5350.411454739976;5638.859028524279;5689.6017278790505;3499.5450591423205;3780.0360907968293;3842.5773164951834;3923.7253392262555;3937.4684417603544;4823.565879070866;4933.481984391659;5590.785298453406;5595.692236926968;5869.647821130746;3177.3804108199142;3828.2917660232947;3840.4047372656723;3854.7642367426824;3878.864239751595;3721.10693433574;3766.4350347447553;3890.3386424307937;3892.1631849152;3909.3354129798004;3800.562935262425;4374.212641724456;4478.900942006891;4518.593326600621;4529.9547004530195;5161.870953226199;5191.714455704972;5366.7048312343195;5657.32274021889;5670.404607516234;1212.5149940024062;1231.7043909461843;1243.3410292544966;1253.7743206222785;1275.8851703346559;715.4455297144755;732.8021434212733;800.4236949396425;809.8461292354434;877.1929824561419;4827.303781537749;5086.3133476980875;5117.590592752571;5119.436861945183;5155.690658560498;4791.257355109963;4927.556519913153;5157.32954715892;5579.6652200868075;5612.943870561242;4446.510883802779;4689.186488107109;4730.858074257778;4831.154303292985;5165.483451654804;2924.0847245054524;2924.9707502925125;2929.882804687831;2936.119164250716;2937.427201695649;4726.834560790356;5236.790528378822;5704.4295570443455;5709.400512946164;5713.171590119409;4703.012367402884;5354.571634269249;5638.577106717013;5662.37713851476;5679.460451257124;4776.423780152541;5634.352049514277;5662.065759149694;5674.4892959633835;5717.514011309086;4924.556789888928;5539.648512649789;5822.417758224144;5836.270466191726;5860.413958604106;5312.2562841203035;5758.280214973129;5772.307323121252;5793.797217097382;5830.47525722687;5085.542301192911;5708.4862362387585;6007.3142948500235;6104.236970378703;6137.7851775187655;3211.823349715788;3301.5873015873017;3883.3398322285843;3884.2425726982965;3912.8630497932436;4735.763211839463;5753.683547404766;5877.589378549203;5904.261967254093;5917.3490915998345;4801.4958429364415;5200.297959775382;5653.067243904709;5768.798092066758;5774.9133762994425;4617.79219935097;5660.037793575019;5661.773529058956;5662.319492329853;5679.926826708304;4752.079534384078;5272.340957380327;5326.893462130658;5516.282883225155;5522.729772152347;4792.424909010941;5242.737863106753;5690.971545142248;5711.71621284037;5721.599488035964;4691.906161876812;4761.262004389325;5352.571794256528;5460.808871689472;5557.694326812064 06:05:50 INFO - PROCESS | 1662 | |5;yui.html;624.7961009559776;666.3270097201824;697.1111832413671;701.4353445476393;709.0109297529946;13616.229890114271;13874.306284685763;14328.28358582072;14522.854771452283;14773.852261477396;12526.624201273953;13622.523211687592;13682.726345473084;13686.452541898338;13712.862871371284;654.7937399719098;817.0358976407837;837.8701301298278;838.8070743728946;838.9973981092597;668.2548957911918;744.0773440933231;813.0243707550933;823.0904850140598;837.6858677995751;879.4811061473729;882.1839798186684;883.1918794303205;888.4979986307748;889.2219308105408;836.9455985360993;878.6748902905902;879.520661239626;879.7448739865496;880.6389380354057;577.413924866261;610.3743662745677;651.5048563092037;653.3923451190391;655.0959675647623;13477.595672129753;13743.587692369245;13886.166829990132;13892.23592702401;13924.234167120805;12890.87109128906;12964.416601252966;13129.934350328187;13336.533221337206;13420.597382078553;11172.217944743872;11298.774024519464;11343.26268792534;11378.146639002025;11629.011534019619;3603.459481077833;3613.703449715263;3619;3624.873129440457;3653.214558869856;77906;80354.59822700908;80538.79191812128;84567.5771621138;87884.56057719735;10491.842622360671;10546.103581195684;10557.577696892038;10607.94696026515;10829.566817327219;20051.899740501205;20055.899720501304;20059.498512537302;20060;20067.29764458266;6350.650714210763;7333.963330183315;7519.248075192437;7731.652075656609;7882.014748156481;4060.857869974596;4164.916701665949;4211.136716973013;4220.261454245556;4323.113761678911;3112.6887311268692;3183.4110689522513;3188.442022646074;3210.9357812843614;3219.1952011997;587.2776484923482;645.5287640022809;647.7441410642864;649.2955143669077;653.2552889705739;524.8031988004499;556.4852511426994;566.4109326300684;629.5876201088295;632.2349956552531;7917.3270272026375;8551.358648101417;8703.260222881127;8825.38222324444;8846.203841654282;523.6125267302245;584.6170758153417;624.5659266809614;625.3027873920637;627.6830200748593;3570.803605801706;4004.9199016019516;4301.397804307399;4357.455318085239;4390.040090327605;90792.54603726939;110567;111290.52415827198;111942.88771015972;112219.43391887796;88133.11866881377;90978;93372.19591942884;93668.5949710755;98010.50994745124;70089.24776880619;72658.63670681613;73425;74482.62758686172;78790.21209787944;9391.29565282593;9575.712728618153;9578.760530986781;9586.808263834544;9589.280803939593;41673.79163104165;45459.772701137605;50917.47247582579;52788.736056319474;53156.93686126178;50558.74720626373;56028;71266;72942;75111.62444187744;11979.221350612183;12065.638030859089;12185.952139404273;12271.325077120844;12368.381580921097;13584.660383490493;13800.516981905585;13871.861281387057;14194.574162775134;14470.348834302271;10724.302920310154;12846.036547258806;12990.740185196431;13461.86538134606;13536.59390218295;12512.124151309685;12799.680007999874;12835.073275969102;12847;13073;11920.818355564606;12052.09609279325;12159.696007599527;12460.12779105454;12518.624441266777;10713.250072494851;10766.354018758899;10820;11085.445727713743;11170.664880053611;10513.421761802869;10544.57821687124;10937.343759374146;10939.23425360249;10958.671239862817 06:05:50 INFO - PROCESS | 1662 | __end_tp_report 06:05:50 INFO - PROCESS | 1662 | __start_cc_report 06:05:50 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,375 06:05:50 INFO - PROCESS | 1662 | __end_cc_report 06:05:50 INFO - PROCESS | 1662 | __startTimestamp1478786750046__endTimestamp 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | ------- Summary: start ------- 06:05:50 INFO - PROCESS | 1662 | Number of tests: 6 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5635.83 Median:5993.46 stddev:807.01 (13.5%) stddev-sans-first:777.69 06:05:50 INFO - PROCESS | 1662 | Values: 2776.8 3377.5 3379.2 3396.6 3430.1 4593.3 5689.7 5728.9 5738.9 5764.3 4793.8 5559.9 5642.6 5683.5 5722.8 5118.7 5139.9 5177.2 5236.1 5273.5 5192.1 5239.7 5270.8 5285.9 5310.9 198.0 5206.4 5234.3 5270.6 5295.7 4214.3 4321.4 5061.9 5102.1 5105.6 5022.9 5061.1 5080.6 5082.5 5114.8 4651.0 5424.5 5547.7 5602.9 5616.4 5092.6 6112.6 6113.8 6126.8 6144.9 6263.2 6265.9 6266.5 6275.9 6292.0 5866.3 5879.7 5885.8 5920.3 5924.0 6107.4 6122.0 6133.3 6232.3 6264.6 6076.1 6111.1 6153.3 6160.9 6203.1 6135.8 6189.7 6195.0 6206.6 6223.3 6085.5 6135.8 6172.0 6174.9 6188.2 6146.3 6181.6 6233.3 6260.4 6260.6 4768.1 4805.6 4851.8 4923.0 4952.7 4349.5 4379.7 4407.3 4420.2 4422.7 5444.1 5687.4 5738.7 5795.9 5820.6 6179.1 6214.7 6226.8 6238.4 6261.6 4953.0 4954.5 5133.4 5845.4 5919.6 5315.0 5427.8 5447.7 5454.1 5529.4 6135.5 6152.6 6228.8 6260.8 6270.0 6020.7 6048.5 6051.4 6055.3 6064.9 6242.0 6253.4 6296.5 6301.0 6320.4 5066.6 6059.2 6111.5 6163.3 6248.5 6188.7 6209.5 6255.9 6261.4 6261.8 5500.5 5969.8 6150.7 6158.5 6161.9 6078.0 6158.7 6173.0 6194.6 6220.6 5720.5 5766.3 5922.9 6008.0 6029.0 6069.5 6086.9 6097.1 6135.4 6137.2 5993.5 6040.8 6047.9 6082.1 6092.0 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:71056.83 Median:21419.52 stddev:159710.00 (745.6%) stddev-sans-first:160070.07 06:05:50 INFO - PROCESS | 1662 | Values: 460.0 466.2 485.4 515.1 547.5 35827.0 38959.6 39027.4 39147.0 39185.8 35221.6 39898.8 39947.8 40033.4 40124.8 8991.7 9141.1 9172.1 9188.9 9192.0 9146.6 9244.3 9250.2 9251.5 9259.9 9223.4 9239.4 9248.9 9252.0 9282.6 9197.4 9220.3 9239.4 9240.1 9254.9 8996.3 9082.2 9085.0 9086.6 9095.2 37156.4 37388.6 37420.6 37440.4 37481.0 3197.7 3379.5 3648.7 3879.4 3911.9 29035.0 29322.7 29525.6 29713.7 30224.0 3197.9 3201.1 3203.5 3265.4 3301.5 427164.9 487006.0 498381.0 502554.0 508236.0 58838.4 59002.0 59055.7 59357.7 60018.7 61289.0 61925.7 61996.0 62052.7 62361.4 15521.9 15639.7 15789.5 15822.5 15842.5 11447.9 11832.4 12167.8 12187.9 12206.8 16319.8 16348.4 16414.6 16497.0 16508.3 5763.5 6375.6 6410.8 6419.0 6431.8 710.6 848.0 945.9 953.3 970.7 29420.9 29511.6 30049.2 30199.5 30205.0 1039.2 1041.2 1052.2 1069.0 1081.8 15305.5 15373.8 15374.5 15488.6 15721.7 663469.7 665873.0 666267.0 673024.0 673116.0 56428.7 56886.4 57447.4 57468.7 57720.0 1314.4 1328.1 1348.5 1353.4 1358.1 29254.6 29290.6 29430.9 29953.9 30073.5 49755.0 49903.3 50632.2 50664.5 50690.5 616950.0 617309.0 619042.0 619329.0 621379.0 25517.6 25908.7 26178.5 26185.5 26276.9 36417.5 45318.0 45692.8 46397.1 46742.0 33487.5 37812.6 38376.6 38666.0 39097.0 31154.8 32393.5 33754.0 34719.7 34897.1 24930.8 30192.1 31877.8 36431.6 36853.0 31248.8 33394.3 33608.8 33717.0 33742.0 24572.8 24787.0 24878.9 25419.2 25647.0 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33889.24 Median:361.59 stddev:99150.33 (27421.0%) stddev-sans-first:99396.47 06:05:50 INFO - PROCESS | 1662 | Values: 341.8 357.8 365.3 378.9 383.8 18125.7 18634.0 19171.5 19664.5 19890.4 16742.8 19538.5 19577.9 19805.2 20185.1 5600.0 5800.8 5890.1 5890.4 5913.5 4904.2 5389.3 5784.2 5786.9 5800.3 5706.9 5737.5 5741.4 5745.7 5756.2 5115.0 5887.9 5981.4 5985.8 6012.8 4879.8 5559.6 5777.8 5781.0 5803.2 12803.9 13048.1 18836.4 20710.3 24288.0 2110.6 2169.7 2294.3 2402.0 2754.5 9827.0 9898.0 9923.1 10420.5 10459.3 1740.7 1776.4 1789.2 1825.7 1871.2 102864.5 126768.0 127172.4 127973.0 128624.4 10158.2 10698.7 11092.3 11205.7 11318.6 14014.7 14058.2 14060.3 14070.2 14116.2 7198.2 8085.7 8232.9 8245.7 8301.5 6911.6 8034.1 8044.0 8138.5 8146.0 4325.8 4359.5 4416.6 4453.5 4484.5 1846.5 1915.9 1916.2 1919.5 1925.3 785.8 801.9 882.8 905.0 936.7 19103.7 20758.0 20792.0 20812.7 21086.1 798.7 809.9 906.4 941.1 976.5 8229.8 8848.0 9900.7 10872.6 10891.5 588138.0 588316.0 596483.0 602308.0 602693.0 74373.0 114203.0 116299.4 117813.2 118262.0 924.5 957.5 971.8 973.6 1008.6 16179.6 18091.8 18960.0 19004.0 19068.0 50637.7 52459.5 52704.0 53200.0 53593.0 71234.0 71604.0 71835.2 72188.6 73845.6 5956.0 7720.5 7840.2 8002.0 8072.2 12411.6 12418.8 12432.3 12760.4 12833.5 10329.5 10907.1 10942.6 11009.0 11084.3 10181.8 10273.4 10750.0 10783.9 11138.2 10922.5 11084.6 11232.8 11270.0 11546.4 7748.4 7874.7 7915.6 8169.8 8186.0 10024.4 10470.0 10476.2 10720.6 10763.9 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5456.61 Median:4984.20 stddev:3046.03 (61.1%) stddev-sans-first:3039.46 06:05:50 INFO - PROCESS | 1662 | Values: 1418.8 1470.4 1498.7 1752.9 1787.5 7517.4 7822.6 8035.9 8485.9 8498.9 8070.8 8387.7 8390.9 8522.6 8541.4 2284.3 2322.2 2328.9 2336.7 2337.6 2319.9 2360.8 2363.1 2366.9 2371.5 2306.7 2307.9 2333.5 2334.3 2342.8 2300.6 2304.3 2327.1 2329.1 2332.3 2326.7 2342.2 2351.7 2352.0 2353.9 8472.2 8505.9 8564.6 8568.0 8646.6 4575.3 5001.3 5232.3 5233.6 5248.8 3253.4 3395.4 3515.7 3576.0 3583.5 968.9 1004.6 1045.5 1061.1 1061.7 10995.5 11157.0 11219.6 11235.6 11259.0 4008.4 4501.0 4597.5 4700.0 4702.6 8750.3 9295.7 9473.3 9561.0 9567.2 5203.7 5216.8 5245.2 5246.4 5249.3 4287.0 4289.4 4729.6 4771.8 4826.1 3123.6 3766.8 3831.8 3855.3 3880.8 1063.0 1250.5 1274.9 1278.1 1280.8 708.3 775.6 778.4 871.5 902.4 5329.9 5593.3 5693.5 5734.7 5762.9 407.5 419.5 435.5 437.8 443.0 3155.8 3557.3 3692.4 3725.1 3785.6 9857.6 10145.8 10252.8 10295.1 10300.3 9588.6 10142.8 10156.1 10208.3 10231.2 7750.1 7972.5 7981.8 7991.0 8002.1 5685.7 5722.9 5743.1 5788.9 6054.5 9412.3 9571.9 9585.0 9594.8 9612.2 9011.9 9405.7 9409.0 9561.3 9608.0 6413.9 6498.8 7077.9 7262.4 7595.1 7779.9 7804.6 7976.7 7982.3 7999.5 6796.3 6806.0 7099.5 7242.5 7279.5 6976.1 7013.3 7071.3 7083.9 7203.1 6540.5 6709.7 6763.0 6777.4 6831.9 4756.4 4881.9 4887.6 4939.6 4992.2 4819.0 4833.4 4914.8 4976.2 5001.9 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4459.14 Median:4829.23 stddev:1377.68 (28.5%) stddev-sans-first:1345.02 06:05:50 INFO - PROCESS | 1662 | Values: 260.7 278.4 299.9 307.5 311.8 5188.1 5300.4 5398.0 5409.0 5425.9 4640.8 4841.9 5272.7 5316.9 5326.3 3933.9 4024.3 4052.4 4086.5 4097.5 3600.0 3973.9 4097.4 4112.1 4113.0 4045.0 4057.9 4063.7 4072.8 4075.9 3417.8 3544.5 3874.9 4035.1 4081.5 3476.5 3592.6 3595.8 3605.9 3610.5 4130.8 4587.9 4650.3 5173.1 5265.2 4013.5 4036.0 4635.2 4637.5 4658.5 4744.1 4941.0 5350.4 5638.9 5689.6 3499.5 3780.0 3842.6 3923.7 3937.5 4823.6 4933.5 5590.8 5595.7 5869.6 3177.4 3828.3 3840.4 3854.8 3878.9 3721.1 3766.4 3890.3 3892.2 3909.3 3800.6 4374.2 4478.9 4518.6 4530.0 5161.9 5191.7 5366.7 5657.3 5670.4 1212.5 1231.7 1243.3 1253.8 1275.9 715.4 732.8 800.4 809.8 877.2 4827.3 5086.3 5117.6 5119.4 5155.7 4791.3 4927.6 5157.3 5579.7 5612.9 4446.5 4689.2 4730.9 4831.2 5165.5 2924.1 2925.0 2929.9 2936.1 2937.4 4726.8 5236.8 5704.4 5709.4 5713.2 4703.0 5354.6 5638.6 5662.4 5679.5 4776.4 5634.4 5662.1 5674.5 5717.5 4924.6 5539.6 5822.4 5836.3 5860.4 5312.3 5758.3 5772.3 5793.8 5830.5 5085.5 5708.5 6007.3 6104.2 6137.8 3211.8 3301.6 3883.3 3884.2 3912.9 4735.8 5753.7 5877.6 5904.3 5917.3 4801.5 5200.3 5653.1 5768.8 5774.9 4617.8 5660.0 5661.8 5662.3 5679.9 4752.1 5272.3 5326.9 5516.3 5522.7 4792.4 5242.7 5691.0 5711.7 5721.6 4691.9 4761.3 5352.6 5460.8 5557.7 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19433.39 Median:4260.83 stddev:28141.07 (660.5%) stddev-sans-first:28184.57 06:05:50 INFO - PROCESS | 1662 | Values: 624.8 666.3 697.1 701.4 709.0 13616.2 13874.3 14328.3 14522.9 14773.9 12526.6 13622.5 13682.7 13686.5 13712.9 654.8 817.0 837.9 838.8 839.0 668.3 744.1 813.0 823.1 837.7 879.5 882.2 883.2 888.5 889.2 836.9 878.7 879.5 879.7 880.6 577.4 610.4 651.5 653.4 655.1 13477.6 13743.6 13886.2 13892.2 13924.2 12890.9 12964.4 13129.9 13336.5 13420.6 11172.2 11298.8 11343.3 11378.1 11629.0 3603.5 3613.7 3619.0 3624.9 3653.2 77906.0 80354.6 80538.8 84567.6 87884.6 10491.8 10546.1 10557.6 10607.9 10829.6 20051.9 20055.9 20059.5 20060.0 20067.3 6350.7 7334.0 7519.2 7731.7 7882.0 4060.9 4164.9 4211.1 4220.3 4323.1 3112.7 3183.4 3188.4 3210.9 3219.2 587.3 645.5 647.7 649.3 653.3 524.8 556.5 566.4 629.6 632.2 7917.3 8551.4 8703.3 8825.4 8846.2 523.6 584.6 624.6 625.3 627.7 3570.8 4004.9 4301.4 4357.5 4390.0 90792.5 110567.0 111290.5 111942.9 112219.4 88133.1 90978.0 93372.2 93668.6 98010.5 70089.2 72658.6 73425.0 74482.6 78790.2 9391.3 9575.7 9578.8 9586.8 9589.3 41673.8 45459.8 50917.5 52788.7 53156.9 50558.7 56028.0 71266.0 72942.0 75111.6 11979.2 12065.6 12186.0 12271.3 12368.4 13584.7 13800.5 13871.9 14194.6 14470.3 10724.3 12846.0 12990.7 13461.9 13536.6 12512.1 12799.7 12835.1 12847.0 13073.0 11920.8 12052.1 12159.7 12460.1 12518.6 10713.3 10766.4 10820.0 11085.4 11170.7 10513.4 10544.6 10937.3 10939.2 10958.7 06:05:50 INFO - PROCESS | 1662 | -------- Summary: end -------- 06:05:50 INFO - PROCESS | 1662 | 06:05:50 INFO - TEST-INFO | 1662: exit 0 06:05:50 INFO - TEST-OK | dromaeo_css | took 1115411ms 06:05:50 INFO - TEST-START | kraken 06:05:50 INFO - operating with platform_type : mac_ 06:05:50 INFO - Initialising browser for kraken test... 06:05:50 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmplnWFsB/profile http://localhost:49280/getInfo.html) 06:05:53 INFO - PROCESS | 1671 | 2016-11-10 06:05:53.682 plugin-container[1672:10125] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 06:05:53 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:53 INFO - PROCESS | 1671 | 2016-11-10 06:05:53.683 plugin-container[1672:10125] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 06:05:53 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:53 INFO - PROCESS | 1671 | 2016-11-10 06:05:53.683 plugin-container[1672:10125] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:05:53 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 06:05:53 INFO - PROCESS | 1671 | colorDepth:24 06:05:53 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 06:05:53 INFO - PROCESS | 1671 | __metrics 06:05:53 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 06:05:54 INFO - TEST-INFO | 1671: exit 0 06:05:54 INFO - Browser initialized. 06:05:54 INFO - Running cycle 1/1 for kraken test... 06:05:54 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmplnWFsB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:57 INFO - PROCESS | 1673 | 2016-11-10 06:05:57.173 plugin-container[1674:10249] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 06:05:57 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:57 INFO - PROCESS | 1673 | 2016-11-10 06:05:57.174 plugin-container[1674:10249] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 06:05:57 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:57 INFO - PROCESS | 1673 | 2016-11-10 06:05:57.174 plugin-container[1674:10249] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:06:06 INFO - PROCESS | 1673 | RSS: Main: 237355008 06:06:06 INFO - PROCESS | 1673 | 06:06:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 06:06:10 INFO - PROCESS | 1673 | RSS: Main: 249851904 06:06:10 INFO - PROCESS | 1673 | 06:06:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 06:06:14 INFO - PROCESS | 1673 | RSS: Main: 257511424 06:06:14 INFO - PROCESS | 1673 | 06:06:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 06:06:18 INFO - PROCESS | 1673 | RSS: Main: 260308992 06:06:18 INFO - PROCESS | 1673 | 06:06:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 06:06:21 INFO - PROCESS | 1673 | RSS: Main: 262492160 06:06:21 INFO - PROCESS | 1673 | 06:06:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:06:23 INFO - PROCESS | 1673 | RSS: Main: 262737920 06:06:23 INFO - PROCESS | 1673 | 06:06:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:27 INFO - PROCESS | 1673 | RSS: Main: 263069696 06:06:27 INFO - PROCESS | 1673 | 06:06:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:30 INFO - PROCESS | 1673 | RSS: Main: 263323648 06:06:30 INFO - PROCESS | 1673 | 06:06:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 06:06:34 INFO - PROCESS | 1673 | RSS: Main: 263356416 06:06:34 INFO - PROCESS | 1673 | 06:06:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:36 INFO - PROCESS | 1673 | RSS: Main: 263217152 06:06:36 INFO - PROCESS | 1673 | 06:06:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:39 INFO - PROCESS | 1673 | RSS: Main: 263282688 06:06:39 INFO - PROCESS | 1673 | 06:06:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:41 INFO - PROCESS | 1673 | RSS: Main: 263315456 06:06:41 INFO - PROCESS | 1673 | 06:06:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:45 INFO - PROCESS | 1673 | RSS: Main: 263630848 06:06:45 INFO - PROCESS | 1673 | 06:06:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:49 INFO - PROCESS | 1673 | RSS: Main: 263475200 06:06:49 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 06:06:51 INFO - PROCESS | 1673 | RSS: Main: 265113600 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | __start_tp_report 06:06:51 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 06:06:51 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 06:06:51 INFO - PROCESS | 1673 | |i|pagename|runs| 06:06:51 INFO - PROCESS | 1673 | |0;ai-astar;106;118;119;119;117;118;119;117;114;118 06:06:51 INFO - PROCESS | 1673 | |1;audio-beat-detection;159;157;146;168;121;105;111;133;179;102 06:06:51 INFO - PROCESS | 1673 | |2;audio-dft;205;245;260;245;273;199;280;195;192;189 06:06:51 INFO - PROCESS | 1673 | |3;audio-fft;82;58;59;75;83;51;73;84;104;85 06:06:51 INFO - PROCESS | 1673 | |4;audio-oscillator;89;72;88;74;72;72;77;92;100;121 06:06:51 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;90;76;135;127;89;86;90;88;92;86 06:06:51 INFO - PROCESS | 1673 | |6;imaging-darkroom;98;115;99;99;132;120;100;98;130;98 06:06:51 INFO - PROCESS | 1673 | |7;imaging-desaturate;130;91;88;134;87;90;91;88;88;88 06:06:51 INFO - PROCESS | 1673 | |8;json-parse-financial;57;58;57;56;55;55;56;60;58;58 06:06:51 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;71;74;74;85;80;72;72;73;78;103 06:06:51 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;93;92;100;92;90;90;90;89;88;89 06:06:51 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;159;162;147;152;151;160;160;167;198;153 06:06:51 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;175;177;179;215;224;239;192;176;173;174 06:06:51 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;73;95;90;88;92;76;68;70;71;67 06:06:51 INFO - PROCESS | 1673 | __end_tp_report 06:06:51 INFO - PROCESS | 1673 | __start_cc_report 06:06:51 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,967 06:06:51 INFO - PROCESS | 1673 | __end_cc_report 06:06:51 INFO - PROCESS | 1673 | __startTimestamp1478786811576__endTimestamp 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | ------- Summary: start ------- 06:06:51 INFO - PROCESS | 1673 | Number of tests: 14 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:3.98 (3.4%) stddev-sans-first:1.58 06:06:51 INFO - PROCESS | 1673 | Values: 106.0 118.0 119.0 119.0 117.0 118.0 119.0 117.0 114.0 118.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:151.50 stddev:27.64 (18.2%) stddev-sans-first:28.26 06:06:51 INFO - PROCESS | 1673 | Values: 159.0 157.0 146.0 168.0 121.0 105.0 111.0 133.0 179.0 102.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:228.30 Median:245.00 stddev:35.91 (14.7%) stddev-sans-first:37.09 06:06:51 INFO - PROCESS | 1673 | Values: 205.0 245.0 260.0 245.0 273.0 199.0 280.0 195.0 192.0 189.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:75.40 Median:78.50 stddev:15.84 (20.2%) stddev-sans-first:16.62 06:06:51 INFO - PROCESS | 1673 | Values: 82.0 58.0 59.0 75.0 83.0 51.0 73.0 84.0 104.0 85.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:75.50 stddev:15.92 (21.1%) stddev-sans-first:16.84 06:06:51 INFO - PROCESS | 1673 | Values: 89.0 72.0 88.0 74.0 72.0 72.0 77.0 92.0 100.0 121.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:88.50 stddev:19.10 (21.6%) stddev-sans-first:20.14 06:06:51 INFO - PROCESS | 1673 | Values: 90.0 76.0 135.0 127.0 89.0 86.0 90.0 88.0 92.0 86.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:98.00 stddev:14.03 (14.3%) stddev-sans-first:14.31 06:06:51 INFO - PROCESS | 1673 | Values: 98.0 115.0 99.0 99.0 132.0 120.0 100.0 98.0 130.0 98.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:88.00 stddev:18.26 (20.7%) stddev-sans-first:15.11 06:06:51 INFO - PROCESS | 1673 | Values: 130.0 91.0 88.0 134.0 87.0 90.0 91.0 88.0 88.0 88.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.50 stddev:1.56 (2.7%) stddev-sans-first:1.66 06:06:51 INFO - PROCESS | 1673 | Values: 57.0 58.0 57.0 56.0 55.0 55.0 56.0 60.0 58.0 58.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:74.00 stddev:9.75 (13.2%) stddev-sans-first:9.99 06:06:51 INFO - PROCESS | 1673 | Values: 71.0 74.0 74.0 85.0 80.0 72.0 72.0 73.0 78.0 103.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:90.00 stddev:3.43 (3.8%) stddev-sans-first:3.59 06:06:51 INFO - PROCESS | 1673 | Values: 93.0 92.0 100.0 92.0 90.0 90.0 90.0 89.0 88.0 89.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:160.90 Median:160.00 stddev:14.35 (9.0%) stddev-sans-first:15.20 06:06:51 INFO - PROCESS | 1673 | Values: 159.0 162.0 147.0 152.0 151.0 160.0 160.0 167.0 198.0 153.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.40 Median:185.50 stddev:24.46 (13.2%) stddev-sans-first:25.12 06:06:51 INFO - PROCESS | 1673 | Values: 175.0 177.0 179.0 215.0 224.0 239.0 192.0 176.0 173.0 174.0 06:06:51 INFO - PROCESS | 1673 | 06:06:51 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.00 Median:82.00 stddev:10.96 (13.4%) stddev-sans-first:11.41 06:06:51 INFO - PROCESS | 1673 | Values: 73.0 95.0 90.0 88.0 92.0 76.0 68.0 70.0 71.0 67.0 06:06:51 INFO - PROCESS | 1673 | -------- Summary: end -------- 06:06:51 INFO - PROCESS | 1673 | 06:06:52 INFO - TEST-INFO | 1673: exit 0 06:06:52 INFO - TEST-OK | kraken | took 61791ms 06:06:52 INFO - SUITE-END | took 1177s 06:06:52 INFO - Completed test suite (00:19:37) 06:06:52 INFO - javascript benchmark 06:06:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7025.430780126413, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2776.819506732062, 3377.5102610121535, 3379.239671074007, 3396.60938992016, 3430.056734398041, 4593.265077587586, 5689.658620482774, 5728.94014607298, 5738.856528586787, 5764.25064741583, 4793.808247670089, 5559.8888022239535, 5642.605017648766, 5683.5169010634145, 5722.828315150542, 5118.692878427288, 5139.922901156486, 5177.249298851683, 5236.136037553799, 5273.472652734734, 5192.091384007803, 5239.738013099349, 5270.762815673284, 5285.920711189316, 5310.867228319285, 198.03289952347723, 5206.37523497179, 5234.293370394986, 5270.552003079723, 5295.659336544938, 4214.346776249687, 4321.373400856858, 5061.949380506185, 5102.056119617882, 5105.591552675777, 5022.924656130161, 5061.114304996648, 5080.644354895159, 5082.542571168576, 5114.769835357418, 4650.976745116287, 5424.457554244584, 5547.72261386933, 5602.887942241173, 5616.382197958222, 5092.618053645954, 6112.5946701277735, 6113.755449782004, 6126.761967422298, 6144.893919094561, 6263.185785847811, 6265.906011409833, 6266.467350275187, 6275.8744825103695, 6292.024736165901, 5866.296044474648, 5879.709609450697, 5885.79399721012, 5920.31916329619, 5924.022536281508, 6107.370879189147, 6122.032243765608, 6133.334663918993, 6232.3144454110015, 6264.59280146789, 6076.058210977306, 6111.083337499411, 6153.323134455206, 6160.9383906160365, 6203.100550420164, 6135.754569817246, 6189.690515474208, 6195.0, 6206.596571222856, 6223.253209614784, 6085.5435842312, 6135.753292002367, 6172.0, 6174.876502470015, 6188.226471691039, 6146.309764814622, 6181.567290289727, 6233.34549872266, 6260.373962603794, 6260.561760676709, 4768.1417344878255, 4805.567498925114, 4851.781669824866, 4922.9753851231235, 4952.702837829742, 4349.499807522112, 4379.715318504287, 4407.272799987966, 4420.160169567772, 4422.690411671217, 5444.128939369682, 5687.40282270364, 5738.655680659173, 5795.884082318415, 5820.592558520948, 6179.134921111048, 6214.720337584819, 6226.813195604139, 6238.438540531374, 6261.5616906816995, 4953.0, 4954.504549545089, 5133.435322114565, 5845.386234445322, 5919.6152250103605, 5314.990151871132, 5427.755750991215, 5447.727613619382, 5454.10007348783, 5529.447055294519, 6135.5082808396355, 6152.600080994721, 6228.781992630236, 6260.843478913063, 6269.96865015672, 6020.698965051817, 6048.455638992512, 6051.364606716407, 6055.303640081447, 6064.939350606437, 6241.96879015602, 6253.437190652863, 6296.464800492009, 6301.0, 6320.399562041599, 5066.645334826527, 6059.242594675666, 6111.480524155497, 6163.291221509405, 6248.5313601479165, 6188.659623720739, 6209.534284928739, 6255.906161407674, 6261.405166509175, 6261.812145635638, 5500.50495455411, 5969.835882003142, 6150.723217455315, 6158.538109641884, 6161.938380616137, 6077.969610151921, 6158.661273629814, 6173.0, 6194.566380353332, 6220.595661282002, 5720.48515633595, 5766.308043034863, 5922.851928701644, 6007.969960150172, 6029.0, 6069.453749162597, 6086.878262434638, 6097.085437184458, 6135.355787642234, 6137.202163718688, 5993.460588547052, 6040.75836966534, 6047.909281360871, 6082.087686847009, 6092.0], "value": 5585.498051536894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.99032124486746, 466.1609103613496, 485.38598672679046, 515.0574448758771, 547.5236544206541, 35827.0, 38959.61040389595, 39027.414588781154, 39147.0, 39185.80407097968, 35221.64778352216, 39898.80050599743, 39947.80026099866, 40033.39949900754, 40124.79937600315, 8991.730248092552, 9141.131592498701, 9172.082791720808, 9188.8621670675, 9192.034836342187, 9146.588403521824, 9244.306676999218, 9250.167484926355, 9251.53742312885, 9259.907400926006, 9223.354365194438, 9239.445633261992, 9248.907510924872, 9252.0, 9282.582283797245, 9197.448153110801, 9220.262379009662, 9239.399439036508, 9240.122188392126, 9254.907450925539, 8996.280297576179, 9082.228010619105, 9084.954575227148, 9086.591103400362, 9095.181433671001, 37156.44265336022, 37388.626113738785, 37420.62579374199, 37440.43839342412, 37481.0, 3197.7441804655805, 3379.509971054208, 3648.7263455240964, 3879.437481565157, 3911.8826435206706, 29034.983775567754, 29322.706772932423, 29525.557116643267, 29713.70286297131, 30224.0, 3197.888073917425, 3201.119692084672, 3203.5034569641743, 3265.4122257993554, 3301.4882693182594, 427164.86417568027, 487006.0, 498381.0, 502554.0, 508236.0, 58838.41161588329, 59002.0, 59055.70472147612, 59357.70321148453, 60018.69990650106, 61289.0, 61925.690371547855, 61996.0, 62052.68973655103, 62361.376386236465, 15521.922390387976, 15639.68720625581, 15789.526314210592, 15822.52532424029, 15842.52472425829, 11447.942760286312, 11832.408379580986, 12167.817482737766, 12187.939060304641, 12206.75586488283, 16319.755203671955, 16348.427805026768, 16414.589635259214, 16497.0, 16508.266632003466, 5763.452472015152, 6375.553711240169, 6410.8076757697345, 6419.042192131351, 6431.839204019937, 710.6446776611695, 847.9660813567389, 945.9101385368378, 953.3231405702029, 970.7427531704104, 29420.852895735385, 29511.55732664012, 30049.248768780955, 30199.547006794917, 30205.0, 1039.2361614213546, 1041.1722680469045, 1052.1950707708609, 1068.962586309491, 1081.7511972246427, 15305.46430874914, 15373.769393459108, 15374.461893833663, 15488.567039064419, 15721.68556628861, 663469.6826515837, 665873.0, 666267.0, 673024.0, 673116.0, 56428.717856410454, 56886.43113568894, 57447.42552574504, 57468.71265643645, 57720.0, 1314.3559655768604, 1328.110166188656, 1348.4740951029303, 1353.3706826325608, 1358.0900796466194, 29254.561181581867, 29290.56064159082, 29430.852845735633, 29953.850230748707, 30073.54889676613, 49755.0, 49903.25145122754, 50632.24051639302, 50664.49335506598, 50690.493095068574, 616950.0, 617309.0, 619042.0, 619329.0, 621379.0, 25517.61723574185, 25908.740912591387, 26178.476430471666, 26185.476290474464, 26276.8686156568, 36417.453738194476, 45318.0, 45692.77153614211, 46397.072058559315, 46742.0, 33487.49768753422, 37812.62187378091, 38376.6162338373, 38665.97289328257, 39097.02257443587, 31154.844225778725, 32393.51409728903, 33754.0, 34719.65280347265, 34897.12757181091, 24930.750692492842, 30192.09423717292, 31877.840610796797, 36431.635683642824, 36853.0, 31248.750049997747, 33394.33211335808, 33608.83195584006, 33717.0, 33742.0, 24572.754272457045, 24787.008519659732, 24878.89013873266, 25419.237422877344, 25647.0], "value": 19235.63498869008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.75222963351575, 357.8461261657488, 365.3241503219044, 378.9071677439028, 383.8042598274879, 18125.728114078283, 18634.0, 19171.520711982208, 19664.50838729031, 19890.403287901387, 16742.828002039863, 19538.511537211576, 19577.923214223247, 19805.207791688314, 20185.09167087481, 5600.0, 5800.796972105977, 5890.116482527612, 5890.410958904118, 5913.497352725023, 4904.215325547913, 5389.326334208224, 5784.219130417403, 5786.913196302059, 5800.303963524362, 5706.885862282752, 5737.512311453511, 5741.368449470555, 5745.655260684352, 5756.222909907172, 5114.974425127888, 5887.941120588782, 5981.37195594465, 5985.760569577212, 6012.759489620454, 4879.780409881564, 5559.610827242094, 5777.768889244425, 5780.971095144498, 5803.245578074865, 12803.871961280454, 13048.086633935629, 18836.434906952814, 20710.275140370166, 24288.0, 2110.6095372356162, 2169.6853956176265, 2294.300238427279, 2402.027178992511, 2754.504189245935, 9827.0, 9898.0, 9923.057309555581, 10420.478976051167, 10459.320144190604, 1740.712782390904, 1776.404904357042, 1789.168036862852, 1825.6622524832949, 1871.2234422714694, 102864.48567757114, 126768.0, 127172.36413817965, 127973.0, 128624.35687821594, 10158.17152912468, 10698.732531686614, 11092.33445993243, 11205.655321361488, 11318.60384886525, 14014.719705606034, 14058.22679752603, 14060.296985150702, 14070.155790652594, 14116.22360770147, 7198.208197098315, 8085.676572937016, 8232.917670823215, 8245.711400100967, 8301.460405073773, 6911.5853048817235, 8034.076081250731, 8044.0, 8138.511689298661, 8145.959270203611, 4325.848595299212, 4359.455068116486, 4416.6245869101485, 4453.51011388747, 4484.529124441952, 1846.5291350705484, 1915.8946257955668, 1916.1951980168362, 1919.5201199700075, 1925.3261358524405, 785.7878372839301, 801.8757092650649, 882.7925437522177, 904.9547522623842, 936.681528280388, 19103.713444298348, 20758.0, 20792.0, 20812.687809682866, 21086.05112769929, 798.6845196147489, 809.9311558517592, 906.428949761646, 941.110650435332, 976.4580657735025, 8229.79425514367, 8847.955760221157, 9900.702978910644, 10872.61945831892, 10891.455427228766, 588138.0, 588316.0, 596483.0, 602308.0, 602693.0, 74373.0, 114203.0, 116299.41850290695, 117813.23280150804, 118262.0, 924.5100096948628, 957.5116690487902, 971.7910649210454, 973.5813600151861, 1008.5713571731984, 16179.59551011187, 18091.81908180954, 18959.957202353453, 19004.0, 19068.0, 50637.74681126571, 52459.47540524699, 52704.0, 53200.0, 53593.0, 71234.0, 71604.0, 71835.20411989742, 72188.63905680439, 73845.56926584423, 5956.0, 7720.53676779395, 7840.1975042103395, 8002.0, 8072.152423995631, 12411.62765117048, 12418.75162496763, 12432.254064756144, 12760.4257808397, 12833.486660533474, 10329.535170917177, 10907.127429805756, 10942.56229750833, 11009.0, 11084.279521831055, 10181.829089654488, 10273.434961076913, 10750.0, 10783.946080269863, 11138.220324577202, 10922.50848711794, 11084.61203857861, 11232.831507527557, 11270.0, 11546.42267886619, 7748.380129589506, 7874.724384646739, 7915.643796029078, 8169.836603268021, 8186.017677878693, 10024.398536087858, 10470.0, 10476.214283928583, 10720.624778132727, 10763.94618026905], "value": 9151.73686507337, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.7659036259017, 1470.3677418709954, 1498.7002599480104, 1752.9298828046878, 1787.5173703100163, 7517.361024312926, 7822.608869556528, 8035.919640803598, 8485.872711909326, 8498.872516912243, 8070.798230044252, 8387.748367548968, 8390.874136887951, 8522.57387130644, 8541.402101852873, 2284.3489605462446, 2322.1756276521846, 2328.906843726249, 2336.7429582745885, 2337.649352597107, 2319.8840057997118, 2360.7639236076425, 2363.1374548289837, 2366.9171578994738, 2371.513839662865, 2306.6655334976417, 2307.9423014424688, 2333.5216280662507, 2334.299710086967, 2342.847714898526, 2300.620397634388, 2304.2741536416142, 2327.0924339507606, 2329.1032952313312, 2332.265336419023, 2326.685897403846, 2342.2153578551206, 2351.729551101628, 2351.9647205291935, 2353.85876847388, 8472.195141461612, 8505.872411913826, 8564.571771411405, 8568.0, 8646.610902509403, 4575.290829921368, 5001.349824522788, 5232.293640358541, 5233.581313494911, 5248.790048398059, 3253.3655937092276, 3395.439752440844, 3515.7011654009184, 3576.034470692898, 3583.4624806279007, 968.8546717992288, 1004.6483730694199, 1045.513836066233, 1061.124572227915, 1061.7080302916681, 10995.450227488594, 11157.0, 11219.551217951272, 11235.60675376366, 11258.986691197832, 4008.378701301303, 4501.0, 4597.494275629678, 4699.976500117477, 4702.623790096853, 8750.299976002032, 9295.698602195698, 9473.336866419422, 9561.0, 9567.234621230285, 5203.713795741271, 5216.7913283469, 5245.239440281138, 5246.396664383645, 5249.317588713443, 4286.957130428656, 4289.356596510548, 4729.55069268419, 4771.785269662873, 4826.0830442215865, 3123.6095488063993, 3766.7928263945746, 3831.8084095795098, 3855.325318069327, 3880.84476620932, 1062.984055239172, 1250.5435516036582, 1274.9043821713408, 1278.0861683895873, 1280.788669869461, 708.3377042465312, 775.5656832173888, 778.4122987144669, 871.4684042733927, 902.3863772634671, 5329.866753331198, 5593.272874526286, 5693.544516438675, 5734.7132643368495, 5762.9135562966585, 407.5048815688952, 419.53431690823163, 435.5296280017573, 437.76579529951323, 443.0120830547896, 3155.8422078896424, 3557.3418917500344, 3692.390755525362, 3725.1432170600915, 3785.5835858055593, 9857.605695772232, 10145.847812282822, 10252.846207306897, 10295.073443389982, 10300.278980471296, 9588.56851441673, 10142.797144057078, 10156.085952264331, 10208.336458130198, 10231.181505479544, 7750.0699916010435, 7972.481788683717, 7981.840363192588, 7991.0, 8002.119766825645, 5685.658860468385, 5722.942770572407, 5743.10981797822, 5788.855278618068, 6054.515638748801, 9412.294077944045, 9571.856422153813, 9585.0, 9594.808103838024, 9612.18296444782, 9011.909880901107, 9405.670801521914, 9409.0, 9561.282903782105, 9607.951960240154, 6413.903791443039, 6498.837529061811, 7077.893831592427, 7262.419006479363, 7595.088589369311, 7779.883301750365, 7804.648790804314, 7976.7208147714555, 7982.321502672338, 7999.520028798291, 6796.320367963362, 6806.0, 7099.538529995534, 7242.529235599669, 7279.4540409468445, 6976.127984002, 7013.333733295329, 7071.257517960746, 7083.893741593984, 7203.135623725187, 6540.50946179048, 6709.698063587248, 6763.019362192356, 6777.423918966943, 6831.863362732817, 4756.381670382828, 4881.877953051202, 4887.584555312838, 4939.629527785502, 4992.176290911959, 4818.951810481991, 4833.3958255218095, 4914.778834952364, 4976.2286845539, 5001.899962000667], "value": 4322.055070976234, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.65593416689967, 278.3931030353829, 299.8620634508126, 307.47728860936405, 311.8347275943748, 5188.118019936611, 5300.443453437381, 5397.999999999995, 5409.0, 5425.864353391163, 4640.814367425307, 4841.91057012172, 5272.710000949946, 5316.946830531693, 5326.3075800146025, 3933.940990885139, 4024.3359845625437, 4052.4326594276827, 4086.5300490443683, 4097.487814023247, 3600.0299752703995, 3973.8609148679802, 4097.446844675976, 4112.128981313495, 4112.97943510282, 4044.979775101135, 4057.918841623166, 4063.674906007527, 4072.816723247446, 4075.8777236682945, 3417.8037686809666, 3544.521489598897, 3874.883753487386, 4035.0921042765435, 4081.53064896637, 3476.461148521983, 3592.5868525119686, 3595.7662751921043, 3605.873794417209, 3610.530631017977, 4130.772807495617, 4587.853036740815, 4650.279396623271, 5173.068847607429, 5265.183896496049, 4013.478247827793, 4035.9910022494378, 4635.188841952679, 4637.489876113625, 4658.4642766082, 4744.122337367598, 4940.962397896446, 5350.411454739976, 5638.859028524279, 5689.6017278790505, 3499.5450591423205, 3780.0360907968293, 3842.5773164951834, 3923.7253392262555, 3937.4684417603544, 4823.565879070866, 4933.481984391659, 5590.785298453406, 5595.692236926968, 5869.647821130746, 3177.3804108199142, 3828.2917660232947, 3840.4047372656723, 3854.7642367426824, 3878.864239751595, 3721.10693433574, 3766.4350347447553, 3890.3386424307937, 3892.1631849152, 3909.3354129798004, 3800.562935262425, 4374.212641724456, 4478.900942006891, 4518.593326600621, 4529.9547004530195, 5161.870953226199, 5191.714455704972, 5366.7048312343195, 5657.32274021889, 5670.404607516234, 1212.5149940024062, 1231.7043909461843, 1243.3410292544966, 1253.7743206222785, 1275.8851703346559, 715.4455297144755, 732.8021434212733, 800.4236949396425, 809.8461292354434, 877.1929824561419, 4827.303781537749, 5086.3133476980875, 5117.590592752571, 5119.436861945183, 5155.690658560498, 4791.257355109963, 4927.556519913153, 5157.32954715892, 5579.6652200868075, 5612.943870561242, 4446.510883802779, 4689.186488107109, 4730.858074257778, 4831.154303292985, 5165.483451654804, 2924.0847245054524, 2924.9707502925125, 2929.882804687831, 2936.119164250716, 2937.427201695649, 4726.834560790356, 5236.790528378822, 5704.4295570443455, 5709.400512946164, 5713.171590119409, 4703.012367402884, 5354.571634269249, 5638.577106717013, 5662.37713851476, 5679.460451257124, 4776.423780152541, 5634.352049514277, 5662.065759149694, 5674.4892959633835, 5717.514011309086, 4924.556789888928, 5539.648512649789, 5822.417758224144, 5836.270466191726, 5860.413958604106, 5312.2562841203035, 5758.280214973129, 5772.307323121252, 5793.797217097382, 5830.47525722687, 5085.542301192911, 5708.4862362387585, 6007.3142948500235, 6104.236970378703, 6137.7851775187655, 3211.823349715788, 3301.5873015873017, 3883.3398322285843, 3884.2425726982965, 3912.8630497932436, 4735.763211839463, 5753.683547404766, 5877.589378549203, 5904.261967254093, 5917.3490915998345, 4801.4958429364415, 5200.297959775382, 5653.067243904709, 5768.798092066758, 5774.9133762994425, 4617.79219935097, 5660.037793575019, 5661.773529058956, 5662.319492329853, 5679.926826708304, 4752.079534384078, 5272.340957380327, 5326.893462130658, 5516.282883225155, 5522.729772152347, 4792.424909010941, 5242.737863106753, 5690.971545142248, 5711.71621284037, 5721.599488035964, 4691.906161876812, 4761.262004389325, 5352.571794256528, 5460.808871689472, 5557.694326812064], "value": 4009.269487879898, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.7961009559776, 666.3270097201824, 697.1111832413671, 701.4353445476393, 709.0109297529946, 13616.229890114271, 13874.306284685763, 14328.28358582072, 14522.854771452283, 14773.852261477396, 12526.624201273953, 13622.523211687592, 13682.726345473084, 13686.452541898338, 13712.862871371284, 654.7937399719098, 817.0358976407837, 837.8701301298278, 838.8070743728946, 838.9973981092597, 668.2548957911918, 744.0773440933231, 813.0243707550933, 823.0904850140598, 837.6858677995751, 879.4811061473729, 882.1839798186684, 883.1918794303205, 888.4979986307748, 889.2219308105408, 836.9455985360993, 878.6748902905902, 879.520661239626, 879.7448739865496, 880.6389380354057, 577.413924866261, 610.3743662745677, 651.5048563092037, 653.3923451190391, 655.0959675647623, 13477.595672129753, 13743.587692369245, 13886.166829990132, 13892.23592702401, 13924.234167120805, 12890.87109128906, 12964.416601252966, 13129.934350328187, 13336.533221337206, 13420.597382078553, 11172.217944743872, 11298.774024519464, 11343.26268792534, 11378.146639002025, 11629.011534019619, 3603.459481077833, 3613.703449715263, 3619.0, 3624.873129440457, 3653.214558869856, 77906.0, 80354.59822700908, 80538.79191812128, 84567.5771621138, 87884.56057719735, 10491.842622360671, 10546.103581195684, 10557.577696892038, 10607.94696026515, 10829.566817327219, 20051.899740501205, 20055.899720501304, 20059.498512537302, 20060.0, 20067.29764458266, 6350.650714210763, 7333.963330183315, 7519.248075192437, 7731.652075656609, 7882.014748156481, 4060.857869974596, 4164.916701665949, 4211.136716973013, 4220.261454245556, 4323.113761678911, 3112.6887311268692, 3183.4110689522513, 3188.442022646074, 3210.9357812843614, 3219.1952011997, 587.2776484923482, 645.5287640022809, 647.7441410642864, 649.2955143669077, 653.2552889705739, 524.8031988004499, 556.4852511426994, 566.4109326300684, 629.5876201088295, 632.2349956552531, 7917.3270272026375, 8551.358648101417, 8703.260222881127, 8825.38222324444, 8846.203841654282, 523.6125267302245, 584.6170758153417, 624.5659266809614, 625.3027873920637, 627.6830200748593, 3570.803605801706, 4004.9199016019516, 4301.397804307399, 4357.455318085239, 4390.040090327605, 90792.54603726939, 110567.0, 111290.52415827198, 111942.88771015972, 112219.43391887796, 88133.11866881377, 90978.0, 93372.19591942884, 93668.5949710755, 98010.50994745124, 70089.24776880619, 72658.63670681613, 73425.0, 74482.62758686172, 78790.21209787944, 9391.29565282593, 9575.712728618153, 9578.760530986781, 9586.808263834544, 9589.280803939593, 41673.79163104165, 45459.772701137605, 50917.47247582579, 52788.736056319474, 53156.93686126178, 50558.74720626373, 56028.0, 71266.0, 72942.0, 75111.62444187744, 11979.221350612183, 12065.638030859089, 12185.952139404273, 12271.325077120844, 12368.381580921097, 13584.660383490493, 13800.516981905585, 13871.861281387057, 14194.574162775134, 14470.348834302271, 10724.302920310154, 12846.036547258806, 12990.740185196431, 13461.86538134606, 13536.59390218295, 12512.124151309685, 12799.680007999874, 12835.073275969102, 12847.0, 13073.0, 11920.818355564606, 12052.09609279325, 12159.696007599527, 12460.12779105454, 12518.624441266777, 10713.250072494851, 10766.354018758899, 10820.0, 11085.445727713743, 11170.664880053611, 10513.421761802869, 10544.57821687124, 10937.343759374146, 10939.23425360249, 10958.671239862817], "value": 7056.0724247325015, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 119.0, 119.0, 117.0, 118.0, 119.0, 117.0, 114.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 146.0, 168.0, 121.0, 105.0, 111.0, 133.0, 179.0, 102.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 245.0, 260.0, 245.0, 273.0, 199.0, 280.0, 195.0, 192.0, 189.0], "value": 228.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 75.0, 83.0, 51.0, 73.0, 84.0, 104.0, 85.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 88.0, 74.0, 72.0, 72.0, 77.0, 92.0, 100.0, 121.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 76.0, 135.0, 127.0, 89.0, 86.0, 90.0, 88.0, 92.0, 86.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 115.0, 99.0, 99.0, 132.0, 120.0, 100.0, 98.0, 130.0, 98.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 91.0, 88.0, 134.0, 87.0, 90.0, 91.0, 88.0, 88.0, 88.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 56.0, 55.0, 55.0, 56.0, 60.0, 58.0, 58.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 74.0, 85.0, 80.0, 72.0, 72.0, 73.0, 78.0, 103.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 100.0, 92.0, 90.0, 90.0, 90.0, 89.0, 88.0, 89.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 147.0, 152.0, 151.0, 160.0, 160.0, 167.0, 198.0, 153.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 179.0, 215.0, 224.0, 239.0, 192.0, 176.0, 173.0, 174.0], "value": 192.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 95.0, 90.0, 88.0, 92.0, 76.0, 68.0, 70.0, 71.0, 67.0], "value": 79.0, "unit": "score"}]}]} 06:06:52 INFO - Return code: 0 06:06:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:06:52 ERROR - # TBPL SUCCESS # 06:06:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:06:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:06:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:06:52 INFO - 2016-11-10 06:06:52.924 screenresolution[1678:10627] starting screenresolution argv=screenresolution get 06:06:52 INFO - 2016-11-10 06:06:52.927 screenresolution[1678:10627] Display 0: 1600x1200x32@60 06:06:52 INFO - 2016-11-10 06:06:52.933 screenresolution[1679:10630] starting screenresolution argv=screenresolution list 06:06:52 INFO - Available Modes on Display 0 06:06:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:06:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:06:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:06:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:06:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:06:53 INFO - Intel Iris: 06:06:53 INFO - Chipset Model: Intel Iris 06:06:53 INFO - Type: GPU 06:06:53 INFO - Bus: Built-In 06:06:53 INFO - VRAM (Dynamic, Max): 1536 MB 06:06:53 INFO - Vendor: Intel (0x8086) 06:06:53 INFO - Device ID: 0x0a2e 06:06:53 INFO - Revision ID: 0x0009 06:06:53 INFO - Displays: 06:06:53 INFO - Display: 06:06:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:06:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:06:53 INFO - Display Serial Number: 1600x1200 60 06:06:53 INFO - Main Display: Yes 06:06:53 INFO - Mirror: Off 06:06:53 INFO - Online: Yes 06:06:53 INFO - Rotation: Supported 06:06:53 INFO - Return code: 0 06:06:53 INFO - Running post-action listener: _resource_record_post_action 06:06:53 INFO - [mozharness: 2016-11-10 14:06:53.171978Z] Finished run-tests step (success) 06:06:53 INFO - Running post-run listener: _resource_record_post_run 06:06:53 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 39524352; Write bytes: 703363584; Read time: 425; Write time: 2619 06:06:53 INFO - TinderboxPrint: CPU usage
54.7% 06:06:53 INFO - TinderboxPrint: I/O read bytes / time
39,524,352 / 425 06:06:53 INFO - TinderboxPrint: I/O write bytes / time
703,363,584 / 2,619 06:06:53 INFO - TinderboxPrint: CPU idle
2,197.4 (45.3%) 06:06:53 INFO - TinderboxPrint: CPU system
985.3 (20.3%) 06:06:53 INFO - TinderboxPrint: CPU user
1,667.0 (34.4%) 06:06:53 INFO - TinderboxPrint: Swap in / out
437,604,352 / 0 06:06:53 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389411328; Write bytes: 384178176; Read time: 29974; Write time: 709 06:06:53 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 37513216; Write bytes: 301113856; Read time: 367; Write time: 1861 06:06:53 INFO - Running post-run listener: _upload_blobber_files 06:06:53 INFO - Blob upload gear active. 06:06:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:06:53 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 06:06:53 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:06:53 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:06:54 INFO - (blobuploader) - INFO - Open directory for files ... 06:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:54 INFO - (blobuploader) - INFO - Done attempting. 06:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:55 INFO - (blobuploader) - INFO - Done attempting. 06:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:06:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:58 INFO - (blobuploader) - INFO - Done attempting. 06:06:58 INFO - (blobuploader) - INFO - Iteration through files over. 06:06:58 INFO - Return code: 0 06:06:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:06:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:06:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39c7ea4866d73a8f2e50382b7bf1cad5b029111e20c678139e02889919472e93fd38cee680aaef33423c69417c27b5a82ac21ed455318bfb7cea7b1bddccdeb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728ca2374a648f94c60fea0fc92a5f182d33eaed2e2a0ea5946999ba10f2a2509c1fff44e2798aee9335b6f95efd20a45c73620aaa3bc35e96248979b66ed017", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19a88a42feb9caf2b372649cceccbc28efbba57582ef483c52a36cd3c863605488c8c844f107d203d4ed0019ec5798973a4d649ca46bb28b4822fbba681700ab"} 06:06:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:06:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:06:58 INFO - Contents: 06:06:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39c7ea4866d73a8f2e50382b7bf1cad5b029111e20c678139e02889919472e93fd38cee680aaef33423c69417c27b5a82ac21ed455318bfb7cea7b1bddccdeb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728ca2374a648f94c60fea0fc92a5f182d33eaed2e2a0ea5946999ba10f2a2509c1fff44e2798aee9335b6f95efd20a45c73620aaa3bc35e96248979b66ed017", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19a88a42feb9caf2b372649cceccbc28efbba57582ef483c52a36cd3c863605488c8c844f107d203d4ed0019ec5798973a4d649ca46bb28b4822fbba681700ab"} 06:06:58 INFO - Running post-run listener: copy_logs_to_upload_dir 06:06:58 INFO - Copying logs to upload dir... 06:06:58 INFO - mkdir: /builds/slave/test/build/upload/logs 06:06:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.315740 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-10 06:06:58.234316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 06:06:58.238178) ========= 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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/Firefox/sha512/39c7ea4866d73a8f2e50382b7bf1cad5b029111e20c678139e02889919472e93fd38cee680aaef33423c69417c27b5a82ac21ed455318bfb7cea7b1bddccdeb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728ca2374a648f94c60fea0fc92a5f182d33eaed2e2a0ea5946999ba10f2a2509c1fff44e2798aee9335b6f95efd20a45c73620aaa3bc35e96248979b66ed017", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19a88a42feb9caf2b372649cceccbc28efbba57582ef483c52a36cd3c863605488c8c844f107d203d4ed0019ec5798973a4d649ca46bb28b4822fbba681700ab"} build_url:https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/bpQigPXxSWS6ygdMYes1CQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39c7ea4866d73a8f2e50382b7bf1cad5b029111e20c678139e02889919472e93fd38cee680aaef33423c69417c27b5a82ac21ed455318bfb7cea7b1bddccdeb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728ca2374a648f94c60fea0fc92a5f182d33eaed2e2a0ea5946999ba10f2a2509c1fff44e2798aee9335b6f95efd20a45c73620aaa3bc35e96248979b66ed017", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19a88a42feb9caf2b372649cceccbc28efbba57582ef483c52a36cd3c863605488c8c844f107d203d4ed0019ec5798973a4d649ca46bb28b4822fbba681700ab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 06:06:58.271380) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 06:06:58.271701) ========= 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.uOCyIS7W2e/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.1dRp1w2A9i/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.005023 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 06:06:58.319633) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 06:06:58.319951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 06:06:58.320267) ========= ========= Total master_lag: 0.17 =========