builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0083 starttime: 1476751052.25 results: success (0) buildid: 20161017151219 builduid: b1454f2e38c9443aa401621025f16bda revision: 07e27ed94ad03941605d4f508695215f85c2b1a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:32.252245) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:32.252640) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:32.252917) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:32.275753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-17 17:37:32.276105) ========= 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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-17 17:37:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 32.6M=0s 2016-10-17 17:37:32 (32.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102615 ========= master_lag: 2.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-17 17:37:34.702130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:34.702455) ========= 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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.019074 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:34.784834) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-17 17:37:34.785234) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 07e27ed94ad03941605d4f508695215f85c2b1a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 07e27ed94ad03941605d4f508695215f85c2b1a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RQP3CrJLTx/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.zDRPtUMaqX/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-17 17:37:34,852 truncating revision to first 12 chars 2016-10-17 17:37:34,852 Setting DEBUG logging. 2016-10-17 17:37:34,852 attempt 1/10 2016-10-17 17:37:34,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/07e27ed94ad0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 17:37:36,268 unpacking tar archive at: larch-07e27ed94ad0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.700841 ========= master_lag: 1.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-17 17:37:37.822071) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:37.822409) ========= script_repo_revision: 07e27ed94ad03941605d4f508695215f85c2b1a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:37.822819) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:37.823088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 17:37:37.848400) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-17 17:37:37.848738) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RQP3CrJLTx/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.zDRPtUMaqX/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:37:38 INFO - MultiFileLogger online at 20161017 17:37:38 in /builds/slave/test 17:37:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 17:37:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:37:38 INFO - {'append_to_log': False, 17:37:38 INFO - 'base_work_dir': '/builds/slave/test', 17:37:38 INFO - 'blob_upload_branch': 'Larch', 17:37:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:37:38 INFO - 'branch': 'Larch', 17:37:38 INFO - 'buildbot_json_path': 'buildprops.json', 17:37:38 INFO - 'config_files': ('talos/mac_config.py',), 17:37:38 INFO - 'default_actions': ('clobber', 17:37:38 INFO - 'read-buildbot-config', 17:37:38 INFO - 'download-and-extract', 17:37:38 INFO - 'populate-webroot', 17:37:38 INFO - 'create-virtualenv', 17:37:38 INFO - 'install', 17:37:38 INFO - 'run-tests'), 17:37:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:37:38 INFO - 'download_minidump_stackwalk': True, 17:37:38 INFO - 'download_symbols': 'ondemand', 17:37:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:37:38 INFO - 'tooltool.py': '/tools/tooltool.py', 17:37:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:37:38 INFO - '/tools/misc-python/virtualenv.py')}, 17:37:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:37:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:37:38 INFO - 'installer_path': 'installer.exe', 17:37:38 INFO - 'log_level': 'info', 17:37:38 INFO - 'log_name': 'talos', 17:37:38 INFO - 'log_to_console': True, 17:37:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:37:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:37:38 INFO - 'opt_config_files': (), 17:37:38 INFO - 'pip_index': False, 17:37:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:37:38 INFO - 'cmd': ('bash', 17:37:38 INFO - '-c', 17:37:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:37:38 INFO - 'enabled': True, 17:37:38 INFO - 'halt_on_failure': False, 17:37:38 INFO - 'name': 'check_screen_resolution'},), 17:37:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:37:38 INFO - 'cmd': ('bash', 17:37:38 INFO - '-c', 17:37:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:37:38 INFO - 'enabled': True, 17:37:38 INFO - 'halt_on_failure': False, 17:37:38 INFO - 'name': 'check_screen_resolution'},), 17:37:38 INFO - 'run_cmd_checks_enabled': True, 17:37:38 INFO - 'sps_profile': False, 17:37:38 INFO - 'sps_profile_interval': 0, 17:37:38 INFO - 'suite': 'dromaeojs-e10s', 17:37:38 INFO - 'system_bits': '32', 17:37:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:37:38 INFO - 'title': 't-yosemite-r7-0083', 17:37:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:37:38 INFO - 'use_talos_json': True, 17:37:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:37:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:37:38 INFO - 'work_dir': 'build'} 17:37:38 INFO - [mozharness: 2016-10-18 00:37:38.047392Z] Running clobber step. 17:37:38 INFO - Running pre-action listener: _resource_record_pre_action 17:37:38 INFO - Running main action method: clobber 17:37:38 INFO - rmtree: /builds/slave/test/build 17:37:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:37:39 INFO - Running post-action listener: _resource_record_post_action 17:37:39 INFO - [mozharness: 2016-10-18 00:37:39.703077Z] Finished clobber step (success) 17:37:39 INFO - [mozharness: 2016-10-18 00:37:39.703308Z] Running read-buildbot-config step. 17:37:39 INFO - Running pre-action listener: _resource_record_pre_action 17:37:39 INFO - Running main action method: read_buildbot_config 17:37:39 INFO - Using buildbot properties: 17:37:39 INFO - { 17:37:39 INFO - "project": "", 17:37:39 INFO - "product": "firefox", 17:37:39 INFO - "script_repo_revision": "production", 17:37:39 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 17:37:39 INFO - "repository": "", 17:37:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 17:37:39 INFO - "buildid": "20161017151219", 17:37:39 INFO - "pgo_build": "False", 17:37:39 INFO - "basedir": "/builds/slave/test", 17:37:39 INFO - "buildnumber": 3, 17:37:39 INFO - "slavename": "t-yosemite-r7-0083", 17:37:39 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:37:39 INFO - "platform": "yosemite_r7", 17:37:39 INFO - "scheduler": "tests-larch-macosx64-talos", 17:37:39 INFO - "branch": "larch", 17:37:39 INFO - "repo_path": "projects/larch", 17:37:39 INFO - "revision": "07e27ed94ad03941605d4f508695215f85c2b1a4", 17:37:39 INFO - "stage_platform": "macosx64", 17:37:39 INFO - "builduid": "b1454f2e38c9443aa401621025f16bda", 17:37:39 INFO - "slavebuilddir": "test" 17:37:39 INFO - } 17:37:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:37:39 INFO - Running post-action listener: _resource_record_post_action 17:37:39 INFO - [mozharness: 2016-10-18 00:37:39.711768Z] Finished read-buildbot-config step (success) 17:37:39 INFO - [mozharness: 2016-10-18 00:37:39.712158Z] Running download-and-extract step. 17:37:39 INFO - Running pre-action listener: _resource_record_pre_action 17:37:39 INFO - Running main action method: download_and_extract 17:37:39 INFO - mkdir: /builds/slave/test/build/tests 17:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:37:39 INFO - trying https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:37:39 INFO - Downloading https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:37:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 17:37:40 INFO - Downloaded 1199 bytes. 17:37:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:37:40 INFO - Using the following test package requirements: 17:37:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:37:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:37:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:37:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'jsshell-mac.zip'], 17:37:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:37:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:37:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:37:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:37:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:37:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:37:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:37:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:37:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:37:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:37:40 INFO - Fetch https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:37:40 INFO - Http code: 200 17:37:40 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 17:37:40 INFO - x-amz-cf-id: iqZ-NRIyYOPLas5eoQuOn0lIqIPwLvbtlrfzi7vhOXVS0LyXN0MviQ== 17:37:40 INFO - x-amz-version-id: iqr_qa.36.J15DINAAKGh2bbX0qVJOq3 17:37:41 INFO - Expected file size: 16508476 17:37:41 INFO - Obtained file size: 16508476 17:37:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:37:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:37:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:37:48 INFO - Fetch https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:37:48 INFO - Http code: 200 17:37:48 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 17:37:48 INFO - x-amz-cf-id: MxWLFvuspjvgbu2mcjfWsCxlHd0-IEh5zVhG45zSFPb18Sv6ZhA9Rg== 17:37:48 INFO - x-amz-version-id: iqr_qa.36.J15DINAAKGh2bbX0qVJOq3 17:37:48 INFO - Expected file size: 16508476 17:37:48 INFO - Obtained file size: 16508476 17:37:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:37:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:37:55 INFO - Fetch https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:37:55 INFO - Http code: 200 17:37:55 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 17:37:55 INFO - x-amz-cf-id: 2xfNkF04PxnGJnbI_qpH4Phs1qdFtV6NHqDFQo6X5jYB7rlYT563nw== 17:37:55 INFO - x-amz-version-id: zt4fpGik5HS288uRyk01.8msPELnAIH8 17:37:56 INFO - Expected file size: 14409006 17:37:56 INFO - Obtained file size: 14409006 17:37:56 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')]} 17:37:56 INFO - trying https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:37:56 INFO - Downloading https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:37:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 17:38:00 INFO - Downloaded 111664616 bytes. 17:38:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:38:00 INFO - mkdir: /builds/slave/test/properties 17:38:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:38:00 INFO - Writing to file /builds/slave/test/properties/build_url 17:38:00 INFO - Contents: 17:38:00 INFO - build_url:https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:38:00 INFO - Running post-action listener: _resource_record_post_action 17:38:00 INFO - Running post-action listener: set_extra_try_arguments 17:38:00 INFO - [mozharness: 2016-10-18 00:38:00.834876Z] Finished download-and-extract step (success) 17:38:00 INFO - [mozharness: 2016-10-18 00:38:00.835036Z] Running populate-webroot step. 17:38:00 INFO - Running pre-action listener: _resource_record_pre_action 17:38:00 INFO - Running main action method: populate_webroot 17:38:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:38:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:38:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:38:00 INFO - '--tppagecycles', 17:38:00 INFO - '7'], 17:38:00 INFO - 'tests': ['tsvgm']}}, 17:38:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:38:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:38:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:38:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:38:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['damp', 'tps']}, 17:38:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'tests': ['damp', 'tps']}, 17:38:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['dromaeo_dom']}, 17:38:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:38:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['basic_compositor_video']}, 17:38:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:38:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['a11yr', 17:38:00 INFO - 'ts_paint', 17:38:00 INFO - 'tpaint', 17:38:00 INFO - 'sessionrestore', 17:38:00 INFO - 'sessionrestore_no_auto_restore', 17:38:00 INFO - 'tabpaint']}, 17:38:00 INFO - 'other-e10s': {'tests': ['a11yr', 17:38:00 INFO - 'ts_paint', 17:38:00 INFO - 'tpaint', 17:38:00 INFO - 'sessionrestore', 17:38:00 INFO - 'sessionrestore_no_auto_restore', 17:38:00 INFO - 'tabpaint']}, 17:38:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:38:00 INFO - 'ts_paint', 17:38:00 INFO - 'tpaint', 17:38:00 INFO - 'sessionrestore', 17:38:00 INFO - 'sessionrestore_no_auto_restore', 17:38:00 INFO - 'tabpaint']}, 17:38:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:38:00 INFO - 'ts_paint', 17:38:00 INFO - 'tpaint', 17:38:00 INFO - 'sessionrestore', 17:38:00 INFO - 'sessionrestore_no_auto_restore', 17:38:00 INFO - 'tabpaint']}, 17:38:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['a11yr', 17:38:00 INFO - 'ts_paint', 17:38:00 INFO - 'tpaint', 17:38:00 INFO - 'sessionrestore', 17:38:00 INFO - 'sessionrestore_no_auto_restore', 17:38:00 INFO - 'tabpaint']}, 17:38:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['a11yr', 17:38:00 INFO - 'ts_paint', 17:38:00 INFO - 'tpaint', 17:38:00 INFO - 'sessionrestore', 17:38:00 INFO - 'sessionrestore_no_auto_restore', 17:38:00 INFO - 'tabpaint']}, 17:38:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['tsvgx', 17:38:00 INFO - 'tsvgr_opacity', 17:38:00 INFO - 'tart', 17:38:00 INFO - 'tscrollx', 17:38:00 INFO - 'cart']}, 17:38:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:38:00 INFO - 'tsvgr_opacity', 17:38:00 INFO - 'tart', 17:38:00 INFO - 'tscrollx', 17:38:00 INFO - 'cart']}, 17:38:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'talos_options': ['--disable-e10s'], 17:38:00 INFO - 'tests': ['tp5o']}, 17:38:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'tests': ['tp5o']}, 17:38:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'talos_options': ['--disable-e10s', 17:38:00 INFO - '--xperf_path', 17:38:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:00 INFO - 'tests': ['tp5n']}, 17:38:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:38:00 INFO - 'talos_options': ['--xperf_path', 17:38:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:00 INFO - 'tests': ['tp5n']}}, 17:38:00 INFO - 'talos.zip': {'path': '', 17:38:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:38:00 INFO - Running post-action listener: _resource_record_post_action 17:38:00 INFO - [mozharness: 2016-10-18 00:38:00.853890Z] Finished populate-webroot step (success) 17:38:00 INFO - [mozharness: 2016-10-18 00:38:00.854020Z] Running create-virtualenv step. 17:38:00 INFO - Running pre-action listener: _resource_record_pre_action 17:38:00 INFO - Running main action method: create_virtualenv 17:38:00 INFO - Creating virtualenv /builds/slave/test/build/venv 17:38:00 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 17:38:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:38:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:38:00 INFO - Using real prefix '/tools/python27' 17:38:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:38:01 INFO - Installing distribute.............................................................................................................................................................................................done. 17:38:04 INFO - Installing pip.................done. 17:38:04 INFO - Return code: 0 17:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:38:04 INFO - Reading from file tmpfile_stdout 17:38:04 INFO - Output received: 17:38:04 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) 17:38:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:38:04 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')]} 17:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:04 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')]} 17:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:04 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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:04 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 17:38:04 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 17:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:04 INFO - 'HOME': '/Users/cltbld', 17:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:04 INFO - 'LOGNAME': 'cltbld', 17:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:04 INFO - 'MOZ_NO_REMOTE': '1', 17:38:04 INFO - 'NO_EM_RESTART': '1', 17:38:04 INFO - 'PAGER': '/bin/cat', 17:38:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:04 INFO - 'PWD': '/builds/slave/test', 17:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:04 INFO - 'SHELL': '/bin/bash', 17:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:04 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:04 INFO - 'USER': 'cltbld', 17:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:04 INFO - 'XPC_FLAGS': '0x0', 17:38:04 INFO - 'XPC_SERVICE_NAME': '0', 17:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:05 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 17:38:05 INFO - Cleaning up... 17:38:05 INFO - Return code: 0 17:38:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:05 INFO - 'HOME': '/Users/cltbld', 17:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:05 INFO - 'LOGNAME': 'cltbld', 17:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:05 INFO - 'MOZ_NO_REMOTE': '1', 17:38:05 INFO - 'NO_EM_RESTART': '1', 17:38:05 INFO - 'PAGER': '/bin/cat', 17:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:05 INFO - 'PWD': '/builds/slave/test', 17:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:05 INFO - 'SHELL': '/bin/bash', 17:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:05 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:05 INFO - 'USER': 'cltbld', 17:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:05 INFO - 'XPC_FLAGS': '0x0', 17:38:05 INFO - 'XPC_SERVICE_NAME': '0', 17:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:05 INFO - Downloading/unpacking psutil>=3.1.1 17:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:38:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:08 INFO - Installing collected packages: psutil 17:38:08 INFO - Running setup.py install for psutil 17:38:08 INFO - building 'psutil._psutil_osx' extension 17:38:08 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 17:38:09 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 17:38:09 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 17:38:09 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 17:38:09 INFO - building 'psutil._psutil_posix' extension 17:38:09 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 17:38:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:38:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:38:09 INFO - ^ 17:38:09 INFO - 1 warning generated. 17:38:09 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 17:38:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:09 INFO - Successfully installed psutil 17:38:09 INFO - Cleaning up... 17:38:09 INFO - Return code: 0 17:38:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:09 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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:09 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 17:38:09 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 17:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:09 INFO - 'HOME': '/Users/cltbld', 17:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:09 INFO - 'LOGNAME': 'cltbld', 17:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:09 INFO - 'MOZ_NO_REMOTE': '1', 17:38:09 INFO - 'NO_EM_RESTART': '1', 17:38:09 INFO - 'PAGER': '/bin/cat', 17:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:09 INFO - 'PWD': '/builds/slave/test', 17:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:09 INFO - 'SHELL': '/bin/bash', 17:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:09 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:09 INFO - 'USER': 'cltbld', 17:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:09 INFO - 'XPC_FLAGS': '0x0', 17:38:09 INFO - 'XPC_SERVICE_NAME': '0', 17:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:38:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:38:12 INFO - Installing collected packages: mozsystemmonitor 17:38:12 INFO - Running setup.py install for mozsystemmonitor 17:38:12 INFO - Successfully installed mozsystemmonitor 17:38:12 INFO - Cleaning up... 17:38:12 INFO - Return code: 0 17:38:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:38:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:12 INFO - 'HOME': '/Users/cltbld', 17:38:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:12 INFO - 'LOGNAME': 'cltbld', 17:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:12 INFO - 'MOZ_NO_REMOTE': '1', 17:38:12 INFO - 'NO_EM_RESTART': '1', 17:38:12 INFO - 'PAGER': '/bin/cat', 17:38:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:12 INFO - 'PWD': '/builds/slave/test', 17:38:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:12 INFO - 'SHELL': '/bin/bash', 17:38:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:12 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:12 INFO - 'USER': 'cltbld', 17:38:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:12 INFO - 'XPC_FLAGS': '0x0', 17:38:12 INFO - 'XPC_SERVICE_NAME': '0', 17:38:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:12 INFO - Downloading/unpacking jsonschema==2.5.1 17:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:38:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:15 INFO - Downloading functools32-3.2.3-2.tar.gz 17:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:38:15 INFO - warning: no files found matching '*.txt' 17:38:15 INFO - no previously-included directories found matching 'build' 17:38:15 INFO - no previously-included directories found matching 'dist' 17:38:15 INFO - no previously-included directories found matching '.git*' 17:38:15 INFO - Installing collected packages: jsonschema, functools32 17:38:15 INFO - Running setup.py install for functools32 17:38:15 INFO - warning: no files found matching '*.txt' 17:38:15 INFO - no previously-included directories found matching 'build' 17:38:15 INFO - no previously-included directories found matching 'dist' 17:38:15 INFO - no previously-included directories found matching '.git*' 17:38:16 INFO - Successfully installed jsonschema functools32 17:38:16 INFO - Cleaning up... 17:38:16 INFO - Return code: 0 17:38:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:38:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:38:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:16 INFO - 'HOME': '/Users/cltbld', 17:38:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:16 INFO - 'LOGNAME': 'cltbld', 17:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:16 INFO - 'MOZ_NO_REMOTE': '1', 17:38:16 INFO - 'NO_EM_RESTART': '1', 17:38:16 INFO - 'PAGER': '/bin/cat', 17:38:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:16 INFO - 'PWD': '/builds/slave/test', 17:38:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:16 INFO - 'SHELL': '/bin/bash', 17:38:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:16 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:16 INFO - 'USER': 'cltbld', 17:38:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:16 INFO - 'XPC_FLAGS': '0x0', 17:38:16 INFO - 'XPC_SERVICE_NAME': '0', 17:38:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:38:16 INFO - Cleaning up... 17:38:16 INFO - Return code: 0 17:38:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:38:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:38:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:16 INFO - 'HOME': '/Users/cltbld', 17:38:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:16 INFO - 'LOGNAME': 'cltbld', 17:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:16 INFO - 'MOZ_NO_REMOTE': '1', 17:38:16 INFO - 'NO_EM_RESTART': '1', 17:38:16 INFO - 'PAGER': '/bin/cat', 17:38:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:16 INFO - 'PWD': '/builds/slave/test', 17:38:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:16 INFO - 'SHELL': '/bin/bash', 17:38:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:16 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:16 INFO - 'USER': 'cltbld', 17:38:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:16 INFO - 'XPC_FLAGS': '0x0', 17:38:16 INFO - 'XPC_SERVICE_NAME': '0', 17:38:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:16 INFO - Downloading/unpacking blobuploader==1.2.4 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:19 INFO - Downloading blobuploader-1.2.4.tar.gz 17:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:38:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:38:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:20 INFO - Downloading docopt-0.6.1.tar.gz 17:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:38:20 INFO - Installing collected packages: blobuploader, requests, docopt 17:38:20 INFO - Running setup.py install for blobuploader 17:38:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:38:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:38:20 INFO - Running setup.py install for requests 17:38:21 INFO - Running setup.py install for docopt 17:38:21 INFO - Successfully installed blobuploader requests docopt 17:38:21 INFO - Cleaning up... 17:38:21 INFO - Return code: 0 17:38:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:21 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')]} 17:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:21 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')]} 17:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:21 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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:21 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 17:38:21 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 17:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:21 INFO - 'HOME': '/Users/cltbld', 17:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:21 INFO - 'LOGNAME': 'cltbld', 17:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:21 INFO - 'MOZ_NO_REMOTE': '1', 17:38:21 INFO - 'NO_EM_RESTART': '1', 17:38:21 INFO - 'PAGER': '/bin/cat', 17:38:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:21 INFO - 'PWD': '/builds/slave/test', 17:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:21 INFO - 'SHELL': '/bin/bash', 17:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:21 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:21 INFO - 'USER': 'cltbld', 17:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:21 INFO - 'XPC_FLAGS': '0x0', 17:38:21 INFO - 'XPC_SERVICE_NAME': '0', 17:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-OEQKy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LUMWq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ky_qF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-GN5xCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-XjM4X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-QzbFFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Lm_os1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-m3ghDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-4zVLvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-xTIPbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-m3Jlds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-HsW4IP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-_TJtfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-zRAwKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-BhpH4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:23 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-UqW5fL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:23 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-l6qHSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:38:23 INFO - Running setup.py install for manifestparser 17:38:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:38:23 INFO - Running setup.py install for mozcrash 17:38:23 INFO - Running setup.py install for mozdebug 17:38:23 INFO - Running setup.py install for mozdevice 17:38:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:38:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:38:23 INFO - Running setup.py install for mozfile 17:38:24 INFO - Running setup.py install for mozhttpd 17:38:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Running setup.py install for mozinfo 17:38:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Running setup.py install for mozInstall 17:38:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Running setup.py install for mozleak 17:38:24 INFO - Running setup.py install for mozlog 17:38:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Running setup.py install for moznetwork 17:38:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:38:24 INFO - Running setup.py install for mozprocess 17:38:25 INFO - Running setup.py install for mozprofile 17:38:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:38:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:38:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:38:25 INFO - Running setup.py install for mozrunner 17:38:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:38:25 INFO - Running setup.py install for mozscreenshot 17:38:25 INFO - Running setup.py install for moztest 17:38:25 INFO - Running setup.py install for mozversion 17:38:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:38:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:38:25 INFO - Cleaning up... 17:38:25 INFO - Return code: 0 17:38:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:25 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')]} 17:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:25 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')]} 17:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:25 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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:25 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 17:38:25 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 17:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:25 INFO - 'HOME': '/Users/cltbld', 17:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:25 INFO - 'LOGNAME': 'cltbld', 17:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:25 INFO - 'MOZ_NO_REMOTE': '1', 17:38:25 INFO - 'NO_EM_RESTART': '1', 17:38:25 INFO - 'PAGER': '/bin/cat', 17:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:25 INFO - 'PWD': '/builds/slave/test', 17:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:25 INFO - 'SHELL': '/bin/bash', 17:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:25 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:25 INFO - 'USER': 'cltbld', 17:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:25 INFO - 'XPC_FLAGS': '0x0', 17:38:25 INFO - 'XPC_SERVICE_NAME': '0', 17:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-_IMdB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Ze8hfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-1yYkGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-IQzhdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-1aTieh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-J91kgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-qMXTm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-jiulIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:26 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)) 17:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:26 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ifheKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-qtLWwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-5mbrOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-kqC7cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-TKtwkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-E9HVr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-yIpzcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-49aWC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:27 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)) 17:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:27 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LqO6Ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:27 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)) 17:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:38:27 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)) 17:38:27 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)) 17:38:27 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)) 17:38:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:38:27 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 17:38:27 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 17:38:27 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 17:38:27 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 17:38:27 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 17:38:27 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 17:38:30 INFO - Downloading blessings-1.6.tar.gz 17:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:38:30 INFO - Installing collected packages: blessings 17:38:30 INFO - Running setup.py install for blessings 17:38:30 INFO - Successfully installed blessings 17:38:30 INFO - Cleaning up... 17:38:30 INFO - Return code: 0 17:38:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:31 INFO - Reading from file tmpfile_stdout 17:38:31 INFO - Current package versions: 17:38:31 INFO - blessings == 1.6 17:38:31 INFO - blobuploader == 1.2.4 17:38:31 INFO - docopt == 0.6.1 17:38:31 INFO - functools32 == 3.2.3-2 17:38:31 INFO - jsonschema == 2.5.1 17:38:31 INFO - manifestparser == 1.1 17:38:31 INFO - mozInstall == 1.12 17:38:31 INFO - mozcrash == 0.17 17:38:31 INFO - mozdebug == 0.1 17:38:31 INFO - mozdevice == 0.48 17:38:31 INFO - mozfile == 1.2 17:38:31 INFO - mozhttpd == 0.7 17:38:31 INFO - mozinfo == 0.9 17:38:31 INFO - mozleak == 0.1 17:38:31 INFO - mozlog == 3.4 17:38:31 INFO - moznetwork == 0.27 17:38:31 INFO - mozprocess == 0.23 17:38:31 INFO - mozprofile == 0.28 17:38:31 INFO - mozrunner == 6.12 17:38:31 INFO - mozscreenshot == 0.1 17:38:31 INFO - mozsystemmonitor == 0.3 17:38:31 INFO - moztest == 0.7 17:38:31 INFO - mozversion == 1.4 17:38:31 INFO - psutil == 3.1.1 17:38:31 INFO - requests == 1.2.3 17:38:31 INFO - wsgiref == 0.1.2 17:38:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38: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')]} 17:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:31 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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:31 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 17:38:31 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 17:38:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:31 INFO - 'HOME': '/Users/cltbld', 17:38:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:31 INFO - 'LOGNAME': 'cltbld', 17:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:31 INFO - 'MOZ_NO_REMOTE': '1', 17:38:31 INFO - 'NO_EM_RESTART': '1', 17:38:31 INFO - 'PAGER': '/bin/cat', 17:38:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:31 INFO - 'PWD': '/builds/slave/test', 17:38:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:31 INFO - 'SHELL': '/bin/bash', 17:38:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:31 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:31 INFO - 'USER': 'cltbld', 17:38:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:31 INFO - 'XPC_FLAGS': '0x0', 17:38:31 INFO - 'XPC_SERVICE_NAME': '0', 17:38:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 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)) 17:38:32 INFO - Cleaning up... 17:38:32 INFO - Return code: 0 17:38:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:32 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 0x1065d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10654f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed405a2c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, '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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 17:38:32 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 17:38:32 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 17:38:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:38:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:32 INFO - 'HOME': '/Users/cltbld', 17:38:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:32 INFO - 'LOGNAME': 'cltbld', 17:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:32 INFO - 'MOZ_NO_REMOTE': '1', 17:38:32 INFO - 'NO_EM_RESTART': '1', 17:38:32 INFO - 'PAGER': '/bin/cat', 17:38:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:32 INFO - 'PWD': '/builds/slave/test', 17:38:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:32 INFO - 'SHELL': '/bin/bash', 17:38:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:38:32 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:38:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:32 INFO - 'USER': 'cltbld', 17:38:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:32 INFO - 'XPC_FLAGS': '0x0', 17:38:32 INFO - 'XPC_SERVICE_NAME': '0', 17:38:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:38:32 INFO - Cleaning up... 17:38:32 INFO - Return code: 0 17:38:32 INFO - Running post-action listener: _resource_record_post_action 17:38:32 INFO - Running post-action listener: _start_resource_monitoring 17:38:32 INFO - Starting resource monitoring. 17:38:32 INFO - [mozharness: 2016-10-18 00:38:32.766799Z] Finished create-virtualenv step (success) 17:38:32 INFO - [mozharness: 2016-10-18 00:38:32.767821Z] Running install step. 17:38:32 INFO - Running pre-action listener: _resource_record_pre_action 17:38:32 INFO - Running main action method: install 17:38:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:33 INFO - Reading from file tmpfile_stdout 17:38:33 INFO - Detecting whether we're running mozinstall >=1.0... 17:38:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:38:33 INFO - Reading from file tmpfile_stdout 17:38:33 INFO - Output received: 17:38:33 INFO - Usage: mozinstall [options] installer 17:38:33 INFO - Options: 17:38:33 INFO - -h, --help show this help message and exit 17:38:33 INFO - -d DEST, --destination=DEST 17:38:33 INFO - Directory to install application into. [default: 17:38:33 INFO - "/builds/slave/test"] 17:38:33 INFO - --app=APP Application being installed. [default: firefox] 17:38:33 INFO - mkdir: /builds/slave/test/build/application 17:38:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:39:07 INFO - Reading from file tmpfile_stdout 17:39:07 INFO - Output received: 17:39:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:39:07 INFO - Running post-action listener: _resource_record_post_action 17:39:07 INFO - [mozharness: 2016-10-18 00:39:07.257070Z] Finished install step (success) 17:39:07 INFO - [mozharness: 2016-10-18 00:39:07.257157Z] Running run-tests step. 17:39:07 INFO - Running pre-action listener: _resource_record_pre_action 17:39:07 INFO - Running main action method: run_tests 17:39:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:39:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:39:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:39:07 INFO - 2016-10-17 17:39:07.269 screenresolution[1925:16883] starting screenresolution argv=screenresolution get 17:39:07 INFO - 2016-10-17 17:39:07.272 screenresolution[1925:16883] Display 0: 1600x1200x32@60 17:39:07 INFO - 2016-10-17 17:39:07.277 screenresolution[1926:16885] starting screenresolution argv=screenresolution list 17:39:07 INFO - Available Modes on Display 0 17:39:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:39:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:39:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:39:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:39:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:39:07 INFO - Intel Iris: 17:39:07 INFO - Chipset Model: Intel Iris 17:39:07 INFO - Type: GPU 17:39:07 INFO - Bus: Built-In 17:39:07 INFO - VRAM (Dynamic, Max): 1536 MB 17:39:07 INFO - Vendor: Intel (0x8086) 17:39:07 INFO - Device ID: 0x0a2e 17:39:07 INFO - Revision ID: 0x0009 17:39:07 INFO - Displays: 17:39:07 INFO - Display: 17:39:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:39:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:39:07 INFO - Display Serial Number: 1600x1200 60 17:39:07 INFO - Main Display: Yes 17:39:07 INFO - Mirror: Off 17:39:07 INFO - Online: Yes 17:39:07 INFO - Rotation: Supported 17:39:07 INFO - Return code: 0 17:39:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:39:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:39:07 INFO - Python 2.7.3 17:39:07 INFO - Return code: 0 17:39:07 INFO - grabbing minidump binary from tooltool 17:39:07 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')]} 17:39:07 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 0x1063edd30>, '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 0x106213db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063e5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:39:07 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 17:39:07 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 17:39:07 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 17:39:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:39:07 INFO - Return code: 0 17:39:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:39:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:39:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:39:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:39:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:39:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 17:39:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0083 --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 17:39:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RQP3CrJLTx/Render', 17:39:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:07 INFO - 'HOME': '/Users/cltbld', 17:39:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:07 INFO - 'LOGNAME': 'cltbld', 17:39:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:39:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:07 INFO - 'MOZ_NO_REMOTE': '1', 17:39:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:39:07 INFO - 'NO_EM_RESTART': '1', 17:39:07 INFO - 'PAGER': '/bin/cat', 17:39:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:07 INFO - 'PWD': '/builds/slave/test', 17:39:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:39:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:07 INFO - 'SHELL': '/bin/bash', 17:39:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zDRPtUMaqX/Listeners', 17:39:07 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 17:39:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:07 INFO - 'USER': 'cltbld', 17:39:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:07 INFO - 'XPC_FLAGS': '0x0', 17:39:07 INFO - 'XPC_SERVICE_NAME': '0', 17:39:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 17:39:07 INFO - mozversion application_buildid: 20161017151219 17:39:07 INFO - mozversion application_changeset: 07e27ed94ad03941605d4f508695215f85c2b1a4 17:39:07 INFO - mozversion application_display_name: Nightly 17:39:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:39:07 INFO - mozversion application_name: Firefox 17:39:07 INFO - mozversion application_remotingname: firefox 17:39:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 17:39:07 INFO - mozversion application_vendor: Mozilla 17:39:07 INFO - mozversion application_version: 52.0a1 17:39:07 INFO - mozversion platform_buildid: 20161017151219 17:39:07 INFO - mozversion platform_changeset: 07e27ed94ad03941605d4f508695215f85c2b1a4 17:39:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 17:39:07 INFO - mozversion platform_version: 52.0a1 17:39:07 INFO - using testdate: 1476751147 17:39:07 INFO - actual date: 1476751147 17:39:07 INFO - starting webserver on 'localhost:52063' 17:39:07 INFO - SUITE-START | Running 2 tests 17:39:07 INFO - TEST-START | dromaeo_css 17:39:07 INFO - operating with platform_type : mac_ 17:39:07 INFO - Initialising browser for dromaeo_css test... 17:39:07 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpPrH8hF/profile http://localhost:52063/getInfo.html) 17:39:10 INFO - PROCESS | 1936 | 2016-10-17 17:39:10.392 plugin-container[1938:17048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 17:39:10 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:10 INFO - PROCESS | 1936 | 2016-10-17 17:39:10.394 plugin-container[1938:17048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b13, name = 'com.apple.CFPasteboardClient' 17:39:10 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:10 INFO - PROCESS | 1936 | 2016-10-17 17:39:10.394 plugin-container[1938:17048] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:10 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 17:39:10 INFO - PROCESS | 1936 | colorDepth:24 17:39:10 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 17:39:10 INFO - PROCESS | 1936 | __metrics 17:39:10 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 17:39:11 INFO - TEST-INFO | 1936: exit 0 17:39:11 INFO - Browser initialized. 17:39:11 INFO - Running cycle 1/1 for dromaeo_css test... 17:39:11 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpPrH8hF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:13 INFO - PROCESS | 1939 | 2016-10-17 17:39:13.893 plugin-container[1940:17172] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 17:39:13 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:13 INFO - PROCESS | 1939 | 2016-10-17 17:39:13.894 plugin-container[1940:17172] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 17:39:13 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:13 INFO - PROCESS | 1939 | 2016-10-17 17:39:13.894 plugin-container[1940:17172] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:23 INFO - PROCESS | 1939 | RSS: Main: 247697408 17:39:23 INFO - PROCESS | 1939 | 17:40:13 INFO - PROCESS | 1939 | 1476751213090 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] 17:42:25 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52063/tests/dromaeo/cssquery-ext.html) 17:42:27 INFO - PROCESS | 1939 | RSS: Main: 263221248 17:42:27 INFO - PROCESS | 1939 | 17:45:30 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-ext.html (next: http://localhost:52063/tests/dromaeo/cssquery-jquery.html) 17:45:30 INFO - PROCESS | 1939 | RSS: Main: 272613376 17:45:30 INFO - PROCESS | 1939 | 17:48:32 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52063/tests/dromaeo/cssquery-mootools.html) 17:48:33 INFO - PROCESS | 1939 | RSS: Main: 275005440 17:48:33 INFO - PROCESS | 1939 | 17:51:35 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52063/tests/dromaeo/cssquery-prototype.html) 17:51:35 INFO - PROCESS | 1939 | RSS: Main: 274563072 17:51:35 INFO - PROCESS | 1939 | 17:54:38 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52063/tests/dromaeo/cssquery-yui.html) 17:54:39 INFO - PROCESS | 1939 | RSS: Main: 280092672 17:54:39 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-yui.html (next: http://localhost:52063/tests/dromaeo/cssquery-dojo.html) 17:57:42 INFO - PROCESS | 1939 | RSS: Main: 283750400 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | __start_tp_report 17:57:42 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 17:57:42 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 17:57:42 INFO - PROCESS | 1939 | |i|pagename|runs| 17:57:42 INFO - PROCESS | 1939 | |0;dojo.html;2768.792340574457;2907.9127626171216;3370.7471939604534;3373.055544447554;3382.391169589473;5557.444255574445;5605.355384130826;5610.551155907523;5697.4587414195685;5707.800226992056;5447.564194864412;5604.551635869131;5612.607117501777;5659.8585035374135;5716.314042314918;5087.135187018206;5151.227315902607;5161.406438259592;5167.224916262553;5190.169572868342;5179.792808287664;5186.481351864821;5194.948050519503;5235.790568377261;5319.20211968204;4998.3502144721315;5183.989122121188;5210.322658054467;5228.660137091077;5233.058049551079;5039.899202015976;5076.898462030738;5084.847454576368;5152.304438900738;5153.020926024081;4823.13183626947;5066.923996140061;5072.82245121419;5086.745662716849;5196.740162991835;4241.533431322552;5429.592780541475;5636.4927156556105;5664.631798933056;5684.687342196177;6103.725332360054;6124.081387791823;6138.633639632414;6201.596896201778;6216.595921265103;6163.96918015407;6207.596506227126;6238.376162383771;6240.968795156041;6242.032484964838;4849.733264670406;5648.068068768607;5695.971520142456;5845.88308233833;5892.558058145656;6142.5294708490455;6152.70793133438;6188.916939535563;6233.688315584293;6305.495560355161;6126.571140020245;6142.170806941006;6158.568900177034;6169.321374648785;6178.258608966953;5983.282006159288;6185.041318595625;6195.845103872349;6196.225471816023;6209.472194863397;6011.729472173763;6043.788467403708;6100.511959043266;6125.082842094287;6193.535484838565;6079.480129967508;6080.427564339064;6142.78500252489;6179.938200617937;6182.876342473125;4788.880277993008;4928.0390323886495;4933.679310844784;4943.8516844494725;4961.355023846877;3570.6607872252093;4382.846600368972;4456.955430445719;4464.531224221475;4563.931541026887;5819;5893.528517718572;5898.174255604219;5937.70311484424;5946.316173640087;5713.342965559014;5749.568782341341;5787.131930210502;5858.589898707135;5882.7352119294555;5672.234248376499;5727.599068065196;5754.769809207669;5769.278840144982;5771.249737534177;5353;5389.83830485086;5409.323834520685;5416.864578385572;5442.537384322298;6215.65813880241;6227.782027629011;6264.906026409516;6269.498440124779;6269.62382257066;6094.146819445193;6110.114033465034;6121.696863452088;6135.785247516315;6136.7852125175405;6306.684665766602;6311.842203944938;6322.841928951813;6342.778002769881;6358.586691865014;5999.220101386792;6044.123602077762;6064.878702426015;6082.817515474543;6112.480439162721;4549.613282870993;6099.2189903441595;6132.600268413353;6188.783392581238;6196.0705894116245;5966.612170208922;6018.3573822333365;6027.035674292092;6049.969750151397;6078.45293923549;5808.041673123887;6013.939860601338;6046.27444706638;6050.6369617823075;6057.394260574084;5667.0649342859615;5802;5873.530117590497;5877.382874798255;5880.382559831156;6069.089636554376;6107.158472117419;6116.305337585956;6116.487945662109;6126.517673797367;5943.346231914573;5966.3138739043825;5971.4327138921735;6008.819735407945;6019.398060194121 17:57:42 INFO - PROCESS | 1939 | |1;ext.html;423.82834951844507;508.4864287070058;535.0769921884747;537.7929497143599;549.1049589169651;33925.64297428103;38411.42382864259;38417;38465.653702120464;38525.807370963106;31895.362092758132;38629.61370386295;38777.03057423566;38910.8054459728;39251.803740981326;8222.766585012236;9286.953565232181;9487.098725621056;9491;9496.477693726842;8627.137286271354;9004;9291.117343852356;9408.811823763555;9426.622935082589;9378;9397.29520285978;9413.388129771543;9420.528973551329;9440.622375104987;9352.7661808455;9381;9400.106989835955;9403.153716165509;9404;9302.116298951589;9357.812843743088;9374.250059995184;9374.67188648394;9382.155605995426;37503.06242343936;37669.81165094185;37710.531972863784;37737;37763;3253.723433508155;3388.0513456232293;3794.9620503794886;3992.3412636914873;4094.2016306820183;29321.56017659737;30147.849260753774;30482.93309734171;30593.235169120726;30644;2811.4377124575167;2920.810147340437;2941.911492747697;3008.3381656035635;3219.0825614699934;326498;326842;382187.26719099237;427430.8628456869;429555.99999999686;57222.85554288891;57509;57550.42449575451;57591.42408575944;57741;56256.71871640698;56720.71639641858;57147.14279285814;57351.85296294051;57396;15788.447404340794;15924.76112858308;16006.439774608065;16021.839781602268;16027.919860400623;12493.375331233583;12556.560520381743;12569.931555817675;12580.308083055337;12600.55898043564;12300.446479908425;15247.16140612277;15439;15534.378624855106;15635.60910977212;2768.5708715149185;3278.5573947517255;3289.3256882339065;7040.718371265194;7319.268073192638;848.3467729848106;965.1313817564248;996.2577879479834;1018.1905385218769;1022.1311884897777;23086;27046;30307.090787275974;30987;31147.68852311493;997.7455748784158;1039.5477967084191;1096.17786660005;1132.473399869065;1202.506972141418;15337.156456394781;15688.294026768823;15813.367465301259;16018.679626407407;16177.4337898173;693375;693583;697422;699321;703840;57929.4207057924;58175;58227.41772582305;58302.125468118014;58635;1160.785254727878;1320.7490576790378;1331.7203387288778;1335.7194989052407;1338.6586420462913;24378.51242975166;28915.710842892142;29859.70140298569;30334;30396.84801575978;53848;55524.889502210535;55710.72144639251;59031.52421189505;59181.40818591759;586401.0679946573;589578;590586.0941390531;591598;594994;26164.215073547824;42259.577404225565;42305.15389692251;42544.7236582903;42556.36165457459;45448.636540903826;46697.53302466932;46703.29945050895;47075.76462117668;47808.760956194994;38321.42517862179;38854;39455;39645.60354396419;40015.99960001023;38210;38485.230295394496;38655.80672096622;39433;39617;36921;37418.62581374151;37492.06269843167;37780.62219377771;38153.61846381501;28088.43823123567;32334;33759.83120084482;33879.3224135511;34076.488852666735;24094.87952560226;25182.11862584801;25501.64064830437;25558.338707983923;25995.220143395727 17:57:42 INFO - PROCESS | 1939 | |2;jquery.html;369.1933126119429;372.9552453705556;374.62724589033905;379.5995225037584;396.728241154809;19269.421917342486;20180.091895864683;20306.390808275755;20578.691319630198;20821.895890520525;19542.902285488588;19923.601527969433;20350;20358.69461958068;20679.896600516975;5156;5436.537894278991;5776.162456443813;5843.5033022193165;6086.269647642267;5791.71041447928;5878.617889837147;5882.382349853268;5886.999210134288;5896.7051647417675;4918.926216106761;4969.304297398367;5479.561635069185;5855.385184555625;5874.5006674432725;5825.1844741736195;5853.50245229156;5869.50109240715;5873.647581145124;5878.941210587882;4925.802967881281;5104.821331253425;5402.486763757437;5468.72656367184;5514.531264842493;13188.340582970908;13429.059965802398;15446.382144714198;24616.5076698465;24649.753502464926;2069.2550681754556;2090.1639344262417;2124.53226907419;2261.21987914171;2325.616273314901;9703.514824258758;9744.90255097447;9786.5596048178;10075.294729368874;10316.123129533999;1482.6589884326665;1613.644998100416;1806.9096545172688;1824.8540116790625;1828.9176987035614;124319;125937.37031314785;127098;130033;131119.34440327832;10116;10644.095251903675;11278.830817537744;11627.941860290644;11634.76730465386;14221.431142754174;14415.855841441451;14439.783403248959;14466.421343146367;14468.421263149567;6465.676716164173;8216.876746848682;8489.61796719149;8556.957215213884;8630.877985861924;8378.413511054168;8408.957955210184;8436.957815211008;8440.62017209227;8549.102344253764;4425.734455932655;4496.887577810581;4555.749433781108;4564.7032942858605;4611.077784443058;1655.4867990922853;1898.2691663709572;1908.5906481048564;1953.9218431262875;1966.6989622142128;756.9053868266467;861.4228466927174;893.8391089603804;909.9090090990848;1035.77213013137;19626.901865490865;20953.79046209549;21136.577268454545;21260.78739212619;21520.89239553792;906.404727679276;970.7087873638021;974.770928831724;984.7538115471132;993.7813680990171;8814.206721395105;10573.312734672221;11130.053945414731;11257.774844503065;11293.15301352402;574233;577736;590403;599816;607323;114704.42647786708;118298.4085079569;118706.40646796711;119603;125536;940.741296143566;998.0767789794411;1033.4316126130598;1048.9737756556146;1052.5263631365915;18433.90783046076;19135.617287654448;19265.422037338903;19562.90218548898;19594.706079408534;42791.999999998756;51347.74326128345;56077.158842616576;57713;57812.4218757807;84131.15868841234;84524;84815.87960301041;85910.57044714736;86568.56715716381;8065.193480652122;8138.389620778347;8202.958985205036;8311.792205194919;8312.750617481484;11070.833937490783;12948.546800861925;12973.805392919303;13120.540781072617;13372.86627133755;9314.394564353295;11361.37512436824;11382.715432114263;11440.542378305108;11584.826227606423;9840.950795245977;10489.318194317346;10918.399488028235;11080.168987325822;11517.884821151682;9289;11067.889321107008;11250.774984500427;11449;11575.479103440533;7831.765047048598;7891.64487598071;8342.249197572248;8367.205115514016;8408.117147699404;10233.079022887976;11088.889111109109;11193.384363859741;11453.885461145283;11535.884641153816 17:57:42 INFO - PROCESS | 1939 | |3;mootools.html;1346.1721041559445;1534.4169215698032;1696.3469064410206;1819.8453131483823;1854.1470923375239;7898.289153976141;8150.755477335674;8426;9012.504312262823;9027.864582031274;8356.247937685606;8652.91347086529;8673.60968756406;8762.780930476742;8807.691730789424;1974.664307067802;2049.3646969439524;2347.542229265294;2371.7983971362455;2379.643053541974;2319.1766922742436;2351.3651314145172;2364.326167042393;2365.31405892291;2369.123424332999;2242.7532971373134;2249.1902914950606;2253.481699209183;2265.490264690448;2286.1198438601177;2320.5242925200464;2355.1756885090167;2355.7173139223232;2361.9173328933575;2371.051579368258;2274.0108052996998;2311.3528212100637;2315.5368926214824;2318.1538738360377;2332;8463.238308552198;8512;8522.01996770373;8522.360822938304;8531.06158322584;5196.68819870809;5347.919781203246;5349.197620356938;5365.168398898177;5393.622446428752;3522.6653467920505;3586.8923932282073;3714.238581090897;3725.1245957200035;3743.026813028605;1045.8849526552074;1067.5089458848938;1081.1945100899802;1106.8007758603449;1109.1958330210614;11567.13246506507;11659.941700291443;11664;11675.532978680927;11699.064074873904;3762.247550489913;4035.1324465240114;4712.175369310426;4817.614590832725;4821.300911367902;7865.4494185406475;8443.746687599381;9850.113489785954;10006.79986400268;10020.549075291628;5294.49702278283;5320.654157479751;5351.919721204107;5352.68859129508;5356.276902618097;4342.739435633872;4415.205263052681;4713.363695901078;4895.951040489621;4912.214045752662;3918.843246270117;3940.645341919241;3995.9400608990886;4006.579309172553;4075.9388609170887;1279.9440461619201;1293.8512071111754;1299.27240745183;1312.6718320419895;1313.4220942785144;761.9085709714759;821.34292565947;905.5200743605899;910.4946754551288;912.6167009855876;5820.417958204231;5841.9415805842245;5971.910421343683;6014.548908831855;6094.39056094387;402.16951994132023;436.77069538492543;443.7714576992851;445.8016182798688;446.6716963032169;3947.822347994347;3957.5448823385677;3964.385520244367;4058.553559108496;4067.4102255172834;10386.948065259625;10737.570497180182;10761.246712730033;10777.46112694378;10812.243142979916;10714.78570428587;10729.248952573245;10730.678079657622;10737.302075365076;10758.031777140095;7960.800979975547;8103.10865804761;8111.1888811118415;8122.431429799975;8147.3482121431325;5604.327480702342;5605.271314729059;5840.386759390203;5854.824355269348;5931.525477961839;8281.95859020701;9978.351407158512;10011.54948027355;10021.649242276486;10033.347832390871;9419.717408477756;9832.311738178545;9908.306418550632;10042;10229.948850255701;6214.471769899609;6959.61722105289;7194.748183813542;7529.58587277705;7710.575918324546;6927.792166235021;8340.165983401612;8561.1866872647;8567.914320856711;8612.48325100496;7341.412686985135;7466.477346585687;7472.476926615085;7523.247675232433;7580.128285247267;7243.0946131733535;7385.113786345673;7406.4074874008875;7521.9469274300545;7537;7041.788746337618;7055.858882822212;7102.360787529147;7102.680379382837;7105.75129870452;4967.379077615298;4974.546176959916;5037.949620503895;5063.3417655704525;5186.273921651047;5062.670926389773;5103.464136265638;5186.299849520417;5216.113260745758;5267.130923397596 17:57:42 INFO - PROCESS | 1939 | |4;prototype.html;301.59887349824737;305;314.28813736885957;315.78684388038096;320.5944480232506;4525;4608.446986361637;5136.691798492093;5396.43337449568;5505.752241149148;4497.505274419811;4611.008633143873;4682.578567928886;5503.889922201553;5516;4187.664986801064;4188.9946412861;4204.3903633973205;4205.537390887;4228.133232687307;3917.8041097945134;4206.780033790197;4216.219999300124;4243.405923170743;4244.405783190356;3590.9461358079648;4138.234426631067;4142.715758114979;4145.585441455861;4154.584541545852;3433.639467855877;3827.636374544442;4130.442390277334;4140.7515549067;4190.455240818705;3401.914952126192;3580.6419358064245;3608.4767708682093;3608.855645774166;3625.709943204564;4871.8294859679745;5037.319961805146;5328.893422131575;5386.380566234894;5423.864403389907;4741.241401375763;4770.928436073462;4779;4783.258594917794;4805.303231031516;5346.1713434417725;5420.617742475643;5638.815848671784;5807.245058142415;5893.970530147365;3442.225499262671;3645.6901163401144;3860.104921309018;3932.665723413513;3936.8031598419966;5147.794088236466;5424.891502169974;5649.898269837383;6152.661603611799;6333.7466501339895;3311.983440082784;3319.6348401675796;3478.356504046709;3988.222296652125;4003.5996400360314;3769.905752356213;3980.283548961157;4030.234255491447;4033.3748269018347;4042.7372220805555;4060.025593857512;4709.152352576501;4759.16714574948;4769.928451073237;4789.736564488987;5584.553235741131;5854.151148083504;5942.851428714317;5959.761609535657;5966.43318884714;1103.834424836281;1122.8820973797633;1167.8788363171282;1249.4377530111485;1324.3577963325406;795.8169620987205;810.4124509730399;870.8069944176392;882.9293656507464;922.3313098004028;4464.419625448671;5578.776848926079;5601.187827764951;5623.481659951872;5696.088625819932;5454.754536045808;5647.352647352647;5795.507381872588;6020.026782323645;6038.396160383926;5293.682379057269;5570.442955704397;5570.498655120978;5619.634723742944;5677.432256774289;2428.210831479776;2668.9733102669115;3047.679993600684;3141.858616362269;3210.2776875202894;6113.0743815697915;6284.4344009039405;6322.968385158045;6325.335839736854;6349.428551430394;5877.942720047949;6192.349803270682;6212.968935155295;6218.968905155536;6232.844178895473;5684.522024273719;6165.876682466325;6260.906086408799;6304.8423789404715;6328.797528469576;5332.146856502941;5545.944540554704;5799.708215920273;6009.188759517409;6049.727762250797;4450.354698568624;5655.293088363955;6079.696015199311;6103.176071230327;6116.510679145568;5324.520793128637;6026.758929642765;6323.525735569942;6350.206224221973;6391.201099862517;3298;3721.404575267944;3881.223755249018;3996.620321069494;4005.7396269242404;5658.801941932013;5846.970765146147;6089.817305480843;6179.598326108789;6188.381161883955;4850.854474365775;5768.94231057684;5770.6249093808765;5928.288605367384;5942.38535044579;5648.943510564842;5661.518770904519;5673.347565030074;5891.764329426775;5926.407359264039;5614.719264036864;5742.856428589319;5826.970865145817;5858.86151554688;5873.295204575478;5236.371635403776;5387.191921211849;5703.201551782837;5931.406859314034;5933.881322373615;4479.037007043501;5838.32859221195;5846.473817356458;5859.974504461788;5861.032929566573 17:57:42 INFO - PROCESS | 1939 | |5;yui.html;703.5110598134297;727.8908163775436;733.9596322202277;756.2248695087535;793.8332950080483;15129.924350378247;15506.302216400261;15761.684766304681;15911.920440397782;15940.92029539852;14436.566902992927;14455.132692038485;14568.053076550017;14610.48863289785;14643.633909152277;811.6104269950398;851.5869803145454;881.1408876345544;882.2897567458193;885.6191837509868;704.1866644026132;722.2632914427281;846.1411667157839;871.2202578692066;880.7357792662208;854.5641722721426;887.0641473245723;915.3501014279869;917.7338571814166;922.861570764384;849.4436144325476;910.7814124610112;912.6851236323457;914.9797975257607;918.5820451694497;679.9970044184837;682.6484360554318;683.8427161752775;686.084077756196;688.2566827825985;14443.566692999228;14450.494232701805;14463;14478.927605361905;14500.854991450056;13795;14181.929090354586;14299.642508937255;14343.426262949573;14497.637559061002;9962.601495940155;11440.141989350748;11543.769124617545;11658.941705291503;11769.882301176964;3717.888463346104;3758.718096142773;3785.432185172219;3882.71870282806;3898.5321761388814;104078.47960760148;107235;108552;111613;111719;9914.603415863285;10909.509072091774;10964.396958167217;10987.780244395068;11395.886041139482;22627;22648;22680.206192783502;22700.432489187904;22754.544909101725;7002.739506888809;7740.342070923922;7924.722634707758;7936.6428510716005;8127.75616731499;4206.915861682811;4241.2365774160335;4287.356896465555;4317.956820431756;4364.54172311909;3572.8392222350058;3661.6704496594903;3700.7224458165747;3702.148505843671;3703.222323312081;627.2942939193407;642.1299139665779;658.5225711359226;712.707789806177;723.5333210079574;607.7903123422412;665.38503680535;670.808819486444;673.192169396726;691.5919607431639;7586;8828.274378585056;9347.532623368805;9508.572114254875;9603;565.7595521903257;569.9772009119589;604.1179877379076;611.9357467465941;672.5897202706345;4500.549945005473;4518.457785065747;4716.669833111718;4725.860265922998;4813.3983252093485;92579;124806;159435;163660;169337.30662693462;141720.62257198244;142357;142620;145314;149053;121856.39071804585;123190;123425.38287308686;123525.38237308756;127133;8842.646294148162;8979.82040359176;8995.955020224857;9086.863697044417;9100.954495227481;53819.73090134524;62742.05886911609;64404.35595643984;65636.34363656302;65725.07061970276;97396.02603974153;97620.51189744005;98663.506682469;103334;104395.4780226094;11648.88351116478;11667.708307292047;11702;11844.993175580172;12159.817602736142;10573.735656608338;12840.228735237484;13069.869301306866;13073.215607063607;13149;11961.940190299341;11979.161458697807;12122.75754484923;12195.207311524722;12541.686457838263;12063.81904271419;12083.57707480234;12185.390730463265;12584.307863067253;12640.304783236643;11744;11752.353620550952;11974.580889669169;12399.876001240233;12530.248185108923;10101.44442055694;10230.590776368861;10346;10398;10727.088197503299;7060.3292687194635;10308;10340;10389.792204156025;10417.374957502574 17:57:42 INFO - PROCESS | 1939 | __end_tp_report 17:57:42 INFO - PROCESS | 1939 | __start_cc_report 17:57:42 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,372 17:57:42 INFO - PROCESS | 1939 | __end_cc_report 17:57:42 INFO - PROCESS | 1939 | __startTimestamp1476752262334__endTimestamp 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | ------- Summary: start ------- 17:57:42 INFO - PROCESS | 1939 | Number of tests: 6 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | [#0] dojo.html Cycles:165 Average:5657.49 Median:5892.56 stddev:676.70 (11.5%) stddev-sans-first:639.70 17:57:42 INFO - PROCESS | 1939 | Values: 2768.8 2907.9 3370.7 3373.1 3382.4 5557.4 5605.4 5610.6 5697.5 5707.8 5447.6 5604.6 5612.6 5659.9 5716.3 5087.1 5151.2 5161.4 5167.2 5190.2 5179.8 5186.5 5194.9 5235.8 5319.2 4998.4 5184.0 5210.3 5228.7 5233.1 5039.9 5076.9 5084.8 5152.3 5153.0 4823.1 5066.9 5072.8 5086.7 5196.7 4241.5 5429.6 5636.5 5664.6 5684.7 6103.7 6124.1 6138.6 6201.6 6216.6 6164.0 6207.6 6238.4 6241.0 6242.0 4849.7 5648.1 5696.0 5845.9 5892.6 6142.5 6152.7 6188.9 6233.7 6305.5 6126.6 6142.2 6158.6 6169.3 6178.3 5983.3 6185.0 6195.8 6196.2 6209.5 6011.7 6043.8 6100.5 6125.1 6193.5 6079.5 6080.4 6142.8 6179.9 6182.9 4788.9 4928.0 4933.7 4943.9 4961.4 3570.7 4382.8 4457.0 4464.5 4563.9 5819.0 5893.5 5898.2 5937.7 5946.3 5713.3 5749.6 5787.1 5858.6 5882.7 5672.2 5727.6 5754.8 5769.3 5771.2 5353.0 5389.8 5409.3 5416.9 5442.5 6215.7 6227.8 6264.9 6269.5 6269.6 6094.1 6110.1 6121.7 6135.8 6136.8 6306.7 6311.8 6322.8 6342.8 6358.6 5999.2 6044.1 6064.9 6082.8 6112.5 4549.6 6099.2 6132.6 6188.8 6196.1 5966.6 6018.4 6027.0 6050.0 6078.5 5808.0 6013.9 6046.3 6050.6 6057.4 5667.1 5802.0 5873.5 5877.4 5880.4 6069.1 6107.2 6116.3 6116.5 6126.5 5943.3 5966.3 5971.4 6008.8 6019.4 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | [#1] ext.html Cycles:180 Average:68793.15 Median:38642.71 stddev:153327.15 (396.8%) stddev-sans-first:153671.35 17:57:42 INFO - PROCESS | 1939 | Values: 423.8 508.5 535.1 537.8 549.1 33925.6 38411.4 38417.0 38465.7 38525.8 31895.4 38629.6 38777.0 38910.8 39251.8 8222.8 9287.0 9487.1 9491.0 9496.5 8627.1 9004.0 9291.1 9408.8 9426.6 9378.0 9397.3 9413.4 9420.5 9440.6 9352.8 9381.0 9400.1 9403.2 9404.0 9302.1 9357.8 9374.3 9374.7 9382.2 37503.1 37669.8 37710.5 37737.0 37763.0 3253.7 3388.1 3795.0 3992.3 4094.2 29321.6 30147.8 30482.9 30593.2 30644.0 2811.4 2920.8 2941.9 3008.3 3219.1 326498.0 326842.0 382187.3 427430.9 429556.0 57222.9 57509.0 57550.4 57591.4 57741.0 56256.7 56720.7 57147.1 57351.9 57396.0 15788.4 15924.8 16006.4 16021.8 16027.9 12493.4 12556.6 12569.9 12580.3 12600.6 12300.4 15247.2 15439.0 15534.4 15635.6 2768.6 3278.6 3289.3 7040.7 7319.3 848.3 965.1 996.3 1018.2 1022.1 23086.0 27046.0 30307.1 30987.0 31147.7 997.7 1039.5 1096.2 1132.5 1202.5 15337.2 15688.3 15813.4 16018.7 16177.4 693375.0 693583.0 697422.0 699321.0 703840.0 57929.4 58175.0 58227.4 58302.1 58635.0 1160.8 1320.7 1331.7 1335.7 1338.7 24378.5 28915.7 29859.7 30334.0 30396.8 53848.0 55524.9 55710.7 59031.5 59181.4 586401.1 589578.0 590586.1 591598.0 594994.0 26164.2 42259.6 42305.2 42544.7 42556.4 45448.6 46697.5 46703.3 47075.8 47808.8 38321.4 38854.0 39455.0 39645.6 40016.0 38210.0 38485.2 38655.8 39433.0 39617.0 36921.0 37418.6 37492.1 37780.6 38153.6 28088.4 32334.0 33759.8 33879.3 34076.5 24094.9 25182.1 25501.6 25558.3 25995.2 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | [#2] jquery.html Cycles:180 Average:34753.17 Median:24633.13 stddev:98733.39 (400.8%) stddev-sans-first:98976.61 17:57:42 INFO - PROCESS | 1939 | Values: 369.2 373.0 374.6 379.6 396.7 19269.4 20180.1 20306.4 20578.7 20821.9 19542.9 19923.6 20350.0 20358.7 20679.9 5156.0 5436.5 5776.2 5843.5 6086.3 5791.7 5878.6 5882.4 5887.0 5896.7 4918.9 4969.3 5479.6 5855.4 5874.5 5825.2 5853.5 5869.5 5873.6 5878.9 4925.8 5104.8 5402.5 5468.7 5514.5 13188.3 13429.1 15446.4 24616.5 24649.8 2069.3 2090.2 2124.5 2261.2 2325.6 9703.5 9744.9 9786.6 10075.3 10316.1 1482.7 1613.6 1806.9 1824.9 1828.9 124319.0 125937.4 127098.0 130033.0 131119.3 10116.0 10644.1 11278.8 11627.9 11634.8 14221.4 14415.9 14439.8 14466.4 14468.4 6465.7 8216.9 8489.6 8557.0 8630.9 8378.4 8409.0 8437.0 8440.6 8549.1 4425.7 4496.9 4555.7 4564.7 4611.1 1655.5 1898.3 1908.6 1953.9 1966.7 756.9 861.4 893.8 909.9 1035.8 19626.9 20953.8 21136.6 21260.8 21520.9 906.4 970.7 974.8 984.8 993.8 8814.2 10573.3 11130.1 11257.8 11293.2 574233.0 577736.0 590403.0 599816.0 607323.0 114704.4 118298.4 118706.4 119603.0 125536.0 940.7 998.1 1033.4 1049.0 1052.5 18433.9 19135.6 19265.4 19562.9 19594.7 42792.0 51347.7 56077.2 57713.0 57812.4 84131.2 84524.0 84815.9 85910.6 86568.6 8065.2 8138.4 8203.0 8311.8 8312.8 11070.8 12948.5 12973.8 13120.5 13372.9 9314.4 11361.4 11382.7 11440.5 11584.8 9841.0 10489.3 10918.4 11080.2 11517.9 9289.0 11067.9 11250.8 11449.0 11575.5 7831.8 7891.6 8342.2 8367.2 8408.1 10233.1 11088.9 11193.4 11453.9 11535.9 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | [#3] mootools.html Cycles:180 Average:5641.14 Median:4904.08 stddev:3172.53 (64.7%) stddev-sans-first:3165.01 17:57:42 INFO - PROCESS | 1939 | Values: 1346.2 1534.4 1696.3 1819.8 1854.1 7898.3 8150.8 8426.0 9012.5 9027.9 8356.2 8652.9 8673.6 8762.8 8807.7 1974.7 2049.4 2347.5 2371.8 2379.6 2319.2 2351.4 2364.3 2365.3 2369.1 2242.8 2249.2 2253.5 2265.5 2286.1 2320.5 2355.2 2355.7 2361.9 2371.1 2274.0 2311.4 2315.5 2318.2 2332.0 8463.2 8512.0 8522.0 8522.4 8531.1 5196.7 5347.9 5349.2 5365.2 5393.6 3522.7 3586.9 3714.2 3725.1 3743.0 1045.9 1067.5 1081.2 1106.8 1109.2 11567.1 11659.9 11664.0 11675.5 11699.1 3762.2 4035.1 4712.2 4817.6 4821.3 7865.4 8443.7 9850.1 10006.8 10020.5 5294.5 5320.7 5351.9 5352.7 5356.3 4342.7 4415.2 4713.4 4896.0 4912.2 3918.8 3940.6 3995.9 4006.6 4075.9 1279.9 1293.9 1299.3 1312.7 1313.4 761.9 821.3 905.5 910.5 912.6 5820.4 5841.9 5971.9 6014.5 6094.4 402.2 436.8 443.8 445.8 446.7 3947.8 3957.5 3964.4 4058.6 4067.4 10386.9 10737.6 10761.2 10777.5 10812.2 10714.8 10729.2 10730.7 10737.3 10758.0 7960.8 8103.1 8111.2 8122.4 8147.3 5604.3 5605.3 5840.4 5854.8 5931.5 8282.0 9978.4 10011.5 10021.6 10033.3 9419.7 9832.3 9908.3 10042.0 10229.9 6214.5 6959.6 7194.7 7529.6 7710.6 6927.8 8340.2 8561.2 8567.9 8612.5 7341.4 7466.5 7472.5 7523.2 7580.1 7243.1 7385.1 7406.4 7521.9 7537.0 7041.8 7055.9 7102.4 7102.7 7105.8 4967.4 4974.5 5037.9 5063.3 5186.3 5062.7 5103.5 5186.3 5216.1 5267.1 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | [#4] prototype.html Cycles:180 Average:4704.31 Median:5391.81 stddev:1513.21 (28.1%) stddev-sans-first:1480.93 17:57:42 INFO - PROCESS | 1939 | Values: 301.6 305.0 314.3 315.8 320.6 4525.0 4608.4 5136.7 5396.4 5505.8 4497.5 4611.0 4682.6 5503.9 5516.0 4187.7 4189.0 4204.4 4205.5 4228.1 3917.8 4206.8 4216.2 4243.4 4244.4 3590.9 4138.2 4142.7 4145.6 4154.6 3433.6 3827.6 4130.4 4140.8 4190.5 3401.9 3580.6 3608.5 3608.9 3625.7 4871.8 5037.3 5328.9 5386.4 5423.9 4741.2 4770.9 4779.0 4783.3 4805.3 5346.2 5420.6 5638.8 5807.2 5894.0 3442.2 3645.7 3860.1 3932.7 3936.8 5147.8 5424.9 5649.9 6152.7 6333.7 3312.0 3319.6 3478.4 3988.2 4003.6 3769.9 3980.3 4030.2 4033.4 4042.7 4060.0 4709.2 4759.2 4769.9 4789.7 5584.6 5854.2 5942.9 5959.8 5966.4 1103.8 1122.9 1167.9 1249.4 1324.4 795.8 810.4 870.8 882.9 922.3 4464.4 5578.8 5601.2 5623.5 5696.1 5454.8 5647.4 5795.5 6020.0 6038.4 5293.7 5570.4 5570.5 5619.6 5677.4 2428.2 2669.0 3047.7 3141.9 3210.3 6113.1 6284.4 6323.0 6325.3 6349.4 5877.9 6192.3 6213.0 6219.0 6232.8 5684.5 6165.9 6260.9 6304.8 6328.8 5332.1 5545.9 5799.7 6009.2 6049.7 4450.4 5655.3 6079.7 6103.2 6116.5 5324.5 6026.8 6323.5 6350.2 6391.2 3298.0 3721.4 3881.2 3996.6 4005.7 5658.8 5847.0 6089.8 6179.6 6188.4 4850.9 5768.9 5770.6 5928.3 5942.4 5648.9 5661.5 5673.3 5891.8 5926.4 5614.7 5742.9 5827.0 5858.9 5873.3 5236.4 5387.2 5703.2 5931.4 5933.9 4479.0 5838.3 5846.5 5860.0 5861.0 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - PROCESS | 1939 | [#5] yui.html Cycles:180 Average:25416.63 Median:3701.44 stddev:41802.18 (1129.4%) stddev-sans-first:41878.26 17:57:42 INFO - PROCESS | 1939 | Values: 703.5 727.9 734.0 756.2 793.8 15129.9 15506.3 15761.7 15911.9 15940.9 14436.6 14455.1 14568.1 14610.5 14643.6 811.6 851.6 881.1 882.3 885.6 704.2 722.3 846.1 871.2 880.7 854.6 887.1 915.4 917.7 922.9 849.4 910.8 912.7 915.0 918.6 680.0 682.6 683.8 686.1 688.3 14443.6 14450.5 14463.0 14478.9 14500.9 13795.0 14181.9 14299.6 14343.4 14497.6 9962.6 11440.1 11543.8 11658.9 11769.9 3717.9 3758.7 3785.4 3882.7 3898.5 104078.5 107235.0 108552.0 111613.0 111719.0 9914.6 10909.5 10964.4 10987.8 11395.9 22627.0 22648.0 22680.2 22700.4 22754.5 7002.7 7740.3 7924.7 7936.6 8127.8 4206.9 4241.2 4287.4 4318.0 4364.5 3572.8 3661.7 3700.7 3702.1 3703.2 627.3 642.1 658.5 712.7 723.5 607.8 665.4 670.8 673.2 691.6 7586.0 8828.3 9347.5 9508.6 9603.0 565.8 570.0 604.1 611.9 672.6 4500.5 4518.5 4716.7 4725.9 4813.4 92579.0 124806.0 159435.0 163660.0 169337.3 141720.6 142357.0 142620.0 145314.0 149053.0 121856.4 123190.0 123425.4 123525.4 127133.0 8842.6 8979.8 8996.0 9086.9 9101.0 53819.7 62742.1 64404.4 65636.3 65725.1 97396.0 97620.5 98663.5 103334.0 104395.5 11648.9 11667.7 11702.0 11845.0 12159.8 10573.7 12840.2 13069.9 13073.2 13149.0 11961.9 11979.2 12122.8 12195.2 12541.7 12063.8 12083.6 12185.4 12584.3 12640.3 11744.0 11752.4 11974.6 12399.9 12530.2 10101.4 10230.6 10346.0 10398.0 10727.1 7060.3 10308.0 10340.0 10389.8 10417.4 17:57:42 INFO - PROCESS | 1939 | -------- Summary: end -------- 17:57:42 INFO - PROCESS | 1939 | 17:57:42 INFO - TEST-INFO | 1939: exit 0 17:57:42 INFO - TEST-OK | dromaeo_css | took 1115125ms 17:57:42 INFO - TEST-START | kraken 17:57:42 INFO - operating with platform_type : mac_ 17:57:42 INFO - Initialising browser for kraken test... 17:57:42 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmp6KVsQL/profile http://localhost:52063/getInfo.html) 17:57:45 INFO - PROCESS | 1944 | 2016-10-17 17:57:45.680 plugin-container[1945:19227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 17:57:45 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:45 INFO - PROCESS | 1944 | 2016-10-17 17:57:45.683 plugin-container[1945:19227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 17:57:45 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:45 INFO - PROCESS | 1944 | 2016-10-17 17:57:45.683 plugin-container[1945:19227] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:45 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 17:57:45 INFO - PROCESS | 1944 | colorDepth:24 17:57:45 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 17:57:45 INFO - PROCESS | 1944 | __metrics 17:57:45 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 17:57:46 INFO - TEST-INFO | 1944: exit 0 17:57:46 INFO - Browser initialized. 17:57:46 INFO - Running cycle 1/1 for kraken test... 17:57:46 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmp6KVsQL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:57:48 INFO - PROCESS | 1946 | 2016-10-17 17:57:48.835 plugin-container[1947:19347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 17:57:48 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:48 INFO - PROCESS | 1946 | 2016-10-17 17:57:48.836 plugin-container[1947:19347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 17:57:48 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:48 INFO - PROCESS | 1946 | 2016-10-17 17:57:48.836 plugin-container[1947:19347] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:58 INFO - PROCESS | 1946 | RSS: Main: 244682752 17:57:58 INFO - PROCESS | 1946 | 17:58:01 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-beat-detection) 17:58:02 INFO - PROCESS | 1946 | RSS: Main: 256487424 17:58:02 INFO - PROCESS | 1946 | 17:58:05 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-dft) 17:58:05 INFO - PROCESS | 1946 | RSS: Main: 263917568 17:58:05 INFO - PROCESS | 1946 | 17:58:09 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-fft) 17:58:09 INFO - PROCESS | 1946 | RSS: Main: 263315456 17:58:09 INFO - PROCESS | 1946 | 17:58:11 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-oscillator) 17:58:12 INFO - PROCESS | 1946 | RSS: Main: 264593408 17:58:12 INFO - PROCESS | 1946 | 17:58:14 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52063/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:58:15 INFO - PROCESS | 1946 | RSS: Main: 264347648 17:58:15 INFO - PROCESS | 1946 | 17:58:18 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52063/tests/kraken/driver.html?testName=imaging-darkroom) 17:58:18 INFO - PROCESS | 1946 | RSS: Main: 265871360 17:58:18 INFO - PROCESS | 1946 | 17:58:21 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52063/tests/kraken/driver.html?testName=imaging-desaturate) 17:58:22 INFO - PROCESS | 1946 | RSS: Main: 266166272 17:58:22 INFO - PROCESS | 1946 | 17:58:25 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52063/tests/kraken/driver.html?testName=json-parse-financial) 17:58:25 INFO - PROCESS | 1946 | RSS: Main: 266338304 17:58:25 INFO - PROCESS | 1946 | 17:58:27 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52063/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:58:27 INFO - PROCESS | 1946 | RSS: Main: 265670656 17:58:27 INFO - PROCESS | 1946 | 17:58:29 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:58:30 INFO - PROCESS | 1946 | RSS: Main: 265990144 17:58:30 INFO - PROCESS | 1946 | 17:58:32 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:58:33 INFO - PROCESS | 1946 | RSS: Main: 265723904 17:58:33 INFO - PROCESS | 1946 | 17:58:36 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:58:37 INFO - PROCESS | 1946 | RSS: Main: 266170368 17:58:37 INFO - PROCESS | 1946 | 17:58:40 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:58:40 INFO - PROCESS | 1946 | RSS: Main: 265928704 17:58:40 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52063/tests/kraken/driver.html?testName=ai-astar) 17:58:42 INFO - PROCESS | 1946 | RSS: Main: 267264000 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | __start_tp_report 17:58:42 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 17:58:42 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 17:58:42 INFO - PROCESS | 1946 | |i|pagename|runs| 17:58:42 INFO - PROCESS | 1946 | |0;ai-astar;114;117;119;117;117;119;117;117;118;115 17:58:42 INFO - PROCESS | 1946 | |1;audio-beat-detection;152;112;105;159;148;109;111;148;184;119 17:58:42 INFO - PROCESS | 1946 | |2;audio-dft;203;220;222;274;275;198;194;196;198;193 17:58:42 INFO - PROCESS | 1946 | |3;audio-fft;84;58;58;85;71;76;71;78;106;89 17:58:42 INFO - PROCESS | 1946 | |4;audio-oscillator;91;72;89;60;86;62;86;91;106;123 17:58:42 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;89;86;89;86;90;85;103;132;111;86 17:58:42 INFO - PROCESS | 1946 | |6;imaging-darkroom;100;96;94;119;92;150;153;95;94;95 17:58:42 INFO - PROCESS | 1946 | |7;imaging-desaturate;101;91;92;93;93;91;92;96;92;79 17:58:42 INFO - PROCESS | 1946 | |8;json-parse-financial;56;56;55;56;57;55;55;55;57;55 17:58:42 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;71;70;70;75;87;74;70;71;72;70 17:58:42 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;87;90;89;87;90;86;100;106;111;89 17:58:42 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;147;150;148;157;164;158;145;150;187;148 17:58:42 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;189;244;177;173;172;172;238;236;187;173 17:58:42 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;74;71;68;69;74;82;91;92;73;68 17:58:42 INFO - PROCESS | 1946 | __end_tp_report 17:58:42 INFO - PROCESS | 1946 | __start_cc_report 17:58:42 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,961 17:58:42 INFO - PROCESS | 1946 | __end_cc_report 17:58:42 INFO - PROCESS | 1946 | __startTimestamp1476752322913__endTimestamp 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | ------- Summary: start ------- 17:58:42 INFO - PROCESS | 1946 | Number of tests: 14 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.00 stddev:1.56 (1.3%) stddev-sans-first:1.22 17:58:42 INFO - PROCESS | 1946 | Values: 114.0 117.0 119.0 117.0 117.0 119.0 117.0 117.0 118.0 115.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:148.00 stddev:26.96 (18.2%) stddev-sans-first:27.86 17:58:42 INFO - PROCESS | 1946 | Values: 152.0 112.0 105.0 159.0 148.0 109.0 111.0 148.0 184.0 119.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:217.30 Median:211.50 stddev:31.82 (15.0%) stddev-sans-first:33.32 17:58:42 INFO - PROCESS | 1946 | Values: 203.0 220.0 222.0 274.0 275.0 198.0 194.0 196.0 198.0 193.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:77.60 Median:77.00 stddev:14.49 (18.8%) stddev-sans-first:15.19 17:58:42 INFO - PROCESS | 1946 | Values: 84.0 58.0 58.0 85.0 71.0 76.0 71.0 78.0 106.0 89.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:86.00 stddev:19.07 (22.2%) stddev-sans-first:20.16 17:58:42 INFO - PROCESS | 1946 | Values: 91.0 72.0 89.0 60.0 86.0 62.0 86.0 91.0 106.0 123.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:86.00 stddev:15.36 (17.9%) stddev-sans-first:16.10 17:58:42 INFO - PROCESS | 1946 | Values: 89.0 86.0 89.0 86.0 90.0 85.0 103.0 132.0 111.0 86.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:94.00 stddev:23.80 (25.3%) stddev-sans-first:25.03 17:58:42 INFO - PROCESS | 1946 | Values: 100.0 96.0 94.0 119.0 92.0 150.0 153.0 95.0 94.0 95.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:92.00 stddev:5.48 (6.0%) stddev-sans-first:4.74 17:58:42 INFO - PROCESS | 1946 | Values: 101.0 91.0 92.0 93.0 93.0 91.0 92.0 96.0 92.0 79.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 17:58:42 INFO - PROCESS | 1946 | Values: 56.0 56.0 55.0 56.0 57.0 55.0 55.0 55.0 57.0 55.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:71.50 stddev:5.23 (7.3%) stddev-sans-first:5.49 17:58:42 INFO - PROCESS | 1946 | Values: 71.0 70.0 70.0 75.0 87.0 74.0 70.0 71.0 72.0 70.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:88.00 stddev:8.89 (10.1%) stddev-sans-first:9.11 17:58:42 INFO - PROCESS | 1946 | Values: 87.0 90.0 89.0 87.0 90.0 86.0 100.0 106.0 111.0 89.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:153.50 stddev:12.60 (8.2%) stddev-sans-first:12.99 17:58:42 INFO - PROCESS | 1946 | Values: 147.0 150.0 148.0 157.0 164.0 158.0 145.0 150.0 187.0 148.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.10 Median:188.00 stddev:30.49 (16.2%) stddev-sans-first:32.24 17:58:42 INFO - PROCESS | 1946 | Values: 189.0 244.0 177.0 173.0 172.0 172.0 238.0 236.0 187.0 173.0 17:58:42 INFO - PROCESS | 1946 | 17:58:42 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:74.00 stddev:9.04 (12.2%) stddev-sans-first:9.55 17:58:42 INFO - PROCESS | 1946 | Values: 74.0 71.0 68.0 69.0 74.0 82.0 91.0 92.0 73.0 68.0 17:58:42 INFO - PROCESS | 1946 | -------- Summary: end -------- 17:58:42 INFO - PROCESS | 1946 | 17:58:43 INFO - TEST-INFO | 1946: exit 0 17:58:43 INFO - TEST-OK | kraken | took 60802ms 17:58:43 INFO - SUITE-END | took 1175s 17:58:44 INFO - Completed test suite (00:19:36) 17:58:44 INFO - javascript benchmark 17:58:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7269.988046838606, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2768.792340574457, 2907.9127626171216, 3370.7471939604534, 3373.055544447554, 3382.391169589473, 5557.444255574445, 5605.355384130826, 5610.551155907523, 5697.4587414195685, 5707.800226992056, 5447.564194864412, 5604.551635869131, 5612.607117501777, 5659.8585035374135, 5716.314042314918, 5087.135187018206, 5151.227315902607, 5161.406438259592, 5167.224916262553, 5190.169572868342, 5179.792808287664, 5186.481351864821, 5194.948050519503, 5235.790568377261, 5319.20211968204, 4998.3502144721315, 5183.989122121188, 5210.322658054467, 5228.660137091077, 5233.058049551079, 5039.899202015976, 5076.898462030738, 5084.847454576368, 5152.304438900738, 5153.020926024081, 4823.13183626947, 5066.923996140061, 5072.82245121419, 5086.745662716849, 5196.740162991835, 4241.533431322552, 5429.592780541475, 5636.4927156556105, 5664.631798933056, 5684.687342196177, 6103.725332360054, 6124.081387791823, 6138.633639632414, 6201.596896201778, 6216.595921265103, 6163.96918015407, 6207.596506227126, 6238.376162383771, 6240.968795156041, 6242.032484964838, 4849.733264670406, 5648.068068768607, 5695.971520142456, 5845.88308233833, 5892.558058145656, 6142.5294708490455, 6152.70793133438, 6188.916939535563, 6233.688315584293, 6305.495560355161, 6126.571140020245, 6142.170806941006, 6158.568900177034, 6169.321374648785, 6178.258608966953, 5983.282006159288, 6185.041318595625, 6195.845103872349, 6196.225471816023, 6209.472194863397, 6011.729472173763, 6043.788467403708, 6100.511959043266, 6125.082842094287, 6193.535484838565, 6079.480129967508, 6080.427564339064, 6142.78500252489, 6179.938200617937, 6182.876342473125, 4788.880277993008, 4928.0390323886495, 4933.679310844784, 4943.8516844494725, 4961.355023846877, 3570.6607872252093, 4382.846600368972, 4456.955430445719, 4464.531224221475, 4563.931541026887, 5819.0, 5893.528517718572, 5898.174255604219, 5937.70311484424, 5946.316173640087, 5713.342965559014, 5749.568782341341, 5787.131930210502, 5858.589898707135, 5882.7352119294555, 5672.234248376499, 5727.599068065196, 5754.769809207669, 5769.278840144982, 5771.249737534177, 5353.0, 5389.83830485086, 5409.323834520685, 5416.864578385572, 5442.537384322298, 6215.65813880241, 6227.782027629011, 6264.906026409516, 6269.498440124779, 6269.62382257066, 6094.146819445193, 6110.114033465034, 6121.696863452088, 6135.785247516315, 6136.7852125175405, 6306.684665766602, 6311.842203944938, 6322.841928951813, 6342.778002769881, 6358.586691865014, 5999.220101386792, 6044.123602077762, 6064.878702426015, 6082.817515474543, 6112.480439162721, 4549.613282870993, 6099.2189903441595, 6132.600268413353, 6188.783392581238, 6196.0705894116245, 5966.612170208922, 6018.3573822333365, 6027.035674292092, 6049.969750151397, 6078.45293923549, 5808.041673123887, 6013.939860601338, 6046.27444706638, 6050.6369617823075, 6057.394260574084, 5667.0649342859615, 5802.0, 5873.530117590497, 5877.382874798255, 5880.382559831156, 6069.089636554376, 6107.158472117419, 6116.305337585956, 6116.487945662109, 6126.517673797367, 5943.346231914573, 5966.3138739043825, 5971.4327138921735, 6008.819735407945, 6019.398060194121], "value": 5612.243514413748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.82834951844507, 508.4864287070058, 535.0769921884747, 537.7929497143599, 549.1049589169651, 33925.64297428103, 38411.42382864259, 38417.0, 38465.653702120464, 38525.807370963106, 31895.362092758132, 38629.61370386295, 38777.03057423566, 38910.8054459728, 39251.803740981326, 8222.766585012236, 9286.953565232181, 9487.098725621056, 9491.0, 9496.477693726842, 8627.137286271354, 9004.0, 9291.117343852356, 9408.811823763555, 9426.622935082589, 9378.0, 9397.29520285978, 9413.388129771543, 9420.528973551329, 9440.622375104987, 9352.7661808455, 9381.0, 9400.106989835955, 9403.153716165509, 9404.0, 9302.116298951589, 9357.812843743088, 9374.250059995184, 9374.67188648394, 9382.155605995426, 37503.06242343936, 37669.81165094185, 37710.531972863784, 37737.0, 37763.0, 3253.723433508155, 3388.0513456232293, 3794.9620503794886, 3992.3412636914873, 4094.2016306820183, 29321.56017659737, 30147.849260753774, 30482.93309734171, 30593.235169120726, 30644.0, 2811.4377124575167, 2920.810147340437, 2941.911492747697, 3008.3381656035635, 3219.0825614699934, 326498.0, 326842.0, 382187.26719099237, 427430.8628456869, 429555.99999999686, 57222.85554288891, 57509.0, 57550.42449575451, 57591.42408575944, 57741.0, 56256.71871640698, 56720.71639641858, 57147.14279285814, 57351.85296294051, 57396.0, 15788.447404340794, 15924.76112858308, 16006.439774608065, 16021.839781602268, 16027.919860400623, 12493.375331233583, 12556.560520381743, 12569.931555817675, 12580.308083055337, 12600.55898043564, 12300.446479908425, 15247.16140612277, 15439.0, 15534.378624855106, 15635.60910977212, 2768.5708715149185, 3278.5573947517255, 3289.3256882339065, 7040.718371265194, 7319.268073192638, 848.3467729848106, 965.1313817564248, 996.2577879479834, 1018.1905385218769, 1022.1311884897777, 23086.0, 27046.0, 30307.090787275974, 30987.0, 31147.68852311493, 997.7455748784158, 1039.5477967084191, 1096.17786660005, 1132.473399869065, 1202.506972141418, 15337.156456394781, 15688.294026768823, 15813.367465301259, 16018.679626407407, 16177.4337898173, 693375.0, 693583.0, 697422.0, 699321.0, 703840.0, 57929.4207057924, 58175.0, 58227.41772582305, 58302.125468118014, 58635.0, 1160.785254727878, 1320.7490576790378, 1331.7203387288778, 1335.7194989052407, 1338.6586420462913, 24378.51242975166, 28915.710842892142, 29859.70140298569, 30334.0, 30396.84801575978, 53848.0, 55524.889502210535, 55710.72144639251, 59031.52421189505, 59181.40818591759, 586401.0679946573, 589578.0, 590586.0941390531, 591598.0, 594994.0, 26164.215073547824, 42259.577404225565, 42305.15389692251, 42544.7236582903, 42556.36165457459, 45448.636540903826, 46697.53302466932, 46703.29945050895, 47075.76462117668, 47808.760956194994, 38321.42517862179, 38854.0, 39455.0, 39645.60354396419, 40015.99960001023, 38210.0, 38485.230295394496, 38655.80672096622, 39433.0, 39617.0, 36921.0, 37418.62581374151, 37492.06269843167, 37780.62219377771, 38153.61846381501, 28088.43823123567, 32334.0, 33759.83120084482, 33879.3224135511, 34076.488852666735, 24094.87952560226, 25182.11862584801, 25501.64064830437, 25558.338707983923, 25995.220143395727], "value": 19402.766550607692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.1933126119429, 372.9552453705556, 374.62724589033905, 379.5995225037584, 396.728241154809, 19269.421917342486, 20180.091895864683, 20306.390808275755, 20578.691319630198, 20821.895890520525, 19542.902285488588, 19923.601527969433, 20350.0, 20358.69461958068, 20679.896600516975, 5156.0, 5436.537894278991, 5776.162456443813, 5843.5033022193165, 6086.269647642267, 5791.71041447928, 5878.617889837147, 5882.382349853268, 5886.999210134288, 5896.7051647417675, 4918.926216106761, 4969.304297398367, 5479.561635069185, 5855.385184555625, 5874.5006674432725, 5825.1844741736195, 5853.50245229156, 5869.50109240715, 5873.647581145124, 5878.941210587882, 4925.802967881281, 5104.821331253425, 5402.486763757437, 5468.72656367184, 5514.531264842493, 13188.340582970908, 13429.059965802398, 15446.382144714198, 24616.5076698465, 24649.753502464926, 2069.2550681754556, 2090.1639344262417, 2124.53226907419, 2261.21987914171, 2325.616273314901, 9703.514824258758, 9744.90255097447, 9786.5596048178, 10075.294729368874, 10316.123129533999, 1482.6589884326665, 1613.644998100416, 1806.9096545172688, 1824.8540116790625, 1828.9176987035614, 124319.0, 125937.37031314785, 127098.0, 130033.0, 131119.34440327832, 10116.0, 10644.095251903675, 11278.830817537744, 11627.941860290644, 11634.76730465386, 14221.431142754174, 14415.855841441451, 14439.783403248959, 14466.421343146367, 14468.421263149567, 6465.676716164173, 8216.876746848682, 8489.61796719149, 8556.957215213884, 8630.877985861924, 8378.413511054168, 8408.957955210184, 8436.957815211008, 8440.62017209227, 8549.102344253764, 4425.734455932655, 4496.887577810581, 4555.749433781108, 4564.7032942858605, 4611.077784443058, 1655.4867990922853, 1898.2691663709572, 1908.5906481048564, 1953.9218431262875, 1966.6989622142128, 756.9053868266467, 861.4228466927174, 893.8391089603804, 909.9090090990848, 1035.77213013137, 19626.901865490865, 20953.79046209549, 21136.577268454545, 21260.78739212619, 21520.89239553792, 906.404727679276, 970.7087873638021, 974.770928831724, 984.7538115471132, 993.7813680990171, 8814.206721395105, 10573.312734672221, 11130.053945414731, 11257.774844503065, 11293.15301352402, 574233.0, 577736.0, 590403.0, 599816.0, 607323.0, 114704.42647786708, 118298.4085079569, 118706.40646796711, 119603.0, 125536.0, 940.741296143566, 998.0767789794411, 1033.4316126130598, 1048.9737756556146, 1052.5263631365915, 18433.90783046076, 19135.617287654448, 19265.422037338903, 19562.90218548898, 19594.706079408534, 42791.999999998756, 51347.74326128345, 56077.158842616576, 57713.0, 57812.4218757807, 84131.15868841234, 84524.0, 84815.87960301041, 85910.57044714736, 86568.56715716381, 8065.193480652122, 8138.389620778347, 8202.958985205036, 8311.792205194919, 8312.750617481484, 11070.833937490783, 12948.546800861925, 12973.805392919303, 13120.540781072617, 13372.86627133755, 9314.394564353295, 11361.37512436824, 11382.715432114263, 11440.542378305108, 11584.826227606423, 9840.950795245977, 10489.318194317346, 10918.399488028235, 11080.168987325822, 11517.884821151682, 9289.0, 11067.889321107008, 11250.774984500427, 11449.0, 11575.479103440533, 7831.765047048598, 7891.64487598071, 8342.249197572248, 8367.205115514016, 8408.117147699404, 10233.079022887976, 11088.889111109109, 11193.384363859741, 11453.885461145283, 11535.884641153816], "value": 9380.735231534563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.1721041559445, 1534.4169215698032, 1696.3469064410206, 1819.8453131483823, 1854.1470923375239, 7898.289153976141, 8150.755477335674, 8426.0, 9012.504312262823, 9027.864582031274, 8356.247937685606, 8652.91347086529, 8673.60968756406, 8762.780930476742, 8807.691730789424, 1974.664307067802, 2049.3646969439524, 2347.542229265294, 2371.7983971362455, 2379.643053541974, 2319.1766922742436, 2351.3651314145172, 2364.326167042393, 2365.31405892291, 2369.123424332999, 2242.7532971373134, 2249.1902914950606, 2253.481699209183, 2265.490264690448, 2286.1198438601177, 2320.5242925200464, 2355.1756885090167, 2355.7173139223232, 2361.9173328933575, 2371.051579368258, 2274.0108052996998, 2311.3528212100637, 2315.5368926214824, 2318.1538738360377, 2332.0, 8463.238308552198, 8512.0, 8522.01996770373, 8522.360822938304, 8531.06158322584, 5196.68819870809, 5347.919781203246, 5349.197620356938, 5365.168398898177, 5393.622446428752, 3522.6653467920505, 3586.8923932282073, 3714.238581090897, 3725.1245957200035, 3743.026813028605, 1045.8849526552074, 1067.5089458848938, 1081.1945100899802, 1106.8007758603449, 1109.1958330210614, 11567.13246506507, 11659.941700291443, 11664.0, 11675.532978680927, 11699.064074873904, 3762.247550489913, 4035.1324465240114, 4712.175369310426, 4817.614590832725, 4821.300911367902, 7865.4494185406475, 8443.746687599381, 9850.113489785954, 10006.79986400268, 10020.549075291628, 5294.49702278283, 5320.654157479751, 5351.919721204107, 5352.68859129508, 5356.276902618097, 4342.739435633872, 4415.205263052681, 4713.363695901078, 4895.951040489621, 4912.214045752662, 3918.843246270117, 3940.645341919241, 3995.9400608990886, 4006.579309172553, 4075.9388609170887, 1279.9440461619201, 1293.8512071111754, 1299.27240745183, 1312.6718320419895, 1313.4220942785144, 761.9085709714759, 821.34292565947, 905.5200743605899, 910.4946754551288, 912.6167009855876, 5820.417958204231, 5841.9415805842245, 5971.910421343683, 6014.548908831855, 6094.39056094387, 402.16951994132023, 436.77069538492543, 443.7714576992851, 445.8016182798688, 446.6716963032169, 3947.822347994347, 3957.5448823385677, 3964.385520244367, 4058.553559108496, 4067.4102255172834, 10386.948065259625, 10737.570497180182, 10761.246712730033, 10777.46112694378, 10812.243142979916, 10714.78570428587, 10729.248952573245, 10730.678079657622, 10737.302075365076, 10758.031777140095, 7960.800979975547, 8103.10865804761, 8111.1888811118415, 8122.431429799975, 8147.3482121431325, 5604.327480702342, 5605.271314729059, 5840.386759390203, 5854.824355269348, 5931.525477961839, 8281.95859020701, 9978.351407158512, 10011.54948027355, 10021.649242276486, 10033.347832390871, 9419.717408477756, 9832.311738178545, 9908.306418550632, 10042.0, 10229.948850255701, 6214.471769899609, 6959.61722105289, 7194.748183813542, 7529.58587277705, 7710.575918324546, 6927.792166235021, 8340.165983401612, 8561.1866872647, 8567.914320856711, 8612.48325100496, 7341.412686985135, 7466.477346585687, 7472.476926615085, 7523.247675232433, 7580.128285247267, 7243.0946131733535, 7385.113786345673, 7406.4074874008875, 7521.9469274300545, 7537.0, 7041.788746337618, 7055.858882822212, 7102.360787529147, 7102.680379382837, 7105.75129870452, 4967.379077615298, 4974.546176959916, 5037.949620503895, 5063.3417655704525, 5186.273921651047, 5062.670926389773, 5103.464136265638, 5186.299849520417, 5216.113260745758, 5267.130923397596], "value": 4459.148143239978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.59887349824737, 305.0, 314.28813736885957, 315.78684388038096, 320.5944480232506, 4525.0, 4608.446986361637, 5136.691798492093, 5396.43337449568, 5505.752241149148, 4497.505274419811, 4611.008633143873, 4682.578567928886, 5503.889922201553, 5516.0, 4187.664986801064, 4188.9946412861, 4204.3903633973205, 4205.537390887, 4228.133232687307, 3917.8041097945134, 4206.780033790197, 4216.219999300124, 4243.405923170743, 4244.405783190356, 3590.9461358079648, 4138.234426631067, 4142.715758114979, 4145.585441455861, 4154.584541545852, 3433.639467855877, 3827.636374544442, 4130.442390277334, 4140.7515549067, 4190.455240818705, 3401.914952126192, 3580.6419358064245, 3608.4767708682093, 3608.855645774166, 3625.709943204564, 4871.8294859679745, 5037.319961805146, 5328.893422131575, 5386.380566234894, 5423.864403389907, 4741.241401375763, 4770.928436073462, 4779.0, 4783.258594917794, 4805.303231031516, 5346.1713434417725, 5420.617742475643, 5638.815848671784, 5807.245058142415, 5893.970530147365, 3442.225499262671, 3645.6901163401144, 3860.104921309018, 3932.665723413513, 3936.8031598419966, 5147.794088236466, 5424.891502169974, 5649.898269837383, 6152.661603611799, 6333.7466501339895, 3311.983440082784, 3319.6348401675796, 3478.356504046709, 3988.222296652125, 4003.5996400360314, 3769.905752356213, 3980.283548961157, 4030.234255491447, 4033.3748269018347, 4042.7372220805555, 4060.025593857512, 4709.152352576501, 4759.16714574948, 4769.928451073237, 4789.736564488987, 5584.553235741131, 5854.151148083504, 5942.851428714317, 5959.761609535657, 5966.43318884714, 1103.834424836281, 1122.8820973797633, 1167.8788363171282, 1249.4377530111485, 1324.3577963325406, 795.8169620987205, 810.4124509730399, 870.8069944176392, 882.9293656507464, 922.3313098004028, 4464.419625448671, 5578.776848926079, 5601.187827764951, 5623.481659951872, 5696.088625819932, 5454.754536045808, 5647.352647352647, 5795.507381872588, 6020.026782323645, 6038.396160383926, 5293.682379057269, 5570.442955704397, 5570.498655120978, 5619.634723742944, 5677.432256774289, 2428.210831479776, 2668.9733102669115, 3047.679993600684, 3141.858616362269, 3210.2776875202894, 6113.0743815697915, 6284.4344009039405, 6322.968385158045, 6325.335839736854, 6349.428551430394, 5877.942720047949, 6192.349803270682, 6212.968935155295, 6218.968905155536, 6232.844178895473, 5684.522024273719, 6165.876682466325, 6260.906086408799, 6304.8423789404715, 6328.797528469576, 5332.146856502941, 5545.944540554704, 5799.708215920273, 6009.188759517409, 6049.727762250797, 4450.354698568624, 5655.293088363955, 6079.696015199311, 6103.176071230327, 6116.510679145568, 5324.520793128637, 6026.758929642765, 6323.525735569942, 6350.206224221973, 6391.201099862517, 3298.0, 3721.404575267944, 3881.223755249018, 3996.620321069494, 4005.7396269242404, 5658.801941932013, 5846.970765146147, 6089.817305480843, 6179.598326108789, 6188.381161883955, 4850.854474365775, 5768.94231057684, 5770.6249093808765, 5928.288605367384, 5942.38535044579, 5648.943510564842, 5661.518770904519, 5673.347565030074, 5891.764329426775, 5926.407359264039, 5614.719264036864, 5742.856428589319, 5826.970865145817, 5858.86151554688, 5873.295204575478, 5236.371635403776, 5387.191921211849, 5703.201551782837, 5931.406859314034, 5933.881322373615, 4479.037007043501, 5838.32859221195, 5846.473817356458, 5859.974504461788, 5861.032929566573], "value": 4209.298693111907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5110598134297, 727.8908163775436, 733.9596322202277, 756.2248695087535, 793.8332950080483, 15129.924350378247, 15506.302216400261, 15761.684766304681, 15911.920440397782, 15940.92029539852, 14436.566902992927, 14455.132692038485, 14568.053076550017, 14610.48863289785, 14643.633909152277, 811.6104269950398, 851.5869803145454, 881.1408876345544, 882.2897567458193, 885.6191837509868, 704.1866644026132, 722.2632914427281, 846.1411667157839, 871.2202578692066, 880.7357792662208, 854.5641722721426, 887.0641473245723, 915.3501014279869, 917.7338571814166, 922.861570764384, 849.4436144325476, 910.7814124610112, 912.6851236323457, 914.9797975257607, 918.5820451694497, 679.9970044184837, 682.6484360554318, 683.8427161752775, 686.084077756196, 688.2566827825985, 14443.566692999228, 14450.494232701805, 14463.0, 14478.927605361905, 14500.854991450056, 13795.0, 14181.929090354586, 14299.642508937255, 14343.426262949573, 14497.637559061002, 9962.601495940155, 11440.141989350748, 11543.769124617545, 11658.941705291503, 11769.882301176964, 3717.888463346104, 3758.718096142773, 3785.432185172219, 3882.71870282806, 3898.5321761388814, 104078.47960760148, 107235.0, 108552.0, 111613.0, 111719.0, 9914.603415863285, 10909.509072091774, 10964.396958167217, 10987.780244395068, 11395.886041139482, 22627.0, 22648.0, 22680.206192783502, 22700.432489187904, 22754.544909101725, 7002.739506888809, 7740.342070923922, 7924.722634707758, 7936.6428510716005, 8127.75616731499, 4206.915861682811, 4241.2365774160335, 4287.356896465555, 4317.956820431756, 4364.54172311909, 3572.8392222350058, 3661.6704496594903, 3700.7224458165747, 3702.148505843671, 3703.222323312081, 627.2942939193407, 642.1299139665779, 658.5225711359226, 712.707789806177, 723.5333210079574, 607.7903123422412, 665.38503680535, 670.808819486444, 673.192169396726, 691.5919607431639, 7586.0, 8828.274378585056, 9347.532623368805, 9508.572114254875, 9603.0, 565.7595521903257, 569.9772009119589, 604.1179877379076, 611.9357467465941, 672.5897202706345, 4500.549945005473, 4518.457785065747, 4716.669833111718, 4725.860265922998, 4813.3983252093485, 92579.0, 124806.0, 159435.0, 163660.0, 169337.30662693462, 141720.62257198244, 142357.0, 142620.0, 145314.0, 149053.0, 121856.39071804585, 123190.0, 123425.38287308686, 123525.38237308756, 127133.0, 8842.646294148162, 8979.82040359176, 8995.955020224857, 9086.863697044417, 9100.954495227481, 53819.73090134524, 62742.05886911609, 64404.35595643984, 65636.34363656302, 65725.07061970276, 97396.02603974153, 97620.51189744005, 98663.506682469, 103334.0, 104395.4780226094, 11648.88351116478, 11667.708307292047, 11702.0, 11844.993175580172, 12159.817602736142, 10573.735656608338, 12840.228735237484, 13069.869301306866, 13073.215607063607, 13149.0, 11961.940190299341, 11979.161458697807, 12122.75754484923, 12195.207311524722, 12541.686457838263, 12063.81904271419, 12083.57707480234, 12185.390730463265, 12584.307863067253, 12640.304783236643, 11744.0, 11752.353620550952, 11974.580889669169, 12399.876001240233, 12530.248185108923, 10101.44442055694, 10230.590776368861, 10346.0, 10398.0, 10727.088197503299, 7060.3292687194635, 10308.0, 10340.0, 10389.792204156025, 10417.374957502574], "value": 7699.435658112311, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 119.0, 117.0, 117.0, 119.0, 117.0, 117.0, 118.0, 115.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 112.0, 105.0, 159.0, 148.0, 109.0, 111.0, 148.0, 184.0, 119.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 220.0, 222.0, 274.0, 275.0, 198.0, 194.0, 196.0, 198.0, 193.0], "value": 217.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 58.0, 85.0, 71.0, 76.0, 71.0, 78.0, 106.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 89.0, 60.0, 86.0, 62.0, 86.0, 91.0, 106.0, 123.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 90.0, 85.0, 103.0, 132.0, 111.0, 86.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 96.0, 94.0, 119.0, 92.0, 150.0, 153.0, 95.0, 94.0, 95.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 91.0, 92.0, 93.0, 93.0, 91.0, 92.0, 96.0, 92.0, 79.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 57.0, 55.0, 55.0, 55.0, 57.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 75.0, 87.0, 74.0, 70.0, 71.0, 72.0, 70.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 89.0, 87.0, 90.0, 86.0, 100.0, 106.0, 111.0, 89.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 148.0, 157.0, 164.0, 158.0, 145.0, 150.0, 187.0, 148.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 244.0, 177.0, 173.0, 172.0, 172.0, 238.0, 236.0, 187.0, 173.0], "value": 196.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 68.0, 69.0, 74.0, 82.0, 91.0, 92.0, 73.0, 68.0], "value": 76.2, "unit": "score"}]}]} 17:58:44 INFO - Return code: 0 17:58:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:58:44 ERROR - # TBPL SUCCESS # 17:58:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:58:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:58:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:58:44 INFO - 2016-10-17 17:58:44.250 screenresolution[1951:19726] starting screenresolution argv=screenresolution get 17:58:44 INFO - 2016-10-17 17:58:44.252 screenresolution[1951:19726] Display 0: 1600x1200x32@60 17:58:44 INFO - 2016-10-17 17:58:44.258 screenresolution[1952:19729] starting screenresolution argv=screenresolution list 17:58:44 INFO - Available Modes on Display 0 17:58:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:58:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:58:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:58:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:58:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:58:44 INFO - Intel Iris: 17:58:44 INFO - Chipset Model: Intel Iris 17:58:44 INFO - Type: GPU 17:58:44 INFO - Bus: Built-In 17:58:44 INFO - VRAM (Dynamic, Max): 1536 MB 17:58:44 INFO - Vendor: Intel (0x8086) 17:58:44 INFO - Device ID: 0x0a2e 17:58:44 INFO - Revision ID: 0x0009 17:58:44 INFO - Displays: 17:58:44 INFO - Display: 17:58:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:58:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:58:44 INFO - Display Serial Number: 1600x1200 60 17:58:44 INFO - Main Display: Yes 17:58:44 INFO - Mirror: Off 17:58:44 INFO - Online: Yes 17:58:44 INFO - Rotation: Supported 17:58:44 INFO - Return code: 0 17:58:44 INFO - Running post-action listener: _resource_record_post_action 17:58:44 INFO - [mozharness: 2016-10-18 00:58:44.497282Z] Finished run-tests step (success) 17:58:44 INFO - Running post-run listener: _resource_record_post_run 17:58:44 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1619456; Write bytes: 710395392; Read time: 16; Write time: 2044 17:58:44 INFO - TinderboxPrint: CPU usage
54.5% 17:58:44 INFO - TinderboxPrint: I/O read bytes / time
1,619,456 / 16 17:58:44 INFO - TinderboxPrint: I/O write bytes / time
710,395,392 / 2,044 17:58:44 INFO - TinderboxPrint: CPU idle
2,206.3 (45.5%) 17:58:44 INFO - TinderboxPrint: CPU system
980.5 (20.2%) 17:58:44 INFO - TinderboxPrint: CPU user
1,658.9 (34.2%) 17:58:44 INFO - TinderboxPrint: Swap in / out
408,612,864 / 0 17:58:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399276544; Write bytes: 394982912; Read time: 29576; Write time: 842 17:58:44 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1619456; Write bytes: 298311680; Read time: 16; Write time: 1163 17:58:44 INFO - Running post-run listener: _upload_blobber_files 17:58:44 INFO - Blob upload gear active. 17:58:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:58:44 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 17:58:44 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:58:44 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:58:45 INFO - (blobuploader) - INFO - Open directory for files ... 17:58:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:58:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:58:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:46 INFO - (blobuploader) - INFO - Done attempting. 17:58:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:58:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:58:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:46 INFO - (blobuploader) - INFO - Done attempting. 17:58:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:58:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:58:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:50 INFO - (blobuploader) - INFO - Done attempting. 17:58:50 INFO - (blobuploader) - INFO - Iteration through files over. 17:58:50 INFO - Return code: 0 17:58:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:58:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:58:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7db0175d0db36e45de80ca04b52ef18f71a627ae9f01ca1d6c934799805bb77d608bac73400283eef2c65603108c1c13fdb8070212b5d206da74eed81ef24e1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0d5421a255aa4faf5987af931e0de8909de4cdad26c61abed73dae9977ba92437663ed07886958c77c2b51184d9333bd85c684539e76d5c8ccfb9e1d98592f40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/530d1024b42d87ce459884d40d6c30fc9b30e8e8be7c35199522505ff6d974de37a4b9f561b51d7e259c6f527e1e84b75693ad9d6c91176d779a2dd9bf5be128"} 17:58:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:58:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:58:50 INFO - Contents: 17:58:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7db0175d0db36e45de80ca04b52ef18f71a627ae9f01ca1d6c934799805bb77d608bac73400283eef2c65603108c1c13fdb8070212b5d206da74eed81ef24e1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0d5421a255aa4faf5987af931e0de8909de4cdad26c61abed73dae9977ba92437663ed07886958c77c2b51184d9333bd85c684539e76d5c8ccfb9e1d98592f40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/530d1024b42d87ce459884d40d6c30fc9b30e8e8be7c35199522505ff6d974de37a4b9f561b51d7e259c6f527e1e84b75693ad9d6c91176d779a2dd9bf5be128"} 17:58:50 INFO - Running post-run listener: copy_logs_to_upload_dir 17:58:50 INFO - Copying logs to upload dir... 17:58:50 INFO - mkdir: /builds/slave/test/build/upload/logs 17:58:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.402657 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-17 17:58:50.288070) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 17:58:50.291919) ========= 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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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/Larch/sha512/7db0175d0db36e45de80ca04b52ef18f71a627ae9f01ca1d6c934799805bb77d608bac73400283eef2c65603108c1c13fdb8070212b5d206da74eed81ef24e1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0d5421a255aa4faf5987af931e0de8909de4cdad26c61abed73dae9977ba92437663ed07886958c77c2b51184d9333bd85c684539e76d5c8ccfb9e1d98592f40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/530d1024b42d87ce459884d40d6c30fc9b30e8e8be7c35199522505ff6d974de37a4b9f561b51d7e259c6f527e1e84b75693ad9d6c91176d779a2dd9bf5be128"} build_url:https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010807 build_url: 'https://queue.taskcluster.net/v1/task/YHxElLvoQW-MaqNf6ha3AQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7db0175d0db36e45de80ca04b52ef18f71a627ae9f01ca1d6c934799805bb77d608bac73400283eef2c65603108c1c13fdb8070212b5d206da74eed81ef24e1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0d5421a255aa4faf5987af931e0de8909de4cdad26c61abed73dae9977ba92437663ed07886958c77c2b51184d9333bd85c684539e76d5c8ccfb9e1d98592f40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/530d1024b42d87ce459884d40d6c30fc9b30e8e8be7c35199522505ff6d974de37a4b9f561b51d7e259c6f527e1e84b75693ad9d6c91176d779a2dd9bf5be128"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 17:58:50.323836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 17:58:50.324163) ========= 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.RQP3CrJLTx/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.zDRPtUMaqX/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.004564 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 17:58:50.372174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 17:58:50.372474) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 17:58:50.372790) ========= ========= Total master_lag: 3.82 =========