builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0210 starttime: 1479448736.23 results: success (0) buildid: 20161117192228 builduid: 5b4237af75434562aaea6e792075d428 revision: 8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.229221) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.229700) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.230002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.257580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.258019) ========= 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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-17 21:58:56-- 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% 54.8M=0s 2016-11-17 21:58:56 (54.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139064 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.419897) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.423238) ========= 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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.024670 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:56.471627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 21:58:56.471950) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a --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 8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V7WfBdXOfR/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.u7YlDbSWHn/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-17 21:58:56,577 truncating revision to first 12 chars 2016-11-17 21:58:56,577 Setting DEBUG logging. 2016-11-17 21:58:56,577 attempt 1/10 2016-11-17 21:58:56,577 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8e476f8bd52d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 21:58:57,939 unpacking tar archive at: mozilla-central-8e476f8bd52d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.818185 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 21:58:58.314982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:58.318334) ========= script_repo_revision: 8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:58.318732) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:58.319004) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 21:58:58.337440) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-17 21:58:58.337764) ========= /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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:58:58 INFO - MultiFileLogger online at 20161117 21:58:58 in /builds/slave/test 21:58:58 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 21:58:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:58:58 INFO - {'append_to_log': False, 21:58:58 INFO - 'base_work_dir': '/builds/slave/test', 21:58:58 INFO - 'blob_upload_branch': 'Firefox', 21:58:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:58:58 INFO - 'branch': 'Firefox', 21:58:58 INFO - 'buildbot_json_path': 'buildprops.json', 21:58:58 INFO - 'config_files': ('talos/mac_config.py',), 21:58:58 INFO - 'default_actions': ('clobber', 21:58:58 INFO - 'read-buildbot-config', 21:58:58 INFO - 'download-and-extract', 21:58:58 INFO - 'populate-webroot', 21:58:58 INFO - 'create-virtualenv', 21:58:58 INFO - 'install', 21:58:58 INFO - 'run-tests'), 21:58:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:58:58 INFO - 'download_minidump_stackwalk': True, 21:58:58 INFO - 'download_symbols': 'ondemand', 21:58:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:58:58 INFO - 'tooltool.py': '/tools/tooltool.py', 21:58:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:58:58 INFO - '/tools/misc-python/virtualenv.py')}, 21:58:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:58:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:58:58 INFO - 'installer_path': 'installer.exe', 21:58:58 INFO - 'log_level': 'info', 21:58:58 INFO - 'log_name': 'talos', 21:58:58 INFO - 'log_to_console': True, 21:58:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:58:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:58:58 INFO - 'opt_config_files': (), 21:58:58 INFO - 'pip_index': False, 21:58:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:58:58 INFO - 'cmd': ('bash', 21:58:58 INFO - '-c', 21:58:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:58:58 INFO - 'enabled': True, 21:58:58 INFO - 'halt_on_failure': False, 21:58:58 INFO - 'name': 'check_screen_resolution'},), 21:58:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:58:58 INFO - 'cmd': ('bash', 21:58:58 INFO - '-c', 21:58:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:58:58 INFO - 'enabled': True, 21:58:58 INFO - 'halt_on_failure': False, 21:58:58 INFO - 'name': 'check_screen_resolution'},), 21:58:58 INFO - 'run_cmd_checks_enabled': True, 21:58:58 INFO - 'sps_profile': False, 21:58:58 INFO - 'sps_profile_interval': 0, 21:58:58 INFO - 'suite': 'dromaeojs-e10s', 21:58:58 INFO - 'system_bits': '32', 21:58:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:58:58 INFO - 'title': 't-yosemite-r7-0210', 21:58:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:58:58 INFO - 'use_talos_json': True, 21:58:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:58:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:58:58 INFO - 'work_dir': 'build'} 21:58:58 INFO - [mozharness: 2016-11-18 05:58:58.528192Z] Running clobber step. 21:58:58 INFO - Running pre-action listener: _resource_record_pre_action 21:58:58 INFO - Running main action method: clobber 21:58:58 INFO - rmtree: /builds/slave/test/build 21:58:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:59:00 INFO - Running post-action listener: _resource_record_post_action 21:59:00 INFO - [mozharness: 2016-11-18 05:59:00.839078Z] Finished clobber step (success) 21:59:00 INFO - [mozharness: 2016-11-18 05:59:00.839235Z] Running read-buildbot-config step. 21:59:00 INFO - Running pre-action listener: _resource_record_pre_action 21:59:00 INFO - Running main action method: read_buildbot_config 21:59:00 INFO - Using buildbot properties: 21:59:00 INFO - { 21:59:00 INFO - "project": "", 21:59:00 INFO - "product": "firefox", 21:59:00 INFO - "script_repo_revision": "production", 21:59:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:59:00 INFO - "repository": "", 21:59:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:59:00 INFO - "buildid": "20161117192228", 21:59:00 INFO - "pgo_build": "False", 21:59:00 INFO - "basedir": "/builds/slave/test", 21:59:00 INFO - "buildnumber": 172, 21:59:00 INFO - "slavename": "t-yosemite-r7-0210", 21:59:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:59:00 INFO - "platform": "yosemite_r7", 21:59:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:59:00 INFO - "branch": "mozilla-central", 21:59:00 INFO - "repo_path": "mozilla-central", 21:59:00 INFO - "revision": "8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a", 21:59:00 INFO - "stage_platform": "macosx64", 21:59:00 INFO - "builduid": "5b4237af75434562aaea6e792075d428", 21:59:00 INFO - "slavebuilddir": "test" 21:59:00 INFO - } 21:59:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:59:00 INFO - Running post-action listener: _resource_record_post_action 21:59:00 INFO - [mozharness: 2016-11-18 05:59:00.842551Z] Finished read-buildbot-config step (success) 21:59:00 INFO - [mozharness: 2016-11-18 05:59:00.842658Z] Running download-and-extract step. 21:59:00 INFO - Running pre-action listener: _resource_record_pre_action 21:59:00 INFO - Running main action method: download_and_extract 21:59:00 INFO - mkdir: /builds/slave/test/build/tests 21:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:00 INFO - trying https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:59:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:59:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 21:59:01 INFO - Downloaded 1199 bytes. 21:59:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:59:01 INFO - Using the following test package requirements: 21:59:01 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:59:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:59:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:59:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'jsshell-mac.zip'], 21:59:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:59:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:59:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:59:01 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:59:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:59:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:59:01 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:59:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:59:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:59:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:59:01 INFO - Fetch https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:59:02 INFO - Http code: 200 21:59:02 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 21:59:02 INFO - x-amz-cf-id: Tdsw-gzUAkGWNkL-KaD5mAKGVGtBZR2RHCnKeyPPvMCICoUFK3AJKg== 21:59:02 INFO - x-amz-version-id: 0Itmr8nyfHcUPgnc52lUgsrvXECM.EjQ 21:59:02 INFO - Expected file size: 16223085 21:59:02 INFO - Obtained file size: 16223085 21:59:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:59:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:59:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:59:09 INFO - Fetch https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:59:09 INFO - Http code: 200 21:59:09 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 21:59:09 INFO - x-amz-cf-id: Lw_RRGQrPpGzNUMpao82-b3znbMGBmjPBMkhzzf91OavWFXlS1Q10A== 21:59:09 INFO - x-amz-version-id: 0Itmr8nyfHcUPgnc52lUgsrvXECM.EjQ 21:59:10 INFO - Expected file size: 16223085 21:59:10 INFO - Obtained file size: 16223085 21:59:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:59:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:59:16 INFO - Fetch https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:59:17 INFO - Http code: 200 21:59:17 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 21:59:17 INFO - x-amz-cf-id: gqjv4wvlh-r_LxQCOiPdHTtBrkIfA0jQcIVQWvXFfGwlNvFgdINGjQ== 21:59:17 INFO - x-amz-version-id: akYFSKLF1P1cmSN111zHEoJTcRt3gLS5 21:59:17 INFO - Expected file size: 14409392 21:59:17 INFO - Obtained file size: 14409392 21:59:18 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')]} 21:59:18 INFO - trying https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:59:18 INFO - Downloading https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:59:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 21:59:20 INFO - Downloaded 107370470 bytes. 21:59:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:59:20 INFO - mkdir: /builds/slave/test/properties 21:59:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:59:20 INFO - Writing to file /builds/slave/test/properties/build_url 21:59:20 INFO - Contents: 21:59:20 INFO - build_url:https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:59:21 INFO - Running post-action listener: _resource_record_post_action 21:59:21 INFO - Running post-action listener: set_extra_try_arguments 21:59:21 INFO - [mozharness: 2016-11-18 05:59:21.983556Z] Finished download-and-extract step (success) 21:59:21 INFO - [mozharness: 2016-11-18 05:59:21.983683Z] Running populate-webroot step. 21:59:21 INFO - Running pre-action listener: _resource_record_pre_action 21:59:21 INFO - Running main action method: populate_webroot 21:59:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:59:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:59:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:59:21 INFO - '--tppagecycles', 21:59:21 INFO - '7'], 21:59:21 INFO - 'tests': ['tsvgm']}}, 21:59:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:59:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:59:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:59:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:59:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:59:21 INFO - 'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:59:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:59:21 INFO - 'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['damp', 'tps']}, 21:59:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:59:21 INFO - 'tests': ['damp', 'tps']}, 21:59:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['dromaeo_dom']}, 21:59:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:59:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['basic_compositor_video']}, 21:59:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:59:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['a11yr', 21:59:21 INFO - 'ts_paint', 21:59:21 INFO - 'tpaint', 21:59:21 INFO - 'sessionrestore', 21:59:21 INFO - 'sessionrestore_no_auto_restore', 21:59:21 INFO - 'tabpaint']}, 21:59:21 INFO - 'other-e10s': {'tests': ['a11yr', 21:59:21 INFO - 'ts_paint', 21:59:21 INFO - 'tpaint', 21:59:21 INFO - 'sessionrestore', 21:59:21 INFO - 'sessionrestore_no_auto_restore', 21:59:21 INFO - 'tabpaint']}, 21:59:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:59:21 INFO - 'ts_paint', 21:59:21 INFO - 'tpaint', 21:59:21 INFO - 'sessionrestore', 21:59:21 INFO - 'sessionrestore_no_auto_restore', 21:59:21 INFO - 'tabpaint']}, 21:59:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:59:21 INFO - 'ts_paint', 21:59:21 INFO - 'tpaint', 21:59:21 INFO - 'sessionrestore', 21:59:21 INFO - 'sessionrestore_no_auto_restore', 21:59:21 INFO - 'tabpaint']}, 21:59:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['a11yr', 21:59:21 INFO - 'ts_paint', 21:59:21 INFO - 'tpaint', 21:59:21 INFO - 'sessionrestore', 21:59:21 INFO - 'sessionrestore_no_auto_restore', 21:59:21 INFO - 'tabpaint']}, 21:59:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['a11yr', 21:59:21 INFO - 'ts_paint', 21:59:21 INFO - 'tpaint', 21:59:21 INFO - 'sessionrestore', 21:59:21 INFO - 'sessionrestore_no_auto_restore', 21:59:21 INFO - 'tabpaint']}, 21:59:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:59:21 INFO - 'tests': ['tsvgx', 21:59:21 INFO - 'tsvgr_opacity', 21:59:21 INFO - 'tart', 21:59:21 INFO - 'tscrollx', 21:59:21 INFO - 'cart']}, 21:59:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:59:21 INFO - 'tsvgr_opacity', 21:59:21 INFO - 'tart', 21:59:21 INFO - 'tscrollx', 21:59:22 INFO - 'cart']}, 21:59:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:59:22 INFO - 'talos_options': ['--disable-e10s'], 21:59:22 INFO - 'tests': ['tp5o']}, 21:59:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:59:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:59:22 INFO - 'talos_options': ['--disable-e10s', 21:59:22 INFO - '--xperf_path', 21:59:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:22 INFO - 'tests': ['tp5n']}, 21:59:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:59:22 INFO - 'talos_options': ['--xperf_path', 21:59:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:22 INFO - 'tests': ['tp5n']}}, 21:59:22 INFO - 'talos.zip': {'path': '', 21:59:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:59:22 INFO - Running post-action listener: _resource_record_post_action 21:59:22 INFO - [mozharness: 2016-11-18 05:59:22.001699Z] Finished populate-webroot step (success) 21:59:22 INFO - [mozharness: 2016-11-18 05:59:22.001814Z] Running create-virtualenv step. 21:59:22 INFO - Running pre-action listener: _resource_record_pre_action 21:59:22 INFO - Running main action method: create_virtualenv 21:59:22 INFO - Creating virtualenv /builds/slave/test/build/venv 21:59:22 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 21:59:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:59:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:59:22 INFO - Using real prefix '/tools/python27' 21:59:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:59:22 INFO - Installing distribute.............................................................................................................................................................................................done. 21:59:25 INFO - Installing pip.................done. 21:59:25 INFO - Return code: 0 21:59:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:59:26 INFO - Reading from file tmpfile_stdout 21:59:26 INFO - Output received: 21:59:26 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) 21:59:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:26 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:26 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 21:59:26 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 21:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:26 INFO - 'HOME': '/Users/cltbld', 21:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:26 INFO - 'LOGNAME': 'cltbld', 21:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:26 INFO - 'MOZ_NO_REMOTE': '1', 21:59:26 INFO - 'NO_EM_RESTART': '1', 21:59:26 INFO - 'PAGER': '/bin/cat', 21:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:26 INFO - 'PWD': '/builds/slave/test', 21:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:26 INFO - 'SHELL': '/bin/bash', 21:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:26 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:26 INFO - 'USER': 'cltbld', 21:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:26 INFO - 'XPC_FLAGS': '0x0', 21:59:26 INFO - 'XPC_SERVICE_NAME': '0', 21:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:26 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 21:59:26 INFO - Cleaning up... 21:59:26 INFO - Return code: 0 21:59:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:26 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:26 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 21:59:26 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 21:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:26 INFO - 'HOME': '/Users/cltbld', 21:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:26 INFO - 'LOGNAME': 'cltbld', 21:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:26 INFO - 'MOZ_NO_REMOTE': '1', 21:59:26 INFO - 'NO_EM_RESTART': '1', 21:59:26 INFO - 'PAGER': '/bin/cat', 21:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:26 INFO - 'PWD': '/builds/slave/test', 21:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:26 INFO - 'SHELL': '/bin/bash', 21:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:26 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:26 INFO - 'USER': 'cltbld', 21:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:26 INFO - 'XPC_FLAGS': '0x0', 21:59:26 INFO - 'XPC_SERVICE_NAME': '0', 21:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:26 INFO - Downloading/unpacking psutil>=3.1.1 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:59:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:59:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:59:30 INFO - Installing collected packages: psutil 21:59:30 INFO - Running setup.py install for psutil 21:59:30 INFO - building 'psutil._psutil_osx' extension 21:59:30 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 21:59:30 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 21:59:30 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 21:59:30 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 21:59:30 INFO - building 'psutil._psutil_posix' extension 21:59:30 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 21:59:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:59:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:59:30 INFO - ^ 21:59:30 INFO - 1 warning generated. 21:59:30 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 21:59:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:59:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:59:31 INFO - Successfully installed psutil 21:59:31 INFO - Cleaning up... 21:59:31 INFO - Return code: 0 21:59:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:59:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:31 INFO - 'HOME': '/Users/cltbld', 21:59:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:31 INFO - 'LOGNAME': 'cltbld', 21:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:31 INFO - 'MOZ_NO_REMOTE': '1', 21:59:31 INFO - 'NO_EM_RESTART': '1', 21:59:31 INFO - 'PAGER': '/bin/cat', 21:59:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:31 INFO - 'PWD': '/builds/slave/test', 21:59:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:31 INFO - 'SHELL': '/bin/bash', 21:59:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:31 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:31 INFO - 'USER': 'cltbld', 21:59:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:31 INFO - 'XPC_FLAGS': '0x0', 21:59:31 INFO - 'XPC_SERVICE_NAME': '0', 21:59:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:59:31 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 21:59:31 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 21:59:31 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 21:59:31 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 21:59:31 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 21:59:31 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 21:59:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:59:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:59:34 INFO - Installing collected packages: mozsystemmonitor 21:59:34 INFO - Running setup.py install for mozsystemmonitor 21:59:34 INFO - Successfully installed mozsystemmonitor 21:59:34 INFO - Cleaning up... 21:59:34 INFO - Return code: 0 21:59:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:59:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:34 INFO - 'HOME': '/Users/cltbld', 21:59:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:34 INFO - 'LOGNAME': 'cltbld', 21:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:34 INFO - 'MOZ_NO_REMOTE': '1', 21:59:34 INFO - 'NO_EM_RESTART': '1', 21:59:34 INFO - 'PAGER': '/bin/cat', 21:59:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:34 INFO - 'PWD': '/builds/slave/test', 21:59:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:34 INFO - 'SHELL': '/bin/bash', 21:59:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:34 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:34 INFO - 'USER': 'cltbld', 21:59:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:34 INFO - 'XPC_FLAGS': '0x0', 21:59:34 INFO - 'XPC_SERVICE_NAME': '0', 21:59:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:34 INFO - Downloading/unpacking jsonschema==2.5.1 21:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:59:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 INFO - Downloading functools32-3.2.3-2.tar.gz 21:59:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:59:37 INFO - warning: no files found matching '*.txt' 21:59:37 INFO - no previously-included directories found matching 'build' 21:59:37 INFO - no previously-included directories found matching 'dist' 21:59:37 INFO - no previously-included directories found matching '.git*' 21:59:37 INFO - Installing collected packages: jsonschema, functools32 21:59:37 INFO - Running setup.py install for functools32 21:59:37 INFO - warning: no files found matching '*.txt' 21:59:37 INFO - no previously-included directories found matching 'build' 21:59:37 INFO - no previously-included directories found matching 'dist' 21:59:37 INFO - no previously-included directories found matching '.git*' 21:59:37 INFO - Successfully installed jsonschema functools32 21:59:37 INFO - Cleaning up... 21:59:37 INFO - Return code: 0 21:59:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:37 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:37 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 21:59:37 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 21:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:37 INFO - 'HOME': '/Users/cltbld', 21:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:37 INFO - 'LOGNAME': 'cltbld', 21:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:37 INFO - 'MOZ_NO_REMOTE': '1', 21:59:37 INFO - 'NO_EM_RESTART': '1', 21:59:37 INFO - 'PAGER': '/bin/cat', 21:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:37 INFO - 'PWD': '/builds/slave/test', 21:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:37 INFO - 'SHELL': '/bin/bash', 21:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:37 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:37 INFO - 'USER': 'cltbld', 21:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:37 INFO - 'XPC_FLAGS': '0x0', 21:59:37 INFO - 'XPC_SERVICE_NAME': '0', 21:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:59:38 INFO - Cleaning up... 21:59:38 INFO - Return code: 0 21:59:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:59: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')]} 21:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59: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')]} 21:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:38 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:38 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 21:59:38 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 21:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:38 INFO - 'HOME': '/Users/cltbld', 21:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:38 INFO - 'LOGNAME': 'cltbld', 21:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:38 INFO - 'MOZ_NO_REMOTE': '1', 21:59:38 INFO - 'NO_EM_RESTART': '1', 21:59:38 INFO - 'PAGER': '/bin/cat', 21:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:38 INFO - 'PWD': '/builds/slave/test', 21:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:38 INFO - 'SHELL': '/bin/bash', 21:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:38 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:38 INFO - 'USER': 'cltbld', 21:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:38 INFO - 'XPC_FLAGS': '0x0', 21:59:38 INFO - 'XPC_SERVICE_NAME': '0', 21:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:38 INFO - Downloading/unpacking blobuploader==1.2.4 21:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:40 INFO - Downloading blobuploader-1.2.4.tar.gz 21:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:59:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:59:40 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 21:59:40 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 21:59:40 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 21:59:40 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 21:59:40 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 21:59:40 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 21:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:59:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 INFO - Downloading docopt-0.6.1.tar.gz 21:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:59:42 INFO - Installing collected packages: blobuploader, requests, docopt 21:59:42 INFO - Running setup.py install for blobuploader 21:59:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:59:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:59:42 INFO - Running setup.py install for requests 21:59:42 INFO - Running setup.py install for docopt 21:59:42 INFO - Successfully installed blobuploader requests docopt 21:59:42 INFO - Cleaning up... 21:59:42 INFO - Return code: 0 21:59:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:42 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:42 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 21:59:42 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 21:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:42 INFO - 'HOME': '/Users/cltbld', 21:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:42 INFO - 'LOGNAME': 'cltbld', 21:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:42 INFO - 'MOZ_NO_REMOTE': '1', 21:59:42 INFO - 'NO_EM_RESTART': '1', 21:59:42 INFO - 'PAGER': '/bin/cat', 21:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:42 INFO - 'PWD': '/builds/slave/test', 21:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:42 INFO - 'SHELL': '/bin/bash', 21:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:42 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:42 INFO - 'USER': 'cltbld', 21:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:43 INFO - 'XPC_FLAGS': '0x0', 21:59:43 INFO - 'XPC_SERVICE_NAME': '0', 21:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-bNLXG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-BLt206-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-5Qq6UT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-fOkCIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-9tJDTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ZLohsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:59:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-pwm9l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-s_lKJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-JLXxXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ZaRxRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-eBNiYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-IWSOoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-fqpA2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Os_MuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-UCZbZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-h7UdKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:59:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-JaLmhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:59:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:59:45 INFO - Running setup.py install for manifestparser 21:59:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:59:45 INFO - Running setup.py install for mozcrash 21:59:45 INFO - Running setup.py install for mozdebug 21:59:45 INFO - Running setup.py install for mozdevice 21:59:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:59:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:59:45 INFO - Running setup.py install for mozfile 21:59:45 INFO - Running setup.py install for mozhttpd 21:59:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:59:45 INFO - Running setup.py install for mozinfo 21:59:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Running setup.py install for mozInstall 21:59:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Running setup.py install for mozleak 21:59:46 INFO - Running setup.py install for mozlog 21:59:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Running setup.py install for moznetwork 21:59:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:59:46 INFO - Running setup.py install for mozprocess 21:59:46 INFO - Running setup.py install for mozprofile 21:59:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:59:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:59:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:59:47 INFO - Running setup.py install for mozrunner 21:59:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:59:47 INFO - Running setup.py install for mozscreenshot 21:59:47 INFO - Running setup.py install for moztest 21:59:47 INFO - Running setup.py install for mozversion 21:59:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:59:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:59:47 INFO - Cleaning up... 21:59:47 INFO - Return code: 0 21:59:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:59:47 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')]} 21:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:47 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')]} 21:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:47 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:47 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 21:59:47 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 21:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:47 INFO - 'HOME': '/Users/cltbld', 21:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:47 INFO - 'LOGNAME': 'cltbld', 21:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:47 INFO - 'MOZ_NO_REMOTE': '1', 21:59:47 INFO - 'NO_EM_RESTART': '1', 21:59:47 INFO - 'PAGER': '/bin/cat', 21:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:47 INFO - 'PWD': '/builds/slave/test', 21:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:47 INFO - 'SHELL': '/bin/bash', 21:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:47 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:47 INFO - 'USER': 'cltbld', 21:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:47 INFO - 'XPC_FLAGS': '0x0', 21:59:47 INFO - 'XPC_SERVICE_NAME': '0', 21:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:59:47 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-u0fwRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-HTm6uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-K6x8oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ajBVc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-9Ca9NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-8S196B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-7f4m_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-oQE5G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-CT2K3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-wlktsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:59:48 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)) 21:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:59:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-0vXdhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:59:49 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)) 21:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:59:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-tfw2uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:59:49 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)) 21:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:59:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-QvyktR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:59:49 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)) 21:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:59:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-e35JFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:59:49 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)) 21:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-RjTsHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:59:49 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)) 21:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:59:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-8yGry4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:59:49 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)) 21:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:59:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-LKzg01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:52 INFO - Downloading blessings-1.6.tar.gz 21:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:59:52 INFO - Installing collected packages: blessings 21:59:52 INFO - Running setup.py install for blessings 21:59:52 INFO - Successfully installed blessings 21:59:52 INFO - Cleaning up... 21:59:52 INFO - Return code: 0 21:59:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:59:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:59:52 INFO - Reading from file tmpfile_stdout 21:59:52 INFO - Current package versions: 21:59:52 INFO - blessings == 1.6 21:59:52 INFO - blobuploader == 1.2.4 21:59:52 INFO - docopt == 0.6.1 21:59:52 INFO - functools32 == 3.2.3-2 21:59:52 INFO - jsonschema == 2.5.1 21:59:52 INFO - manifestparser == 1.1 21:59:52 INFO - mozInstall == 1.12 21:59:52 INFO - mozcrash == 1.0 21:59:52 INFO - mozdebug == 0.1 21:59:52 INFO - mozdevice == 0.48 21:59:52 INFO - mozfile == 1.2 21:59:52 INFO - mozhttpd == 0.7 21:59:52 INFO - mozinfo == 0.9 21:59:52 INFO - mozleak == 0.1 21:59:52 INFO - mozlog == 3.4 21:59:52 INFO - moznetwork == 0.27 21:59:52 INFO - mozprocess == 0.23 21:59:52 INFO - mozprofile == 0.28 21:59:52 INFO - mozrunner == 6.13 21:59:52 INFO - mozscreenshot == 0.1 21:59:52 INFO - mozsystemmonitor == 0.3 21:59:52 INFO - moztest == 0.7 21:59:52 INFO - mozversion == 1.4 21:59:52 INFO - psutil == 3.1.1 21:59:52 INFO - requests == 1.2.3 21:59:52 INFO - wsgiref == 0.1.2 21:59:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:53 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')]} 21:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:53 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:53 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 21:59:53 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 21:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:53 INFO - 'HOME': '/Users/cltbld', 21:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:53 INFO - 'LOGNAME': 'cltbld', 21:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:53 INFO - 'MOZ_NO_REMOTE': '1', 21:59:53 INFO - 'NO_EM_RESTART': '1', 21:59:53 INFO - 'PAGER': '/bin/cat', 21:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:53 INFO - 'PWD': '/builds/slave/test', 21:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:53 INFO - 'SHELL': '/bin/bash', 21:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:53 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:53 INFO - 'USER': 'cltbld', 21:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:53 INFO - 'XPC_FLAGS': '0x0', 21:59:53 INFO - 'XPC_SERVICE_NAME': '0', 21:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 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)) 21:59:53 INFO - Cleaning up... 21:59:53 INFO - Return code: 0 21:59:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:59:53 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')]} 21:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:53 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')]} 21:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:54 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 0x1065763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa112d966e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, '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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 21:59:54 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 21:59:54 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 21:59:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 21:59:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:59:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:59:54 INFO - 'HOME': '/Users/cltbld', 21:59:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:59:54 INFO - 'LOGNAME': 'cltbld', 21:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:54 INFO - 'MOZ_NO_REMOTE': '1', 21:59:54 INFO - 'NO_EM_RESTART': '1', 21:59:54 INFO - 'PAGER': '/bin/cat', 21:59:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:59:54 INFO - 'PWD': '/builds/slave/test', 21:59:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:59:54 INFO - 'SHELL': '/bin/bash', 21:59:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 21:59:54 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 21:59:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:59:54 INFO - 'USER': 'cltbld', 21:59:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:59:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:54 INFO - 'XPC_FLAGS': '0x0', 21:59:54 INFO - 'XPC_SERVICE_NAME': '0', 21:59:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:59:54 INFO - Cleaning up... 21:59:54 INFO - Return code: 0 21:59:54 INFO - Running post-action listener: _resource_record_post_action 21:59:54 INFO - Running post-action listener: _start_resource_monitoring 21:59:54 INFO - Starting resource monitoring. 21:59:54 INFO - [mozharness: 2016-11-18 05:59:54.595098Z] Finished create-virtualenv step (success) 21:59:54 INFO - [mozharness: 2016-11-18 05:59:54.596031Z] Running install step. 21:59:54 INFO - Running pre-action listener: _resource_record_pre_action 21:59:54 INFO - Running main action method: install 21:59:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:59:54 INFO - Reading from file tmpfile_stdout 21:59:54 INFO - Detecting whether we're running mozinstall >=1.0... 21:59:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:59:54 INFO - Reading from file tmpfile_stdout 21:59:54 INFO - Output received: 21:59:54 INFO - Usage: mozinstall [options] installer 21:59:54 INFO - Options: 21:59:54 INFO - -h, --help show this help message and exit 21:59:54 INFO - -d DEST, --destination=DEST 21:59:54 INFO - Directory to install application into. [default: 21:59:54 INFO - "/builds/slave/test"] 21:59:54 INFO - --app=APP Application being installed. [default: firefox] 21:59:54 INFO - mkdir: /builds/slave/test/build/application 21:59:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:00:27 INFO - Reading from file tmpfile_stdout 22:00:27 INFO - Output received: 22:00:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:00:27 INFO - Running post-action listener: _resource_record_post_action 22:00:27 INFO - [mozharness: 2016-11-18 06:00:27.624167Z] Finished install step (success) 22:00:27 INFO - [mozharness: 2016-11-18 06:00:27.624301Z] Running run-tests step. 22:00:27 INFO - Running pre-action listener: _resource_record_pre_action 22:00:27 INFO - Running main action method: run_tests 22:00:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:00:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:00:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:00:27 INFO - 2016-11-17 22:00:27.639 screenresolution[1624:7424] starting screenresolution argv=screenresolution get 22:00:27 INFO - 2016-11-17 22:00:27.641 screenresolution[1624:7424] Display 0: 1600x1200x32@60 22:00:27 INFO - 2016-11-17 22:00:27.647 screenresolution[1625:7426] starting screenresolution argv=screenresolution list 22:00:27 INFO - Available Modes on Display 0 22:00:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:00:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:00:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:00:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:00:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:00:27 INFO - Intel Iris: 22:00:27 INFO - Chipset Model: Intel Iris 22:00:27 INFO - Type: GPU 22:00:27 INFO - Bus: Built-In 22:00:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:00:27 INFO - Vendor: Intel (0x8086) 22:00:27 INFO - Device ID: 0x0a2e 22:00:27 INFO - Revision ID: 0x0009 22:00:27 INFO - Displays: 22:00:27 INFO - Display: 22:00:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:00:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:00:27 INFO - Display Serial Number: 1600x1200 60 22:00:27 INFO - Main Display: Yes 22:00:27 INFO - Mirror: Off 22:00:27 INFO - Online: Yes 22:00:27 INFO - Rotation: Supported 22:00:27 INFO - Return code: 0 22:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:00:27 INFO - Python 2.7.3 22:00:27 INFO - Return code: 0 22:00:27 INFO - grabbing minidump binary from tooltool 22:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:00:27 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 0x10638cd30>, '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 0x1061f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106384b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:00:27 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 22:00:27 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 22:00:27 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 22:00:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:00:28 INFO - Return code: 0 22:00:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:00:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:00:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:00:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:00:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:00:28 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 22:00:28 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0210 --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 22:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V7WfBdXOfR/Render', 22:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:28 INFO - 'HOME': '/Users/cltbld', 22:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:28 INFO - 'LOGNAME': 'cltbld', 22:00:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:00:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:28 INFO - 'MOZ_NO_REMOTE': '1', 22:00:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:00:28 INFO - 'NO_EM_RESTART': '1', 22:00:28 INFO - 'PAGER': '/bin/cat', 22:00:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:28 INFO - 'PWD': '/builds/slave/test', 22:00:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:28 INFO - 'SHELL': '/bin/bash', 22:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u7YlDbSWHn/Listeners', 22:00:28 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 22:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:28 INFO - 'USER': 'cltbld', 22:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:28 INFO - 'XPC_FLAGS': '0x0', 22:00:28 INFO - 'XPC_SERVICE_NAME': '0', 22:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:28 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 22:00:28 INFO - mozversion application_buildid: 20161117192228 22:00:28 INFO - mozversion application_changeset: 8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a 22:00:28 INFO - mozversion application_display_name: Nightly 22:00:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:00:28 INFO - mozversion application_name: Firefox 22:00:28 INFO - mozversion application_remotingname: firefox 22:00:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:00:28 INFO - mozversion application_vendor: Mozilla 22:00:28 INFO - mozversion application_version: 53.0a1 22:00:28 INFO - mozversion platform_buildid: 20161117192228 22:00:28 INFO - mozversion platform_changeset: 8e476f8bd52d13cc1648e15ea2b72641c2d7bd8a 22:00:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:00:28 INFO - mozversion platform_version: 53.0a1 22:00:28 INFO - using testdate: 1479448828 22:00:28 INFO - actual date: 1479448828 22:00:28 INFO - starting webserver on 'localhost:49242' 22:00:28 INFO - SUITE-START | Running 2 tests 22:00:28 INFO - TEST-START | dromaeo_css 22:00:28 INFO - operating with platform_type : mac_ 22:00:28 INFO - Initialising browser for dromaeo_css test... 22:00:28 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmppXkZoS/profile http://localhost:49242/getInfo.html) 22:00:31 INFO - PROCESS | 1635 | 2016-11-17 22:00:31.424 plugin-container[1636:7580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 22:00:31 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:31 INFO - PROCESS | 1635 | 2016-11-17 22:00:31.426 plugin-container[1636:7580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 22:00:31 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:31 INFO - PROCESS | 1635 | 2016-11-17 22:00:31.426 plugin-container[1636:7580] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:00:31 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 22:00:31 INFO - PROCESS | 1635 | colorDepth:24 22:00:31 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 22:00:31 INFO - PROCESS | 1635 | __metrics 22:00:31 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 22:00:32 INFO - TEST-INFO | 1635: exit 0 22:00:32 INFO - Browser initialized. 22:00:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:00:32 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmppXkZoS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:35 INFO - PROCESS | 1638 | 2016-11-17 22:00:35.326 plugin-container[1639:7713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 22:00:35 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:35 INFO - PROCESS | 1638 | 2016-11-17 22:00:35.327 plugin-container[1639:7713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:00:35 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:35 INFO - PROCESS | 1638 | 2016-11-17 22:00:35.327 plugin-container[1639:7713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:00:45 INFO - PROCESS | 1638 | RSS: Main: 242999296 22:00:45 INFO - PROCESS | 1638 | 22:01:34 INFO - PROCESS | 1638 | 1479448894684 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] 22:03:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 22:03:49 INFO - PROCESS | 1638 | RSS: Main: 248279040 22:03:49 INFO - PROCESS | 1638 | 22:06:52 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 22:06:52 INFO - PROCESS | 1638 | RSS: Main: 259043328 22:06:52 INFO - PROCESS | 1638 | 22:09:55 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 22:09:55 INFO - PROCESS | 1638 | RSS: Main: 264486912 22:09:55 INFO - PROCESS | 1638 | 22:12:57 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 22:12:58 INFO - PROCESS | 1638 | RSS: Main: 264122368 22:12:58 INFO - PROCESS | 1638 | 22:16:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 22:16:01 INFO - PROCESS | 1638 | RSS: Main: 268505088 22:16:01 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 22:19:04 INFO - PROCESS | 1638 | RSS: Main: 271155200 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | __start_tp_report 22:19:04 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 22:19:04 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 22:19:04 INFO - PROCESS | 1638 | |i|pagename|runs| 22:19:04 INFO - PROCESS | 1638 | |0;dojo.html;2705.4994825957187;3283.1135593389786;3365.4110530657135;3398.8640454381825;3413.8805141820035;5641.774329026834;5708.1437784332375;5716.942830571698;5720.542356611466;5731.914021289674;5657.830265092043;5720.2849643794525;5756.222909907172;5814.680192589406;5847.912281315784;5144.819931302405;5166.43169251382;5195.636305458619;5279.815206467774;5321.36143662761;4825.2279635258365;4901.77455636091;5299.072662284085;5311.415744268127;5333.146696528556;5136.6661167024395;5275;5279.683219006853;5312.787488500494;5313.256144139824;5090.287359769635;5126.154184559543;5174.844754657329;5191.32512773337;5229.607779416559;5148.3564554430695;5170.37955445349;5187.844364669066;5207.0887594670785;5243.397009343936;5077.619178561586;5276.707206734336;5754.3094828620415;5764.308283006024;5777.768889244425;6127.158986117662;6137.131844484721;6216.160818289518;6250.218722659668;6265.498760099181;6274.396125607867;6288.465480434169;6306.306306306303;6310.810675679737;6331.430171284606;5720.8283751487525;5816.214811000503;5824.592278540504;5848.1227815827115;5880.94119058804;6227.688615569204;6245.437910587978;6246.593971391845;6265.4361107500545;6322.968385158137;6199.442050215412;6225.813225603149;6227.9377206227355;6237.157983672237;6239.781607643801;6141.263048434216;6299.905501417483;6304.275008374004;6312.210973628296;6327.683615819191;6119.663418511936;6124.136831511551;6181.783637572753;6198.287196972406;6234.563580549318;6212.4408803207925;6240.968795155995;6300.905486417708;6321.020241862519;6337.556371053982;4926.802927882916;4958.7024778513405;4977.253411988231;4994.750262486934;5012.147934851084;4383.715058521186;4410.603045725836;4463.24124898768;4493.662975276868;4511.390962220124;5664.631798933056;5794.652320860761;5819.76720931158;5825.2135961644635;5825.533957283407;6191.040388739752;6224.253089629183;6249.593776404519;6257.561970662101;6260.436560709558;5815.88368232633;5849.09339052439;5863.970680146657;5873.383294754075;5875.294964604275;4619.2378257587125;5428.9185662215095;5431.158170483582;5432.565394768409;5449.373322067933;6194.907076393948;6207.503399728102;6226.5641405102115;6238.625682459067;6262.029385445263;6133.08984587437;6221.844453888689;6235.875282494234;6283.088952101828;6302.621842689453;5033.320501732219;5289.862679523767;5336.786528538815;5520.889582208413;6268.843278918063;6094.902917474897;6125.571210015256;6133.110698948717;6256.124142619946;6270.623762574261;4584.243599805995;6088.269407671108;6238.656873871981;6238.875222495434;6257.217847769029;6092.025275955826;6108.725107370268;6159.84600384994;6177.96911015442;6183.288921774054;5070.4929507049;6126.969365153146;6155.47678447337;6164;6173.9691301545;5647.29408823897;5827.155062515996;5828;5884.7940322088525;5985.25184351956;6070.575059745775;6093.390660934048;6108.584917381041;6198;6211.875762484816;6012.882608478693;6024.867069123943;6045.335013148638;6060.90908636362;6122.877542449215 22:19:04 INFO - PROCESS | 1638 | |1;ext.html;472.0605994071796;477.41755058828215;512.9842911036149;521.1219095823535;545.3428618514693;29262.41475170498;30886.69113308866;39053.609463905384;39985.80007099968;40032.99999999993;41242.96892577687;41555;41705;41786;41789.955251118736;9367.156955873987;9485.241180705561;9522.190613797835;9538.380005299634;9557.569909354095;9423.34036617437;9437.669681561147;9497.335186536946;9507.667231646894;9512.048795120501;9386.671466498674;9419.952900235488;9458;9479.241660667165;9481.09929556691;9444.763880902998;9463;9489.193418559431;9491.955884852661;9502.382345147544;9324.720258392259;9410.106039926197;9424.670136545188;9426.858597121049;9438.905610943872;39186.80406597977;39568.60431395678;39584;39598.80200598978;39684;3266.575345205108;3371.831408429569;3787.3561494545993;3979.184267225212;4108.548059713429;29172.27069323284;29951.550726739115;30125;30148.397032059234;30958.53562196569;2915.8336665333823;3153.1171272043866;3206.118317462693;3276.2137087099163;3317.1209629448217;257042;301732;318721.81278187153;340515;343997.28001360083;56282.71858640681;56489;56547.43452565504;56644.43355566391;56884.43115568874;59421;59919.700401497714;59934.70032649809;60008.699956499935;60029.39970600238;15738.291776870068;15941;15962;15984.120873351845;15988.840111598734;12270.141090123785;12317;12392.876071239352;12401.441935112942;12423.565175218824;13268.601941941757;14578.562643120511;15750.76373854393;16082.919585401998;16213;2792.3298408382248;3119.9688003119677;3258.6741325867697;5238.816641417532;6070.544709146831;880.6169316347409;904.1591320072264;915.688665853613;975.8682577851899;977.9070988256104;26287.868560657073;27130.728692713215;29363.412731745244;29427.558586621217;30335.393292134475;935.3826474526779;972.7081875437341;981.5043403081398;1101.5758932810766;1106.8654629005334;14105.717885642434;14950.775738363933;15421.383144674312;15431.38274469031;15744.212789360487;611358;613831;618632;619413;620296.8985155046;56519.43480565224;56611.43388566144;57147;57349.85300293971;57455.71272143696;1269.6381531263546;1291.8320618319747;1305.5039085147582;1314.18520517278;1320.1155225998605;27628.58557121685;28533.42933141367;29373.853130735064;30043.549346760254;30157.09528714142;40365.79817100896;49198.01603967829;49401;49800;49892.750536247084;562594.1870290622;563080.1845990744;565062;565223;568582.1570892119;25107.372315692253;25654.230373088838;25856.095036673625;25913.352166195997;25936.610950835377;37337.81331093327;44993.775031124635;45798.54201458076;45866.312005319276;46321.76839115783;36777.448338274415;37205.44191837179;37336;38380;38654.420183696704;37175.69885054119;37392;38282;38394;38596.42105368478;36445;36607;37562.43656345212;37591.43612845755;37892;26918.59622105709;31583.526247105852;31916.6808331923;33250.33499330048;33467.66532334645;24539.631905521073;24620.753792461845;24852.130175443774;24910.0035998558;25176.496470070862 22:19:04 INFO - PROCESS | 1638 | |2;jquery.html;302.4434503919569;334.54334832953026;335.7075260248044;341.9623841377449;373.7757345592643;18715.81284187158;18787.2485100596;19359.612807743837;19885.801141988577;19945;18982.905085474587;19250.22999080035;19359;19757.91331476768;19906.900465497652;5374.704391258489;5742.511886489653;5769.884602307951;5778.104393818965;5781.82654520365;5399.649022813524;5666.206731057655;5684.175794509793;5730.828075157752;5748.511376533;5679.346875109353;5690.146478028309;5693.3452652945025;5697.943020569783;5699.743511541969;5469.890602187954;5667.4615911488345;5813.273340832396;5853.50245229156;5906.320773111104;5479.123340265538;5690.5447564194765;5691.9146212806845;5711.371749107595;5713.0287851065;20017.699734503993;22452.663210051862;23667.526649466916;23698.526029479486;24330.513389732107;2087.2381580723077;2109.7573779015456;2158.549662103202;2197.6593627987686;2880.8908570200415;9738.707838764849;9748.415095094246;9768.316217864682;10259.076683098558;10387.688369348856;1593.6254980079673;1832.2854086906118;1895.2419032387018;1899.5441094137445;1900.277894400119;114510.42744786222;119465.80534194634;119607;121676;122575;7739.613019349123;9041.50271735061;9889.357191782656;9913.553890074963;11532.827007594893;12170.208936419105;12222.75554488923;12227;12231.449584768707;12249.816252756216;6638.535302528777;6924.619145947021;7622.5426474411715;7829;7903.762887113396;5809.970950145223;6715.529912906148;8028.4781489203015;8106.067802202822;8258.339332853357;3773.64150405711;3828.464015037897;3861.2663593917064;3883.2427676603356;3925.7448265862627;1599.720048991445;1651.8348165183386;1955.491572191212;1959.7256384106236;1968.320929279425;669.625009994395;795.8845967334704;800.667722894992;834;930.901536187292;16470.75293870593;19642.803571964385;20725.481862953246;20823.687644685342;21087.367378978655;899.4288626722079;901.7069452427857;916.1479823763964;959.4003747657714;965.3001573859036;10417.791644167075;10827;10914.890851091546;10939;10940.179486538542;554888;572516;574242;586040;587432;117627;123494.14758778625;129571.35214323868;135034.32482837522;137152;819.491915012684;961.485605201214;985.4875464758286;1016.9288149829441;1018.954147063384;18049.548761281072;18472.353467628567;19005.809941900403;19019.524511887314;19032.33386831454;54990.450095500135;55738.442615574946;55764.88470230492;56113.15830262467;56429.43570564242;81130;81636.18363816285;81824;82459;83145;7644.923550764421;7762.5730584818375;7820.687172513263;7908.130105688255;7992.800179995546;8948.910510894808;11957.641270761891;11974.58088966882;12230.510779568718;12598.677138900284;8218.753437396888;10519.631812886511;10576.576936922436;10787.67636970892;11008.339499630049;10690.358578485315;10798.13614910821;10836.857311379612;10921.617743378943;11123.388213648326;10812.297200681931;10946;11099.278546894426;11128.833067503832;11256.268342557707;6716.093327400934;7131;7674.309312161932;7764.767056988299;8091.595420229083;9185.357025008185;9602.423854568748;11130.721731956766;11136.33182009082;11260.83108753353 22:19:04 INFO - PROCESS | 1638 | |3;mootools.html;1455.7452445821982;1486.0043770673647;1495.6335697754048;1705.9402920897771;1714.6742118997397;7463.2163622819635;7734.72928447503;7821.960890195548;8211.589420528971;8223.30101941335;7937.880931786013;8149.062857771358;8262.256396924291;8411.327093832493;8420.368472364567;2298.321995011476;2303.435658263728;2319.2926157521947;2323.663068855015;2331.2889568681544;2299.1378233162563;2333.3349995251356;2353.470469144446;2354.8822558872075;2359.634256690216;2293.885305734715;2305.1816605105196;2316.81465482761;2322.6632138339933;2328.580855446019;2269.137727663494;2282.5206706591553;2302.2172461363134;2304.5506126305377;2322.047960336254;2302.7697230277004;2323.0591610397814;2326;2331.0908745589236;2333.976660233393;8186.345092392654;8292.958535207346;8337.207965243353;8341.791455213608;8350.206730360605;4404.4494438195225;4668.136394766979;4766.475687674353;5172.534471897509;5174.197999310113;3262.9021129366156;3438.449848024329;3505.1237190702323;3616.7649102808496;3622.130688634709;831.9126491718343;1047.6909311752975;1058.8358804385332;1064.8615679961633;1086.788076325117;9901.702948911545;10088.243381746324;10809.459527023697;10893.564257429693;10894.727631809188;3935.7995811277265;4422.800973956179;4529.139463501924;4647.18674232008;4698;9226.953865230766;9383.812323753487;9385.249180065579;9402.34183607141;9408.952955235316;5181.637285390062;5184.766685499161;5193.792248310101;5196.168613021899;5197.142471492161;4264.445622069174;4335.544767799399;4647.209974304376;4712.50518695532;4759.428868535798;3165.9050228493184;3737.495438115874;3828.2152158807935;3841.770633397286;3864.2851072551666;1250.5185503581006;1256.5612373831912;1259.137490818792;1268.8731126887421;1269.4668239339499;780.7735756630627;790.8932294140333;825.9710910118117;868.0572759252886;879.2394578689388;5373.623846330719;5552.001279539362;5713.514351280104;5760.567957403212;5851.531877449794;747.137056699513;781.281221276427;869.9782505437415;898.6899519665704;908.5321059654283;3742.5321834770652;3774.962250377516;3775.4336849472797;3832.463455116286;3873.7094717895707;9600.375975561567;10085.29402941787;10090.596376144871;10098.646547370954;10134.189264858793;10053.346532475513;10080.193584513221;10091.142252908438;10095.141912937335;10099.192064634812;6400.327965563549;7717.382609391235;7868.331191848757;7872.44892857506;7896.644650990719;4998.850034498971;5078.21287700407;5271.262023316662;5667.489925906688;5860.09168578871;9050.275977921881;9146.679866204651;9185.08149185103;9221.262299016196;9390.483523406278;9399.389039712396;9406.294527910297;9454.858177127211;9526.666566670134;9640.662576809778;6551.689662067511;6811.386975172259;7104.076470058859;7174.713011479483;7264.673089711081;7841.490303130278;7964.20357964199;7969.203079691984;7975.4018448615425;7995.440319177602;6969.825754356181;7075.292470752884;7122.287771222836;7151.141862976476;7221.350078492961;6838.076859623887;6927.168739751262;6982.997311156251;7051.048108505286;7154.67803948834;6584.934150658432;6637.966810165918;6706.83232919181;6709.463242940651;6714.630695311805;4287.933815598025;4348.434703488526;4562.977185114053;4650.767461626972;4742.762861856824;4687.484376718627;4731.739754313408;4807.663463557517;4809.134355815987;4944.826931057396 22:19:04 INFO - PROCESS | 1638 | |4;prototype.html;280.2880683065015;299.4938553844004;307.9584256125422;308.0005382533677;308.5864940979088;4614.792334344954;5431.782728690852;5437.429069947658;5456.672599644019;5474.124140137579;4717.693349932252;5185.45552716964;5224.320838291017;5340.412554618989;5409.702466364349;3155.794873333226;3897.4153876918404;4013.1371755072655;4059.8579049733116;4082.6937979651498;3236.7896086754404;3995.760254384746;4068.698016634678;4092.774897380643;4114.991827002374;4060.5485337463215;4063.719928222615;4063.740240525432;4077.9592204077876;4087.305158123126;3853.595372485877;4041.6766658667234;4092.9386059209137;4093.85671501496;4112.753234805907;3504.6144924058335;3510.4207805712026;3512.209752805624;3519.7008254298157;3526.2418580005424;5158.613104017176;5169.483051694838;5185.118529849935;5210.505002024802;5231.476852314738;4292.978535107336;4688.2733176357715;4710.835120770791;4711.3639658646325;4725.952740472586;4955.479674634147;5631.8592035199035;5674.574406919498;5694.402087780806;5708.600397972144;3553.6446355364515;3837.9040523986846;3850;3858.247641709868;3877.3408520551575;5816.331121920991;6007.197840647832;6014.278286605635;6024.668643224621;6103.72533236001;3279.8032118072992;3840.846366145323;3931.901702457404;3942.664873485786;3981.0843505993785;3240.918977025546;3986.6412022918075;3998.640122388999;4048.0689441428635;4070.287699652549;4298.742075475481;4367.45406824147;4699.976500117477;4703.623710103184;4729.527047295243;4804.35141255926;5610.354809196913;5755.625884317506;5810.331811841692;5843.766249350064;1107.9058280046286;1313.8686131386785;1314.6918815778363;1324.079764563619;1325.7613629546581;822.4243029879109;838.7357982235576;878.3982971664431;895.3642913531336;895.9964839379805;4940.925886111711;5288.629795914326;5438.91841622376;5440.463698595865;5452.618316717871;5081.720505372166;5827.621204621686;5935.347111817697;5962.85092872673;5968.4634030536845;4305.655547556188;4992.151334273182;5243.4232234454175;5432.890155112353;5573.916391254134;2898.826070435781;2921.7078292170613;2924.5174546200064;2927.7218664226866;2964.688707685705;5279.604029697711;5531.778728850801;5803.303603567514;5823.650580965155;5825.417458254141;4642.72143671374;5392.20582066591;5521.503064724194;5956.987312156857;6121.632702037892;5414.401087478032;5994.010988186988;6167.784127555514;6252.155269352343;6311;4884.658073934933;5023.447420783784;5486.506214440719;5978.521718262616;6023.457888789854;5307.6730817295675;5782.074868021096;5842.853928651818;5886.38192989747;6006.699665016819;5762.394948530344;6272.686365681607;6294.5279104068295;6297.811065668037;6350.90473642905;3198;3376.6285708572527;3933.97716593682;3942.5071866016747;3960.861369852041;4851.272309153655;5857.736401861841;5904.3210030847;5909.55678324136;5936.37668044849;5089.160288552347;5348.545373643284;5798.10129429939;5869.53043756507;5870.882582348415;5161.741912904265;5420.17058506277;5649.051077378298;5769;5811.215485909517;5007.222436035155;5235.214717792362;5443.918341224964;5652.519535839499;5691.8292451226525;4983;5367.758450869798;5427.402985671652;5486.231927530069;5501.889962200654;4711.316859055486;5141.943222710796;5188.558972487381;5606.915896261641;5619.325680918316 22:19:04 INFO - PROCESS | 1638 | |5;yui.html;672.5628341577975;688.1741909708351;734.174054189037;738.3428748413916;759.8328367759091;13222.074454788144;13240.933795331033;13856.237906915116;14676.77984830227;14682.559523214308;12962.74074518509;12988;13129.80305295419;13133.868661313409;13145.145565538234;628.9874202515936;664.0238848892145;785.8428314337156;819.7622689420061;823.333100188846;671.4393481443001;687.2062767503542;810.2019510781876;833.9916600834005;837.4347315561971;873.9563021848913;880.1330689271063;880.7137680253911;881.4711173296035;885.3227281129941;841.8568843296654;867.6832955971057;871.729763773226;875.0855356152804;875.6935072724559;642.2357394700339;654.5712558274338;654.9574277671984;656.0487293424553;660.1286302081255;13328.266945318004;13715.862841371558;13749.450021999108;13760.518381856687;13991.160530368108;13051.673708157277;13055.086143969926;13115.606531804062;13144.342782860818;13344.866551334459;10697.197710171691;10829.891701082966;10877.238593298393;11230.438478076063;11432.256903301259;3632.6185750496347;3678.2459595782802;3724.832382542792;3786.3941769317053;3796.5064541609413;85551.5722421384;87067.69398459028;88105.55947220288;88853;90834;9808.950955245178;10021.198304135653;10157.288989770645;10199.592016319264;10412.21908356861;16482.34070637185;18334.81665183331;19056.142473588443;19127.426177214707;20175.798242017685;7602.42981776369;7603.695852165962;7612.467127301146;7692.153863075057;7936.484128531627;4257.169851878916;4276.799832399749;4327.394164816928;4334.263175260214;4347.456567929009;3270.640229574745;3356.412627790127;3362.1930736623035;3502.404591219499;3704.2961837250837;570.7183693760875;655.5542231282774;658.8418779492887;662.8972509261072;673.3603077076797;605;634.3941535833268;646.9256035555971;662.2615783401569;680.792358330704;8542.829143417097;8644.438111522732;8686.39195256326;8819.779505512286;8837.690680826141;598.7515181199753;620.5687047502033;646.8027251688186;654.5450911616381;666.4638009388407;3651.908702282464;4083.285425050664;4240.194363071007;4271.466066741657;4274.166537525152;83281.58359208165;96628;104919;109408.90591094147;110552;81752;83655.3268934618;90491;90753;91781.54109229411;75588;77168.61415692886;83973.16026839775;84116.15883841083;85246.14753852507;7607.923920760722;8888.33337499703;9080.910290764887;9301.906980930375;9320.72037838866;37789.81105094457;52450.47549524456;52604.736976314874;54340;57165.85668286694;53365.93268134693;65373.67313163404;83111;84553.15446845452;85087.14912850792;12276;12741.745165096832;12917.160384574972;13052.412641430969;13531.797023044466;14114.717705645624;14259.786103208253;14352.928235358757;14430.567082987527;14875.256237187881;11644.53421863116;11942.582009629621;12882;12923.332018159268;12991.545295914597;13307.66730831737;13401;13404.798928016282;13419.463221471031;14011.441579057913;12574.811377829523;12793.488260469478;12794.599424203463;13071.673208169492;13138.211707297593;8765.430247033923;9825.557849896915;10235;10702.304350217211;10746.226872566252;8580.34399261085;8801.919477519792;10195.929427410016;10739.624113156002;11204.943975280072 22:19:04 INFO - PROCESS | 1638 | __end_tp_report 22:19:04 INFO - PROCESS | 1638 | __start_cc_report 22:19:04 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,361 22:19:04 INFO - PROCESS | 1638 | __end_cc_report 22:19:04 INFO - PROCESS | 1638 | __startTimestamp1479449944685__endTimestamp 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | ------- Summary: start ------- 22:19:04 INFO - PROCESS | 1638 | Number of tests: 6 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5710.03 Median:5875.29 stddev:670.10 (11.4%) stddev-sans-first:629.34 22:19:04 INFO - PROCESS | 1638 | Values: 2705.5 3283.1 3365.4 3398.9 3413.9 5641.8 5708.1 5716.9 5720.5 5731.9 5657.8 5720.3 5756.2 5814.7 5847.9 5144.8 5166.4 5195.6 5279.8 5321.4 4825.2 4901.8 5299.1 5311.4 5333.1 5136.7 5275.0 5279.7 5312.8 5313.3 5090.3 5126.2 5174.8 5191.3 5229.6 5148.4 5170.4 5187.8 5207.1 5243.4 5077.6 5276.7 5754.3 5764.3 5777.8 6127.2 6137.1 6216.2 6250.2 6265.5 6274.4 6288.5 6306.3 6310.8 6331.4 5720.8 5816.2 5824.6 5848.1 5880.9 6227.7 6245.4 6246.6 6265.4 6323.0 6199.4 6225.8 6227.9 6237.2 6239.8 6141.3 6299.9 6304.3 6312.2 6327.7 6119.7 6124.1 6181.8 6198.3 6234.6 6212.4 6241.0 6300.9 6321.0 6337.6 4926.8 4958.7 4977.3 4994.8 5012.1 4383.7 4410.6 4463.2 4493.7 4511.4 5664.6 5794.7 5819.8 5825.2 5825.5 6191.0 6224.3 6249.6 6257.6 6260.4 5815.9 5849.1 5864.0 5873.4 5875.3 4619.2 5428.9 5431.2 5432.6 5449.4 6194.9 6207.5 6226.6 6238.6 6262.0 6133.1 6221.8 6235.9 6283.1 6302.6 5033.3 5289.9 5336.8 5520.9 6268.8 6094.9 6125.6 6133.1 6256.1 6270.6 4584.2 6088.3 6238.7 6238.9 6257.2 6092.0 6108.7 6159.8 6178.0 6183.3 5070.5 6127.0 6155.5 6164.0 6174.0 5647.3 5827.2 5828.0 5884.8 5985.3 6070.6 6093.4 6108.6 6198.0 6211.9 6012.9 6024.9 6045.3 6060.9 6122.9 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:63316.43 Median:38854.01 stddev:138130.68 (355.5%) stddev-sans-first:138437.58 22:19:04 INFO - PROCESS | 1638 | Values: 472.1 477.4 513.0 521.1 545.3 29262.4 30886.7 39053.6 39985.8 40033.0 41243.0 41555.0 41705.0 41786.0 41790.0 9367.2 9485.2 9522.2 9538.4 9557.6 9423.3 9437.7 9497.3 9507.7 9512.0 9386.7 9420.0 9458.0 9479.2 9481.1 9444.8 9463.0 9489.2 9492.0 9502.4 9324.7 9410.1 9424.7 9426.9 9438.9 39186.8 39568.6 39584.0 39598.8 39684.0 3266.6 3371.8 3787.4 3979.2 4108.5 29172.3 29951.6 30125.0 30148.4 30958.5 2915.8 3153.1 3206.1 3276.2 3317.1 257042.0 301732.0 318721.8 340515.0 343997.3 56282.7 56489.0 56547.4 56644.4 56884.4 59421.0 59919.7 59934.7 60008.7 60029.4 15738.3 15941.0 15962.0 15984.1 15988.8 12270.1 12317.0 12392.9 12401.4 12423.6 13268.6 14578.6 15750.8 16082.9 16213.0 2792.3 3120.0 3258.7 5238.8 6070.5 880.6 904.2 915.7 975.9 977.9 26287.9 27130.7 29363.4 29427.6 30335.4 935.4 972.7 981.5 1101.6 1106.9 14105.7 14950.8 15421.4 15431.4 15744.2 611358.0 613831.0 618632.0 619413.0 620296.9 56519.4 56611.4 57147.0 57349.9 57455.7 1269.6 1291.8 1305.5 1314.2 1320.1 27628.6 28533.4 29373.9 30043.5 30157.1 40365.8 49198.0 49401.0 49800.0 49892.8 562594.2 563080.2 565062.0 565223.0 568582.2 25107.4 25654.2 25856.1 25913.4 25936.6 37337.8 44993.8 45798.5 45866.3 46321.8 36777.4 37205.4 37336.0 38380.0 38654.4 37175.7 37392.0 38282.0 38394.0 38596.4 36445.0 36607.0 37562.4 37591.4 37892.0 26918.6 31583.5 31916.7 33250.3 33467.7 24539.6 24620.8 24852.1 24910.0 25176.5 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34144.56 Median:2073.71 stddev:96451.35 (4651.2%) stddev-sans-first:96688.45 22:19:04 INFO - PROCESS | 1638 | Values: 302.4 334.5 335.7 342.0 373.8 18715.8 18787.2 19359.6 19885.8 19945.0 18982.9 19250.2 19359.0 19757.9 19906.9 5374.7 5742.5 5769.9 5778.1 5781.8 5399.6 5666.2 5684.2 5730.8 5748.5 5679.3 5690.1 5693.3 5697.9 5699.7 5469.9 5667.5 5813.3 5853.5 5906.3 5479.1 5690.5 5691.9 5711.4 5713.0 20017.7 22452.7 23667.5 23698.5 24330.5 2087.2 2109.8 2158.5 2197.7 2880.9 9738.7 9748.4 9768.3 10259.1 10387.7 1593.6 1832.3 1895.2 1899.5 1900.3 114510.4 119465.8 119607.0 121676.0 122575.0 7739.6 9041.5 9889.4 9913.6 11532.8 12170.2 12222.8 12227.0 12231.4 12249.8 6638.5 6924.6 7622.5 7829.0 7903.8 5810.0 6715.5 8028.5 8106.1 8258.3 3773.6 3828.5 3861.3 3883.2 3925.7 1599.7 1651.8 1955.5 1959.7 1968.3 669.6 795.9 800.7 834.0 930.9 16470.8 19642.8 20725.5 20823.7 21087.4 899.4 901.7 916.1 959.4 965.3 10417.8 10827.0 10914.9 10939.0 10940.2 554888.0 572516.0 574242.0 586040.0 587432.0 117627.0 123494.1 129571.4 135034.3 137152.0 819.5 961.5 985.5 1016.9 1019.0 18049.5 18472.4 19005.8 19019.5 19032.3 54990.5 55738.4 55764.9 56113.2 56429.4 81130.0 81636.2 81824.0 82459.0 83145.0 7644.9 7762.6 7820.7 7908.1 7992.8 8948.9 11957.6 11974.6 12230.5 12598.7 8218.8 10519.6 10576.6 10787.7 11008.3 10690.4 10798.1 10836.9 10921.6 11123.4 10812.3 10946.0 11099.3 11128.8 11256.3 6716.1 7131.0 7674.3 7764.8 8091.6 9185.4 9602.4 11130.7 11136.3 11260.8 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5382.57 Median:5038.53 stddev:2958.26 (58.7%) stddev-sans-first:2951.84 22:19:04 INFO - PROCESS | 1638 | Values: 1455.7 1486.0 1495.6 1705.9 1714.7 7463.2 7734.7 7822.0 8211.6 8223.3 7937.9 8149.1 8262.3 8411.3 8420.4 2298.3 2303.4 2319.3 2323.7 2331.3 2299.1 2333.3 2353.5 2354.9 2359.6 2293.9 2305.2 2316.8 2322.7 2328.6 2269.1 2282.5 2302.2 2304.6 2322.0 2302.8 2323.1 2326.0 2331.1 2334.0 8186.3 8293.0 8337.2 8341.8 8350.2 4404.4 4668.1 4766.5 5172.5 5174.2 3262.9 3438.4 3505.1 3616.8 3622.1 831.9 1047.7 1058.8 1064.9 1086.8 9901.7 10088.2 10809.5 10893.6 10894.7 3935.8 4422.8 4529.1 4647.2 4698.0 9227.0 9383.8 9385.2 9402.3 9409.0 5181.6 5184.8 5193.8 5196.2 5197.1 4264.4 4335.5 4647.2 4712.5 4759.4 3165.9 3737.5 3828.2 3841.8 3864.3 1250.5 1256.6 1259.1 1268.9 1269.5 780.8 790.9 826.0 868.1 879.2 5373.6 5552.0 5713.5 5760.6 5851.5 747.1 781.3 870.0 898.7 908.5 3742.5 3775.0 3775.4 3832.5 3873.7 9600.4 10085.3 10090.6 10098.6 10134.2 10053.3 10080.2 10091.1 10095.1 10099.2 6400.3 7717.4 7868.3 7872.4 7896.6 4998.9 5078.2 5271.3 5667.5 5860.1 9050.3 9146.7 9185.1 9221.3 9390.5 9399.4 9406.3 9454.9 9526.7 9640.7 6551.7 6811.4 7104.1 7174.7 7264.7 7841.5 7964.2 7969.2 7975.4 7995.4 6969.8 7075.3 7122.3 7151.1 7221.4 6838.1 6927.2 6983.0 7051.0 7154.7 6584.9 6638.0 6706.8 6709.5 6714.6 4287.9 4348.4 4563.0 4650.8 4742.8 4687.5 4731.7 4807.7 4809.1 4944.8 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4597.09 Median:5185.29 stddev:1446.18 (27.9%) stddev-sans-first:1413.48 22:19:04 INFO - PROCESS | 1638 | Values: 280.3 299.5 308.0 308.0 308.6 4614.8 5431.8 5437.4 5456.7 5474.1 4717.7 5185.5 5224.3 5340.4 5409.7 3155.8 3897.4 4013.1 4059.9 4082.7 3236.8 3995.8 4068.7 4092.8 4115.0 4060.5 4063.7 4063.7 4078.0 4087.3 3853.6 4041.7 4092.9 4093.9 4112.8 3504.6 3510.4 3512.2 3519.7 3526.2 5158.6 5169.5 5185.1 5210.5 5231.5 4293.0 4688.3 4710.8 4711.4 4726.0 4955.5 5631.9 5674.6 5694.4 5708.6 3553.6 3837.9 3850.0 3858.2 3877.3 5816.3 6007.2 6014.3 6024.7 6103.7 3279.8 3840.8 3931.9 3942.7 3981.1 3240.9 3986.6 3998.6 4048.1 4070.3 4298.7 4367.5 4700.0 4703.6 4729.5 4804.4 5610.4 5755.6 5810.3 5843.8 1107.9 1313.9 1314.7 1324.1 1325.8 822.4 838.7 878.4 895.4 896.0 4940.9 5288.6 5438.9 5440.5 5452.6 5081.7 5827.6 5935.3 5962.9 5968.5 4305.7 4992.2 5243.4 5432.9 5573.9 2898.8 2921.7 2924.5 2927.7 2964.7 5279.6 5531.8 5803.3 5823.7 5825.4 4642.7 5392.2 5521.5 5957.0 6121.6 5414.4 5994.0 6167.8 6252.2 6311.0 4884.7 5023.4 5486.5 5978.5 6023.5 5307.7 5782.1 5842.9 5886.4 6006.7 5762.4 6272.7 6294.5 6297.8 6350.9 3198.0 3376.6 3934.0 3942.5 3960.9 4851.3 5857.7 5904.3 5909.6 5936.4 5089.2 5348.5 5798.1 5869.5 5870.9 5161.7 5420.2 5649.1 5769.0 5811.2 5007.2 5235.2 5443.9 5652.5 5691.8 4983.0 5367.8 5427.4 5486.2 5501.9 4711.3 5141.9 5188.6 5606.9 5619.3 22:19:04 INFO - PROCESS | 1638 | 22:19:04 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19728.93 Median:52985.33 stddev:28626.52 (54.0%) stddev-sans-first:28671.07 22:19:04 INFO - PROCESS | 1638 | Values: 672.6 688.2 734.2 738.3 759.8 13222.1 13240.9 13856.2 14676.8 14682.6 12962.7 12988.0 13129.8 13133.9 13145.1 629.0 664.0 785.8 819.8 823.3 671.4 687.2 810.2 834.0 837.4 874.0 880.1 880.7 881.5 885.3 841.9 867.7 871.7 875.1 875.7 642.2 654.6 655.0 656.0 660.1 13328.3 13715.9 13749.5 13760.5 13991.2 13051.7 13055.1 13115.6 13144.3 13344.9 10697.2 10829.9 10877.2 11230.4 11432.3 3632.6 3678.2 3724.8 3786.4 3796.5 85551.6 87067.7 88105.6 88853.0 90834.0 9809.0 10021.2 10157.3 10199.6 10412.2 16482.3 18334.8 19056.1 19127.4 20175.8 7602.4 7603.7 7612.5 7692.2 7936.5 4257.2 4276.8 4327.4 4334.3 4347.5 3270.6 3356.4 3362.2 3502.4 3704.3 570.7 655.6 658.8 662.9 673.4 605.0 634.4 646.9 662.3 680.8 8542.8 8644.4 8686.4 8819.8 8837.7 598.8 620.6 646.8 654.5 666.5 3651.9 4083.3 4240.2 4271.5 4274.2 83281.6 96628.0 104919.0 109408.9 110552.0 81752.0 83655.3 90491.0 90753.0 91781.5 75588.0 77168.6 83973.2 84116.2 85246.1 7607.9 8888.3 9080.9 9301.9 9320.7 37789.8 52450.5 52604.7 54340.0 57165.9 53365.9 65373.7 83111.0 84553.2 85087.1 12276.0 12741.7 12917.2 13052.4 13531.8 14114.7 14259.8 14352.9 14430.6 14875.3 11644.5 11942.6 12882.0 12923.3 12991.5 13307.7 13401.0 13404.8 13419.5 14011.4 12574.8 12793.5 12794.6 13071.7 13138.2 8765.4 9825.6 10235.0 10702.3 10746.2 8580.3 8801.9 10195.9 10739.6 11204.9 22:19:04 INFO - PROCESS | 1638 | -------- Summary: end -------- 22:19:04 INFO - PROCESS | 1638 | 22:19:05 INFO - TEST-INFO | 1638: exit 0 22:19:05 INFO - TEST-OK | dromaeo_css | took 1117122ms 22:19:05 INFO - TEST-START | kraken 22:19:05 INFO - operating with platform_type : mac_ 22:19:05 INFO - Initialising browser for kraken test... 22:19:05 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpQApvCH/profile http://localhost:49242/getInfo.html) 22:19:08 INFO - PROCESS | 1649 | 2016-11-17 22:19:08.431 plugin-container[1650:10121] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 22:19:08 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:19:08 INFO - PROCESS | 1649 | 2016-11-17 22:19:08.434 plugin-container[1650:10121] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 22:19:08 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:19:08 INFO - PROCESS | 1649 | 2016-11-17 22:19:08.434 plugin-container[1650:10121] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:19:08 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 22:19:08 INFO - PROCESS | 1649 | colorDepth:24 22:19:08 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 22:19:08 INFO - PROCESS | 1649 | __metrics 22:19:08 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 22:19:09 INFO - TEST-INFO | 1649: exit 0 22:19:09 INFO - Browser initialized. 22:19:09 INFO - Running cycle 1/1 for kraken test... 22:19:09 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpQApvCH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:19:11 INFO - PROCESS | 1651 | 2016-11-17 22:19:11.972 plugin-container[1652:10240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 22:19:11 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:19:11 INFO - PROCESS | 1651 | 2016-11-17 22:19:11.973 plugin-container[1652:10240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 22:19:11 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:19:11 INFO - PROCESS | 1651 | 2016-11-17 22:19:11.973 plugin-container[1652:10240] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:19:21 INFO - PROCESS | 1651 | RSS: Main: 242704384 22:19:21 INFO - PROCESS | 1651 | 22:19:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 22:19:25 INFO - PROCESS | 1651 | RSS: Main: 254795776 22:19:25 INFO - PROCESS | 1651 | 22:19:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 22:19:28 INFO - PROCESS | 1651 | RSS: Main: 262463488 22:19:28 INFO - PROCESS | 1651 | 22:19:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 22:19:33 INFO - PROCESS | 1651 | RSS: Main: 264941568 22:19:33 INFO - PROCESS | 1651 | 22:19:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 22:19:35 INFO - PROCESS | 1651 | RSS: Main: 267190272 22:19:35 INFO - PROCESS | 1651 | 22:19:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:19:38 INFO - PROCESS | 1651 | RSS: Main: 267489280 22:19:38 INFO - PROCESS | 1651 | 22:19:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 22:19:42 INFO - PROCESS | 1651 | RSS: Main: 273018880 22:19:42 INFO - PROCESS | 1651 | 22:19:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 22:19:45 INFO - PROCESS | 1651 | RSS: Main: 272568320 22:19:45 INFO - PROCESS | 1651 | 22:19:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 22:19:49 INFO - PROCESS | 1651 | RSS: Main: 272314368 22:19:49 INFO - PROCESS | 1651 | 22:19:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:19:51 INFO - PROCESS | 1651 | RSS: Main: 271740928 22:19:51 INFO - PROCESS | 1651 | 22:19:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:19:54 INFO - PROCESS | 1651 | RSS: Main: 271962112 22:19:54 INFO - PROCESS | 1651 | 22:19:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:19:57 INFO - PROCESS | 1651 | RSS: Main: 271777792 22:19:57 INFO - PROCESS | 1651 | 22:20:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:20:01 INFO - PROCESS | 1651 | RSS: Main: 272207872 22:20:01 INFO - PROCESS | 1651 | 22:20:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:20:04 INFO - PROCESS | 1651 | RSS: Main: 280387584 22:20:04 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 22:20:06 INFO - PROCESS | 1651 | RSS: Main: 281735168 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | __start_tp_report 22:20:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 22:20:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 22:20:06 INFO - PROCESS | 1651 | |i|pagename|runs| 22:20:06 INFO - PROCESS | 1651 | |0;ai-astar;109;118;117;118;117;118;116;119;117;120 22:20:06 INFO - PROCESS | 1651 | |1;audio-beat-detection;141;116;125;187;147;117;117;116;145;114 22:20:06 INFO - PROCESS | 1651 | |2;audio-dft;283;238;280;276;238;226;266;196;194;194 22:20:06 INFO - PROCESS | 1651 | |3;audio-fft;81;60;75;108;85;59;59;58;77;59 22:20:06 INFO - PROCESS | 1651 | |4;audio-oscillator;90;72;90;70;78;66;86;69;72;87 22:20:06 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;91;82;137;131;90;88;79;130;137;88 22:20:06 INFO - PROCESS | 1651 | |6;imaging-darkroom;100;163;107;104;171;173;102;101;102;117 22:20:06 INFO - PROCESS | 1651 | |7;imaging-desaturate;93;95;91;91;91;91;92;94;92;98 22:20:06 INFO - PROCESS | 1651 | |8;json-parse-financial;57;57;57;58;57;59;57;56;59;58 22:20:06 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;68;77;72;76;81;81;108;59;83;105 22:20:06 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;98;89;89;94;93;89;118;110;80;92 22:20:06 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;151;172;176;167;156;164;168;149;198;165 22:20:06 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;172;177;206;193;177;175;176;176;176;174 22:20:06 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;70;71;65;77;89;91;94;83;70 22:20:06 INFO - PROCESS | 1651 | __end_tp_report 22:20:06 INFO - PROCESS | 1651 | __start_cc_report 22:20:06 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,963 22:20:06 INFO - PROCESS | 1651 | __end_cc_report 22:20:06 INFO - PROCESS | 1651 | __startTimestamp1479450006672__endTimestamp 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | ------- Summary: start ------- 22:20:06 INFO - PROCESS | 1651 | Number of tests: 14 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:116.90 Median:118.00 stddev:3.00 (2.5%) stddev-sans-first:1.20 22:20:06 INFO - PROCESS | 1651 | Values: 109.0 118.0 117.0 118.0 117.0 118.0 116.0 119.0 117.0 120.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:133.00 stddev:23.17 (17.4%) stddev-sans-first:24.37 22:20:06 INFO - PROCESS | 1651 | Values: 141.0 116.0 125.0 187.0 147.0 117.0 117.0 116.0 145.0 114.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:239.10 Median:252.00 stddev:36.19 (14.4%) stddev-sans-first:34.72 22:20:06 INFO - PROCESS | 1651 | Values: 283.0 238.0 280.0 276.0 238.0 226.0 266.0 196.0 194.0 194.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:72.10 Median:67.50 stddev:16.42 (24.3%) stddev-sans-first:17.10 22:20:06 INFO - PROCESS | 1651 | Values: 81.0 60.0 75.0 108.0 85.0 59.0 59.0 58.0 77.0 59.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:82.00 stddev:9.39 (11.5%) stddev-sans-first:8.90 22:20:06 INFO - PROCESS | 1651 | Values: 90.0 72.0 90.0 70.0 78.0 66.0 86.0 69.0 72.0 87.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:105.30 Median:85.00 stddev:24.84 (29.2%) stddev-sans-first:25.80 22:20:06 INFO - PROCESS | 1651 | Values: 91.0 82.0 137.0 131.0 90.0 88.0 79.0 130.0 137.0 88.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:124.00 Median:112.00 stddev:31.52 (28.1%) stddev-sans-first:32.21 22:20:06 INFO - PROCESS | 1651 | Values: 100.0 163.0 107.0 104.0 171.0 173.0 102.0 101.0 102.0 117.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:92.80 Median:92.50 stddev:2.30 (2.5%) stddev-sans-first:2.44 22:20:06 INFO - PROCESS | 1651 | Values: 93.0 95.0 91.0 91.0 91.0 91.0 92.0 94.0 92.0 98.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:57.50 stddev:0.97 (1.7%) stddev-sans-first:1.01 22:20:06 INFO - PROCESS | 1651 | Values: 57.0 57.0 57.0 58.0 57.0 59.0 57.0 56.0 59.0 58.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:81.00 Median:76.50 stddev:15.22 (19.9%) stddev-sans-first:15.40 22:20:06 INFO - PROCESS | 1651 | Values: 68.0 77.0 72.0 76.0 81.0 81.0 108.0 59.0 83.0 105.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:90.50 stddev:11.10 (12.3%) stddev-sans-first:11.73 22:20:06 INFO - PROCESS | 1651 | Values: 98.0 89.0 89.0 94.0 93.0 89.0 118.0 110.0 80.0 92.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:166.60 Median:167.50 stddev:14.06 (8.4%) stddev-sans-first:13.74 22:20:06 INFO - PROCESS | 1651 | Values: 151.0 172.0 176.0 167.0 156.0 164.0 168.0 149.0 198.0 165.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:176.50 stddev:10.73 (6.1%) stddev-sans-first:10.96 22:20:06 INFO - PROCESS | 1651 | Values: 172.0 177.0 206.0 193.0 177.0 175.0 176.0 176.0 176.0 174.0 22:20:06 INFO - PROCESS | 1651 | 22:20:06 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.70 Median:80.00 stddev:10.74 (13.4%) stddev-sans-first:10.67 22:20:06 INFO - PROCESS | 1651 | Values: 67.0 70.0 71.0 65.0 77.0 89.0 91.0 94.0 83.0 70.0 22:20:06 INFO - PROCESS | 1651 | -------- Summary: end -------- 22:20:06 INFO - PROCESS | 1651 | 22:20:07 INFO - TEST-INFO | 1651: exit 0 22:20:07 INFO - TEST-OK | kraken | took 62407ms 22:20:07 INFO - SUITE-END | took 1179s 22:20:07 INFO - Completed test suite (00:19:39) 22:20:07 INFO - javascript benchmark 22:20:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7060.641392319622, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2705.4994825957187, 3283.1135593389786, 3365.4110530657135, 3398.8640454381825, 3413.8805141820035, 5641.774329026834, 5708.1437784332375, 5716.942830571698, 5720.542356611466, 5731.914021289674, 5657.830265092043, 5720.2849643794525, 5756.222909907172, 5814.680192589406, 5847.912281315784, 5144.819931302405, 5166.43169251382, 5195.636305458619, 5279.815206467774, 5321.36143662761, 4825.2279635258365, 4901.77455636091, 5299.072662284085, 5311.415744268127, 5333.146696528556, 5136.6661167024395, 5275.0, 5279.683219006853, 5312.787488500494, 5313.256144139824, 5090.287359769635, 5126.154184559543, 5174.844754657329, 5191.32512773337, 5229.607779416559, 5148.3564554430695, 5170.37955445349, 5187.844364669066, 5207.0887594670785, 5243.397009343936, 5077.619178561586, 5276.707206734336, 5754.3094828620415, 5764.308283006024, 5777.768889244425, 6127.158986117662, 6137.131844484721, 6216.160818289518, 6250.218722659668, 6265.498760099181, 6274.396125607867, 6288.465480434169, 6306.306306306303, 6310.810675679737, 6331.430171284606, 5720.8283751487525, 5816.214811000503, 5824.592278540504, 5848.1227815827115, 5880.94119058804, 6227.688615569204, 6245.437910587978, 6246.593971391845, 6265.4361107500545, 6322.968385158137, 6199.442050215412, 6225.813225603149, 6227.9377206227355, 6237.157983672237, 6239.781607643801, 6141.263048434216, 6299.905501417483, 6304.275008374004, 6312.210973628296, 6327.683615819191, 6119.663418511936, 6124.136831511551, 6181.783637572753, 6198.287196972406, 6234.563580549318, 6212.4408803207925, 6240.968795155995, 6300.905486417708, 6321.020241862519, 6337.556371053982, 4926.802927882916, 4958.7024778513405, 4977.253411988231, 4994.750262486934, 5012.147934851084, 4383.715058521186, 4410.603045725836, 4463.24124898768, 4493.662975276868, 4511.390962220124, 5664.631798933056, 5794.652320860761, 5819.76720931158, 5825.2135961644635, 5825.533957283407, 6191.040388739752, 6224.253089629183, 6249.593776404519, 6257.561970662101, 6260.436560709558, 5815.88368232633, 5849.09339052439, 5863.970680146657, 5873.383294754075, 5875.294964604275, 4619.2378257587125, 5428.9185662215095, 5431.158170483582, 5432.565394768409, 5449.373322067933, 6194.907076393948, 6207.503399728102, 6226.5641405102115, 6238.625682459067, 6262.029385445263, 6133.08984587437, 6221.844453888689, 6235.875282494234, 6283.088952101828, 6302.621842689453, 5033.320501732219, 5289.862679523767, 5336.786528538815, 5520.889582208413, 6268.843278918063, 6094.902917474897, 6125.571210015256, 6133.110698948717, 6256.124142619946, 6270.623762574261, 4584.243599805995, 6088.269407671108, 6238.656873871981, 6238.875222495434, 6257.217847769029, 6092.025275955826, 6108.725107370268, 6159.84600384994, 6177.96911015442, 6183.288921774054, 5070.4929507049, 6126.969365153146, 6155.47678447337, 6164.0, 6173.9691301545, 5647.29408823897, 5827.155062515996, 5828.0, 5884.7940322088525, 5985.25184351956, 6070.575059745775, 6093.390660934048, 6108.584917381041, 6198.0, 6211.875762484816, 6012.882608478693, 6024.867069123943, 6045.335013148638, 6060.90908636362, 6122.877542449215], "value": 5666.553922907704, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.0605994071796, 477.41755058828215, 512.9842911036149, 521.1219095823535, 545.3428618514693, 29262.41475170498, 30886.69113308866, 39053.609463905384, 39985.80007099968, 40032.99999999993, 41242.96892577687, 41555.0, 41705.0, 41786.0, 41789.955251118736, 9367.156955873987, 9485.241180705561, 9522.190613797835, 9538.380005299634, 9557.569909354095, 9423.34036617437, 9437.669681561147, 9497.335186536946, 9507.667231646894, 9512.048795120501, 9386.671466498674, 9419.952900235488, 9458.0, 9479.241660667165, 9481.09929556691, 9444.763880902998, 9463.0, 9489.193418559431, 9491.955884852661, 9502.382345147544, 9324.720258392259, 9410.106039926197, 9424.670136545188, 9426.858597121049, 9438.905610943872, 39186.80406597977, 39568.60431395678, 39584.0, 39598.80200598978, 39684.0, 3266.575345205108, 3371.831408429569, 3787.3561494545993, 3979.184267225212, 4108.548059713429, 29172.27069323284, 29951.550726739115, 30125.0, 30148.397032059234, 30958.53562196569, 2915.8336665333823, 3153.1171272043866, 3206.118317462693, 3276.2137087099163, 3317.1209629448217, 257042.0, 301732.0, 318721.81278187153, 340515.0, 343997.28001360083, 56282.71858640681, 56489.0, 56547.43452565504, 56644.43355566391, 56884.43115568874, 59421.0, 59919.700401497714, 59934.70032649809, 60008.699956499935, 60029.39970600238, 15738.291776870068, 15941.0, 15962.0, 15984.120873351845, 15988.840111598734, 12270.141090123785, 12317.0, 12392.876071239352, 12401.441935112942, 12423.565175218824, 13268.601941941757, 14578.562643120511, 15750.76373854393, 16082.919585401998, 16213.0, 2792.3298408382248, 3119.9688003119677, 3258.6741325867697, 5238.816641417532, 6070.544709146831, 880.6169316347409, 904.1591320072264, 915.688665853613, 975.8682577851899, 977.9070988256104, 26287.868560657073, 27130.728692713215, 29363.412731745244, 29427.558586621217, 30335.393292134475, 935.3826474526779, 972.7081875437341, 981.5043403081398, 1101.5758932810766, 1106.8654629005334, 14105.717885642434, 14950.775738363933, 15421.383144674312, 15431.38274469031, 15744.212789360487, 611358.0, 613831.0, 618632.0, 619413.0, 620296.8985155046, 56519.43480565224, 56611.43388566144, 57147.0, 57349.85300293971, 57455.71272143696, 1269.6381531263546, 1291.8320618319747, 1305.5039085147582, 1314.18520517278, 1320.1155225998605, 27628.58557121685, 28533.42933141367, 29373.853130735064, 30043.549346760254, 30157.09528714142, 40365.79817100896, 49198.01603967829, 49401.0, 49800.0, 49892.750536247084, 562594.1870290622, 563080.1845990744, 565062.0, 565223.0, 568582.1570892119, 25107.372315692253, 25654.230373088838, 25856.095036673625, 25913.352166195997, 25936.610950835377, 37337.81331093327, 44993.775031124635, 45798.54201458076, 45866.312005319276, 46321.76839115783, 36777.448338274415, 37205.44191837179, 37336.0, 38380.0, 38654.420183696704, 37175.69885054119, 37392.0, 38282.0, 38394.0, 38596.42105368478, 36445.0, 36607.0, 37562.43656345212, 37591.43612845755, 37892.0, 26918.59622105709, 31583.526247105852, 31916.6808331923, 33250.33499330048, 33467.66532334645, 24539.631905521073, 24620.753792461845, 24852.130175443774, 24910.0035998558, 25176.496470070862], "value": 18783.84009408329, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.4434503919569, 334.54334832953026, 335.7075260248044, 341.9623841377449, 373.7757345592643, 18715.81284187158, 18787.2485100596, 19359.612807743837, 19885.801141988577, 19945.0, 18982.905085474587, 19250.22999080035, 19359.0, 19757.91331476768, 19906.900465497652, 5374.704391258489, 5742.511886489653, 5769.884602307951, 5778.104393818965, 5781.82654520365, 5399.649022813524, 5666.206731057655, 5684.175794509793, 5730.828075157752, 5748.511376533, 5679.346875109353, 5690.146478028309, 5693.3452652945025, 5697.943020569783, 5699.743511541969, 5469.890602187954, 5667.4615911488345, 5813.273340832396, 5853.50245229156, 5906.320773111104, 5479.123340265538, 5690.5447564194765, 5691.9146212806845, 5711.371749107595, 5713.0287851065, 20017.699734503993, 22452.663210051862, 23667.526649466916, 23698.526029479486, 24330.513389732107, 2087.2381580723077, 2109.7573779015456, 2158.549662103202, 2197.6593627987686, 2880.8908570200415, 9738.707838764849, 9748.415095094246, 9768.316217864682, 10259.076683098558, 10387.688369348856, 1593.6254980079673, 1832.2854086906118, 1895.2419032387018, 1899.5441094137445, 1900.277894400119, 114510.42744786222, 119465.80534194634, 119607.0, 121676.0, 122575.0, 7739.613019349123, 9041.50271735061, 9889.357191782656, 9913.553890074963, 11532.827007594893, 12170.208936419105, 12222.75554488923, 12227.0, 12231.449584768707, 12249.816252756216, 6638.535302528777, 6924.619145947021, 7622.5426474411715, 7829.0, 7903.762887113396, 5809.970950145223, 6715.529912906148, 8028.4781489203015, 8106.067802202822, 8258.339332853357, 3773.64150405711, 3828.464015037897, 3861.2663593917064, 3883.2427676603356, 3925.7448265862627, 1599.720048991445, 1651.8348165183386, 1955.491572191212, 1959.7256384106236, 1968.320929279425, 669.625009994395, 795.8845967334704, 800.667722894992, 834.0, 930.901536187292, 16470.75293870593, 19642.803571964385, 20725.481862953246, 20823.687644685342, 21087.367378978655, 899.4288626722079, 901.7069452427857, 916.1479823763964, 959.4003747657714, 965.3001573859036, 10417.791644167075, 10827.0, 10914.890851091546, 10939.0, 10940.179486538542, 554888.0, 572516.0, 574242.0, 586040.0, 587432.0, 117627.0, 123494.14758778625, 129571.35214323868, 135034.32482837522, 137152.0, 819.491915012684, 961.485605201214, 985.4875464758286, 1016.9288149829441, 1018.954147063384, 18049.548761281072, 18472.353467628567, 19005.809941900403, 19019.524511887314, 19032.33386831454, 54990.450095500135, 55738.442615574946, 55764.88470230492, 56113.15830262467, 56429.43570564242, 81130.0, 81636.18363816285, 81824.0, 82459.0, 83145.0, 7644.923550764421, 7762.5730584818375, 7820.687172513263, 7908.130105688255, 7992.800179995546, 8948.910510894808, 11957.641270761891, 11974.58088966882, 12230.510779568718, 12598.677138900284, 8218.753437396888, 10519.631812886511, 10576.576936922436, 10787.67636970892, 11008.339499630049, 10690.358578485315, 10798.13614910821, 10836.857311379612, 10921.617743378943, 11123.388213648326, 10812.297200681931, 10946.0, 11099.278546894426, 11128.833067503832, 11256.268342557707, 6716.093327400934, 7131.0, 7674.309312161932, 7764.767056988299, 8091.595420229083, 9185.357025008185, 9602.423854568748, 11130.721731956766, 11136.33182009082, 11260.83108753353], "value": 9095.022779521321, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.7452445821982, 1486.0043770673647, 1495.6335697754048, 1705.9402920897771, 1714.6742118997397, 7463.2163622819635, 7734.72928447503, 7821.960890195548, 8211.589420528971, 8223.30101941335, 7937.880931786013, 8149.062857771358, 8262.256396924291, 8411.327093832493, 8420.368472364567, 2298.321995011476, 2303.435658263728, 2319.2926157521947, 2323.663068855015, 2331.2889568681544, 2299.1378233162563, 2333.3349995251356, 2353.470469144446, 2354.8822558872075, 2359.634256690216, 2293.885305734715, 2305.1816605105196, 2316.81465482761, 2322.6632138339933, 2328.580855446019, 2269.137727663494, 2282.5206706591553, 2302.2172461363134, 2304.5506126305377, 2322.047960336254, 2302.7697230277004, 2323.0591610397814, 2326.0, 2331.0908745589236, 2333.976660233393, 8186.345092392654, 8292.958535207346, 8337.207965243353, 8341.791455213608, 8350.206730360605, 4404.4494438195225, 4668.136394766979, 4766.475687674353, 5172.534471897509, 5174.197999310113, 3262.9021129366156, 3438.449848024329, 3505.1237190702323, 3616.7649102808496, 3622.130688634709, 831.9126491718343, 1047.6909311752975, 1058.8358804385332, 1064.8615679961633, 1086.788076325117, 9901.702948911545, 10088.243381746324, 10809.459527023697, 10893.564257429693, 10894.727631809188, 3935.7995811277265, 4422.800973956179, 4529.139463501924, 4647.18674232008, 4698.0, 9226.953865230766, 9383.812323753487, 9385.249180065579, 9402.34183607141, 9408.952955235316, 5181.637285390062, 5184.766685499161, 5193.792248310101, 5196.168613021899, 5197.142471492161, 4264.445622069174, 4335.544767799399, 4647.209974304376, 4712.50518695532, 4759.428868535798, 3165.9050228493184, 3737.495438115874, 3828.2152158807935, 3841.770633397286, 3864.2851072551666, 1250.5185503581006, 1256.5612373831912, 1259.137490818792, 1268.8731126887421, 1269.4668239339499, 780.7735756630627, 790.8932294140333, 825.9710910118117, 868.0572759252886, 879.2394578689388, 5373.623846330719, 5552.001279539362, 5713.514351280104, 5760.567957403212, 5851.531877449794, 747.137056699513, 781.281221276427, 869.9782505437415, 898.6899519665704, 908.5321059654283, 3742.5321834770652, 3774.962250377516, 3775.4336849472797, 3832.463455116286, 3873.7094717895707, 9600.375975561567, 10085.29402941787, 10090.596376144871, 10098.646547370954, 10134.189264858793, 10053.346532475513, 10080.193584513221, 10091.142252908438, 10095.141912937335, 10099.192064634812, 6400.327965563549, 7717.382609391235, 7868.331191848757, 7872.44892857506, 7896.644650990719, 4998.850034498971, 5078.21287700407, 5271.262023316662, 5667.489925906688, 5860.09168578871, 9050.275977921881, 9146.679866204651, 9185.08149185103, 9221.262299016196, 9390.483523406278, 9399.389039712396, 9406.294527910297, 9454.858177127211, 9526.666566670134, 9640.662576809778, 6551.689662067511, 6811.386975172259, 7104.076470058859, 7174.713011479483, 7264.673089711081, 7841.490303130278, 7964.20357964199, 7969.203079691984, 7975.4018448615425, 7995.440319177602, 6969.825754356181, 7075.292470752884, 7122.287771222836, 7151.141862976476, 7221.350078492961, 6838.076859623887, 6927.168739751262, 6982.997311156251, 7051.048108505286, 7154.67803948834, 6584.934150658432, 6637.966810165918, 6706.83232919181, 6709.463242940651, 6714.630695311805, 4287.933815598025, 4348.434703488526, 4562.977185114053, 4650.767461626972, 4742.762861856824, 4687.484376718627, 4731.739754313408, 4807.663463557517, 4809.134355815987, 4944.826931057396], "value": 4354.000695070372, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.2880683065015, 299.4938553844004, 307.9584256125422, 308.0005382533677, 308.5864940979088, 4614.792334344954, 5431.782728690852, 5437.429069947658, 5456.672599644019, 5474.124140137579, 4717.693349932252, 5185.45552716964, 5224.320838291017, 5340.412554618989, 5409.702466364349, 3155.794873333226, 3897.4153876918404, 4013.1371755072655, 4059.8579049733116, 4082.6937979651498, 3236.7896086754404, 3995.760254384746, 4068.698016634678, 4092.774897380643, 4114.991827002374, 4060.5485337463215, 4063.719928222615, 4063.740240525432, 4077.9592204077876, 4087.305158123126, 3853.595372485877, 4041.6766658667234, 4092.9386059209137, 4093.85671501496, 4112.753234805907, 3504.6144924058335, 3510.4207805712026, 3512.209752805624, 3519.7008254298157, 3526.2418580005424, 5158.613104017176, 5169.483051694838, 5185.118529849935, 5210.505002024802, 5231.476852314738, 4292.978535107336, 4688.2733176357715, 4710.835120770791, 4711.3639658646325, 4725.952740472586, 4955.479674634147, 5631.8592035199035, 5674.574406919498, 5694.402087780806, 5708.600397972144, 3553.6446355364515, 3837.9040523986846, 3850.0, 3858.247641709868, 3877.3408520551575, 5816.331121920991, 6007.197840647832, 6014.278286605635, 6024.668643224621, 6103.72533236001, 3279.8032118072992, 3840.846366145323, 3931.901702457404, 3942.664873485786, 3981.0843505993785, 3240.918977025546, 3986.6412022918075, 3998.640122388999, 4048.0689441428635, 4070.287699652549, 4298.742075475481, 4367.45406824147, 4699.976500117477, 4703.623710103184, 4729.527047295243, 4804.35141255926, 5610.354809196913, 5755.625884317506, 5810.331811841692, 5843.766249350064, 1107.9058280046286, 1313.8686131386785, 1314.6918815778363, 1324.079764563619, 1325.7613629546581, 822.4243029879109, 838.7357982235576, 878.3982971664431, 895.3642913531336, 895.9964839379805, 4940.925886111711, 5288.629795914326, 5438.91841622376, 5440.463698595865, 5452.618316717871, 5081.720505372166, 5827.621204621686, 5935.347111817697, 5962.85092872673, 5968.4634030536845, 4305.655547556188, 4992.151334273182, 5243.4232234454175, 5432.890155112353, 5573.916391254134, 2898.826070435781, 2921.7078292170613, 2924.5174546200064, 2927.7218664226866, 2964.688707685705, 5279.604029697711, 5531.778728850801, 5803.303603567514, 5823.650580965155, 5825.417458254141, 4642.72143671374, 5392.20582066591, 5521.503064724194, 5956.987312156857, 6121.632702037892, 5414.401087478032, 5994.010988186988, 6167.784127555514, 6252.155269352343, 6311.0, 4884.658073934933, 5023.447420783784, 5486.506214440719, 5978.521718262616, 6023.457888789854, 5307.6730817295675, 5782.074868021096, 5842.853928651818, 5886.38192989747, 6006.699665016819, 5762.394948530344, 6272.686365681607, 6294.5279104068295, 6297.811065668037, 6350.90473642905, 3198.0, 3376.6285708572527, 3933.97716593682, 3942.5071866016747, 3960.861369852041, 4851.272309153655, 5857.736401861841, 5904.3210030847, 5909.55678324136, 5936.37668044849, 5089.160288552347, 5348.545373643284, 5798.10129429939, 5869.53043756507, 5870.882582348415, 5161.741912904265, 5420.17058506277, 5649.051077378298, 5769.0, 5811.215485909517, 5007.222436035155, 5235.214717792362, 5443.918341224964, 5652.519535839499, 5691.8292451226525, 4983.0, 5367.758450869798, 5427.402985671652, 5486.231927530069, 5501.889962200654, 4711.316859055486, 5141.943222710796, 5188.558972487381, 5606.915896261641, 5619.325680918316], "value": 4129.672059646657, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.5628341577975, 688.1741909708351, 734.174054189037, 738.3428748413916, 759.8328367759091, 13222.074454788144, 13240.933795331033, 13856.237906915116, 14676.77984830227, 14682.559523214308, 12962.74074518509, 12988.0, 13129.80305295419, 13133.868661313409, 13145.145565538234, 628.9874202515936, 664.0238848892145, 785.8428314337156, 819.7622689420061, 823.333100188846, 671.4393481443001, 687.2062767503542, 810.2019510781876, 833.9916600834005, 837.4347315561971, 873.9563021848913, 880.1330689271063, 880.7137680253911, 881.4711173296035, 885.3227281129941, 841.8568843296654, 867.6832955971057, 871.729763773226, 875.0855356152804, 875.6935072724559, 642.2357394700339, 654.5712558274338, 654.9574277671984, 656.0487293424553, 660.1286302081255, 13328.266945318004, 13715.862841371558, 13749.450021999108, 13760.518381856687, 13991.160530368108, 13051.673708157277, 13055.086143969926, 13115.606531804062, 13144.342782860818, 13344.866551334459, 10697.197710171691, 10829.891701082966, 10877.238593298393, 11230.438478076063, 11432.256903301259, 3632.6185750496347, 3678.2459595782802, 3724.832382542792, 3786.3941769317053, 3796.5064541609413, 85551.5722421384, 87067.69398459028, 88105.55947220288, 88853.0, 90834.0, 9808.950955245178, 10021.198304135653, 10157.288989770645, 10199.592016319264, 10412.21908356861, 16482.34070637185, 18334.81665183331, 19056.142473588443, 19127.426177214707, 20175.798242017685, 7602.42981776369, 7603.695852165962, 7612.467127301146, 7692.153863075057, 7936.484128531627, 4257.169851878916, 4276.799832399749, 4327.394164816928, 4334.263175260214, 4347.456567929009, 3270.640229574745, 3356.412627790127, 3362.1930736623035, 3502.404591219499, 3704.2961837250837, 570.7183693760875, 655.5542231282774, 658.8418779492887, 662.8972509261072, 673.3603077076797, 605.0, 634.3941535833268, 646.9256035555971, 662.2615783401569, 680.792358330704, 8542.829143417097, 8644.438111522732, 8686.39195256326, 8819.779505512286, 8837.690680826141, 598.7515181199753, 620.5687047502033, 646.8027251688186, 654.5450911616381, 666.4638009388407, 3651.908702282464, 4083.285425050664, 4240.194363071007, 4271.466066741657, 4274.166537525152, 83281.58359208165, 96628.0, 104919.0, 109408.90591094147, 110552.0, 81752.0, 83655.3268934618, 90491.0, 90753.0, 91781.54109229411, 75588.0, 77168.61415692886, 83973.16026839775, 84116.15883841083, 85246.14753852507, 7607.923920760722, 8888.33337499703, 9080.910290764887, 9301.906980930375, 9320.72037838866, 37789.81105094457, 52450.47549524456, 52604.736976314874, 54340.0, 57165.85668286694, 53365.93268134693, 65373.67313163404, 83111.0, 84553.15446845452, 85087.14912850792, 12276.0, 12741.745165096832, 12917.160384574972, 13052.412641430969, 13531.797023044466, 14114.717705645624, 14259.786103208253, 14352.928235358757, 14430.567082987527, 14875.256237187881, 11644.53421863116, 11942.582009629621, 12882.0, 12923.332018159268, 12991.545295914597, 13307.66730831737, 13401.0, 13404.798928016282, 13419.463221471031, 14011.441579057913, 12574.811377829523, 12793.488260469478, 12794.599424203463, 13071.673208169492, 13138.211707297593, 8765.430247033923, 9825.557849896915, 10235.0, 10702.304350217211, 10746.226872566252, 8580.34399261085, 8801.919477519792, 10195.929427410016, 10739.624113156002, 11204.943975280072], "value": 7117.201981281308, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 117.0, 118.0, 117.0, 118.0, 116.0, 119.0, 117.0, 120.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 125.0, 187.0, 147.0, 117.0, 117.0, 116.0, 145.0, 114.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [283.0, 238.0, 280.0, 276.0, 238.0, 226.0, 266.0, 196.0, 194.0, 194.0], "value": 239.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 75.0, 108.0, 85.0, 59.0, 59.0, 58.0, 77.0, 59.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 90.0, 70.0, 78.0, 66.0, 86.0, 69.0, 72.0, 87.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 82.0, 137.0, 131.0, 90.0, 88.0, 79.0, 130.0, 137.0, 88.0], "value": 105.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 163.0, 107.0, 104.0, 171.0, 173.0, 102.0, 101.0, 102.0, 117.0], "value": 124.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 91.0, 91.0, 91.0, 92.0, 94.0, 92.0, 98.0], "value": 92.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 58.0, 57.0, 59.0, 57.0, 56.0, 59.0, 58.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 72.0, 76.0, 81.0, 81.0, 108.0, 59.0, 83.0, 105.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 89.0, 89.0, 94.0, 93.0, 89.0, 118.0, 110.0, 80.0, 92.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 172.0, 176.0, 167.0, 156.0, 164.0, 168.0, 149.0, 198.0, 165.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 177.0, 206.0, 193.0, 177.0, 175.0, 176.0, 176.0, 176.0, 174.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 71.0, 65.0, 77.0, 89.0, 91.0, 94.0, 83.0, 70.0], "value": 77.7, "unit": "score"}]}]} 22:20:07 INFO - Return code: 0 22:20:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:20:07 ERROR - # TBPL SUCCESS # 22:20:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:20:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:20:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:20:08 INFO - 2016-11-17 22:20:08.009 screenresolution[1656:10644] starting screenresolution argv=screenresolution get 22:20:08 INFO - 2016-11-17 22:20:08.013 screenresolution[1656:10644] Display 0: 1600x1200x32@60 22:20:08 INFO - 2016-11-17 22:20:08.019 screenresolution[1657:10647] starting screenresolution argv=screenresolution list 22:20:08 INFO - Available Modes on Display 0 22:20:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:20:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:20:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:20:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:20:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:20:08 INFO - Intel Iris: 22:20:08 INFO - Chipset Model: Intel Iris 22:20:08 INFO - Type: GPU 22:20:08 INFO - Bus: Built-In 22:20:08 INFO - VRAM (Dynamic, Max): 1536 MB 22:20:08 INFO - Vendor: Intel (0x8086) 22:20:08 INFO - Device ID: 0x0a2e 22:20:08 INFO - Revision ID: 0x0009 22:20:08 INFO - Displays: 22:20:08 INFO - Display: 22:20:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:20:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:20:08 INFO - Display Serial Number: 1600x1200 60 22:20:08 INFO - Main Display: Yes 22:20:08 INFO - Mirror: Off 22:20:08 INFO - Online: Yes 22:20:08 INFO - Rotation: Supported 22:20:08 INFO - Return code: 0 22:20:08 INFO - Running post-action listener: _resource_record_post_action 22:20:08 INFO - [mozharness: 2016-11-18 06:20:08.264215Z] Finished run-tests step (success) 22:20:08 INFO - Running post-run listener: _resource_record_post_run 22:20:08 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 37579264; Write bytes: 695190528; Read time: 243; Write time: 1994 22:20:08 INFO - TinderboxPrint: CPU usage
54.5% 22:20:08 INFO - TinderboxPrint: I/O read bytes / time
37,579,264 / 243 22:20:08 INFO - TinderboxPrint: I/O write bytes / time
695,190,528 / 1,994 22:20:08 INFO - TinderboxPrint: CPU idle
2,207.0 (45.5%) 22:20:08 INFO - TinderboxPrint: CPU system
982.6 (20.2%) 22:20:08 INFO - TinderboxPrint: CPU user
1,664.1 (34.3%) 22:20:08 INFO - TinderboxPrint: Swap in / out
421,584,896 / 0 22:20:08 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 270336; Write bytes: 384927744; Read time: 5; Write time: 800 22:20:08 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35314176; Write bytes: 306574336; Read time: 221; Write time: 1178 22:20:08 INFO - Running post-run listener: _upload_blobber_files 22:20:08 INFO - Blob upload gear active. 22:20:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:20:08 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 22:20:08 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'] 22:20:08 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 22:20:09 INFO - (blobuploader) - INFO - Open directory for files ... 22:20:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:20:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:20:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:10 INFO - (blobuploader) - INFO - Done attempting. 22:20:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:20:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:20:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:11 INFO - (blobuploader) - INFO - Done attempting. 22:20:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:20:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:13 INFO - (blobuploader) - INFO - Done attempting. 22:20:13 INFO - (blobuploader) - INFO - Iteration through files over. 22:20:13 INFO - Return code: 0 22:20:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:20:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:20:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16b0870abb5e1b612dbd68c69ee1119c86d548ac5152bff6b78962e1c7e9e70e0c186993b98841389a20e987c5f2592cc9782647f9df3b3680c092bdd0aef01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943f0327da04b468a78e21ec7ba3852c8bffd60c9138cffa56d3cc5e3b3c4dcafb521d660dd11aa406b5fe52a98aa89d1796de499c196c6ebb837a67a08b2bb5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70c5a3fcc97058eaacdaa01d95f7ec1c8a27cb9165dcda6b5ffc5f7c2945ffa7aaad6e8b12216fd5a092799d3c4a9a1a6faecc305b21758e9882af9555ce226d"} 22:20:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:20:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:20:13 INFO - Contents: 22:20:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16b0870abb5e1b612dbd68c69ee1119c86d548ac5152bff6b78962e1c7e9e70e0c186993b98841389a20e987c5f2592cc9782647f9df3b3680c092bdd0aef01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943f0327da04b468a78e21ec7ba3852c8bffd60c9138cffa56d3cc5e3b3c4dcafb521d660dd11aa406b5fe52a98aa89d1796de499c196c6ebb837a67a08b2bb5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70c5a3fcc97058eaacdaa01d95f7ec1c8a27cb9165dcda6b5ffc5f7c2945ffa7aaad6e8b12216fd5a092799d3c4a9a1a6faecc305b21758e9882af9555ce226d"} 22:20:13 INFO - Running post-run listener: copy_logs_to_upload_dir 22:20:13 INFO - Copying logs to upload dir... 22:20:13 INFO - mkdir: /builds/slave/test/build/upload/logs 22:20:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.273968 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-17 22:20:13.704082) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 22:20:13.714617) ========= 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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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/f16b0870abb5e1b612dbd68c69ee1119c86d548ac5152bff6b78962e1c7e9e70e0c186993b98841389a20e987c5f2592cc9782647f9df3b3680c092bdd0aef01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943f0327da04b468a78e21ec7ba3852c8bffd60c9138cffa56d3cc5e3b3c4dcafb521d660dd11aa406b5fe52a98aa89d1796de499c196c6ebb837a67a08b2bb5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70c5a3fcc97058eaacdaa01d95f7ec1c8a27cb9165dcda6b5ffc5f7c2945ffa7aaad6e8b12216fd5a092799d3c4a9a1a6faecc305b21758e9882af9555ce226d"} build_url:https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010975 build_url: 'https://queue.taskcluster.net/v1/task/NPzjYRK3SQuLY4wedYXUlQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16b0870abb5e1b612dbd68c69ee1119c86d548ac5152bff6b78962e1c7e9e70e0c186993b98841389a20e987c5f2592cc9782647f9df3b3680c092bdd0aef01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943f0327da04b468a78e21ec7ba3852c8bffd60c9138cffa56d3cc5e3b3c4dcafb521d660dd11aa406b5fe52a98aa89d1796de499c196c6ebb837a67a08b2bb5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70c5a3fcc97058eaacdaa01d95f7ec1c8a27cb9165dcda6b5ffc5f7c2945ffa7aaad6e8b12216fd5a092799d3c4a9a1a6faecc305b21758e9882af9555ce226d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 22:20:13.757693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 22:20:13.758036) ========= 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.V7WfBdXOfR/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.u7YlDbSWHn/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.005256 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 22:20:13.812982) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 22:20:13.825553) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 22:20:13.826043) ========= ========= Total master_lag: 0.25 =========