builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0283 starttime: 1477435447.27 results: success (0) buildid: 20161025130125 builduid: 776cd8f7d3994ae9ba81ac02241c2ff5 revision: 0c5eca45fc7f3eee4295e70f77110869a0196a87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.271586) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.271940) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.272205) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.295986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.296257) ========= 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.MyPhAdDVuH/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.lwybFe5tpU/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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-25 15:44:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2016-10-25 15:44:07 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.474202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.793195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.793470) ========= 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.MyPhAdDVuH/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.lwybFe5tpU/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.019740 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:07.844205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 15:44:07.844505) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 0c5eca45fc7f3eee4295e70f77110869a0196a87 --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 0c5eca45fc7f3eee4295e70f77110869a0196a87 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MyPhAdDVuH/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.lwybFe5tpU/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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-25 15:44:07,927 truncating revision to first 12 chars 2016-10-25 15:44:07,927 Setting DEBUG logging. 2016-10-25 15:44:07,927 attempt 1/10 2016-10-25 15:44:07,927 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/0c5eca45fc7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 15:44:08,917 unpacking tar archive at: larch-0c5eca45fc7f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.265795 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 15:44:09.131997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:09.132342) ========= script_repo_revision: 0c5eca45fc7f3eee4295e70f77110869a0196a87 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:09.132798) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:09.133105) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 15:44:09.151108) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-25 15:44:09.151455) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.MyPhAdDVuH/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.lwybFe5tpU/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:09 INFO - MultiFileLogger online at 20161025 15:44:09 in /builds/slave/test 15:44:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:44:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:44:09 INFO - {'append_to_log': False, 15:44:09 INFO - 'base_work_dir': '/builds/slave/test', 15:44:09 INFO - 'blob_upload_branch': 'Larch', 15:44:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:44:09 INFO - 'branch': 'Larch', 15:44:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:09 INFO - 'config_files': ('talos/mac_config.py',), 15:44:09 INFO - 'default_actions': ('clobber', 15:44:09 INFO - 'read-buildbot-config', 15:44:09 INFO - 'download-and-extract', 15:44:09 INFO - 'populate-webroot', 15:44:09 INFO - 'create-virtualenv', 15:44:09 INFO - 'install', 15:44:09 INFO - 'run-tests'), 15:44:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:09 INFO - 'download_minidump_stackwalk': True, 15:44:09 INFO - 'download_symbols': 'ondemand', 15:44:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:09 INFO - 'installer_path': 'installer.exe', 15:44:09 INFO - 'log_level': 'info', 15:44:09 INFO - 'log_name': 'talos', 15:44:09 INFO - 'log_to_console': True, 15:44:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:44:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:44:09 INFO - 'opt_config_files': (), 15:44:09 INFO - 'pip_index': False, 15:44:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:09 INFO - 'cmd': ('bash', 15:44:09 INFO - '-c', 15:44:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:09 INFO - 'enabled': True, 15:44:09 INFO - 'halt_on_failure': False, 15:44:09 INFO - 'name': 'check_screen_resolution'},), 15:44:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:09 INFO - 'cmd': ('bash', 15:44:09 INFO - '-c', 15:44:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:09 INFO - 'enabled': True, 15:44:09 INFO - 'halt_on_failure': False, 15:44:09 INFO - 'name': 'check_screen_resolution'},), 15:44:09 INFO - 'run_cmd_checks_enabled': True, 15:44:09 INFO - 'sps_profile': False, 15:44:09 INFO - 'sps_profile_interval': 0, 15:44:09 INFO - 'suite': 'dromaeojs', 15:44:09 INFO - 'system_bits': '32', 15:44:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:09 INFO - 'title': 't-yosemite-r7-0283', 15:44:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:09 INFO - 'use_talos_json': True, 15:44:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:44:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:09 INFO - 'work_dir': 'build'} 15:44:09 INFO - [mozharness: 2016-10-25 22:44:09.354965Z] Running clobber step. 15:44:09 INFO - Running pre-action listener: _resource_record_pre_action 15:44:09 INFO - Running main action method: clobber 15:44:09 INFO - rmtree: /builds/slave/test/build 15:44:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:44:11 INFO - Running post-action listener: _resource_record_post_action 15:44:11 INFO - [mozharness: 2016-10-25 22:44:11.008600Z] Finished clobber step (success) 15:44:11 INFO - [mozharness: 2016-10-25 22:44:11.008728Z] Running read-buildbot-config step. 15:44:11 INFO - Running pre-action listener: _resource_record_pre_action 15:44:11 INFO - Running main action method: read_buildbot_config 15:44:11 INFO - Using buildbot properties: 15:44:11 INFO - { 15:44:11 INFO - "project": "", 15:44:11 INFO - "product": "firefox", 15:44:11 INFO - "script_repo_revision": "production", 15:44:11 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 15:44:11 INFO - "repository": "", 15:44:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 15:44:11 INFO - "buildid": "20161025130125", 15:44:11 INFO - "pgo_build": "False", 15:44:11 INFO - "basedir": "/builds/slave/test", 15:44:11 INFO - "buildnumber": 1, 15:44:11 INFO - "slavename": "t-yosemite-r7-0283", 15:44:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:44:11 INFO - "platform": "yosemite_r7", 15:44:11 INFO - "scheduler": "tests-larch-macosx64-talos", 15:44:11 INFO - "branch": "larch", 15:44:11 INFO - "repo_path": "projects/larch", 15:44:11 INFO - "revision": "0c5eca45fc7f3eee4295e70f77110869a0196a87", 15:44:11 INFO - "stage_platform": "macosx64", 15:44:11 INFO - "builduid": "776cd8f7d3994ae9ba81ac02241c2ff5", 15:44:11 INFO - "slavebuilddir": "test" 15:44:11 INFO - } 15:44:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:44:11 INFO - Running post-action listener: _resource_record_post_action 15:44:11 INFO - [mozharness: 2016-10-25 22:44:11.012023Z] Finished read-buildbot-config step (success) 15:44:11 INFO - [mozharness: 2016-10-25 22:44:11.012156Z] Running download-and-extract step. 15:44:11 INFO - Running pre-action listener: _resource_record_pre_action 15:44:11 INFO - Running main action method: download_and_extract 15:44:11 INFO - mkdir: /builds/slave/test/build/tests 15:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:11 INFO - trying https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:44:11 INFO - Downloading https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/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 15:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/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 15:44:12 INFO - Downloaded 1199 bytes. 15:44:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:44:12 INFO - Using the following test package requirements: 15:44:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:44:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:44:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:44:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'jsshell-mac.zip'], 15:44:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:44:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:44:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:44:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:44:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:44:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:44:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:44:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:44:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:44:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:44:12 INFO - Fetch https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:44:13 INFO - Http code: 200 15:44:13 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 15:44:13 INFO - x-amz-cf-id: YiNdl9NWqZUe0s2leLRuT4X32e1e1M7-VI3HE_xVA_1j_4nK5aQa1A== 15:44:13 INFO - x-amz-version-id: F380.NCiyPC8goD18E0uUe1bWbb_egcD 15:44:13 INFO - Expected file size: 16409325 15:44:13 INFO - Obtained file size: 16409325 15:44:19 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 15:44:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:44:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:44:19 INFO - Fetch https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:44:20 INFO - Http code: 200 15:44:20 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 15:44:20 INFO - x-amz-cf-id: JI48LEAJVSNdU3Uey0VN32riCwtFzViniwsKay1Tn23VnxYtBoB-pA== 15:44:20 INFO - x-amz-version-id: F380.NCiyPC8goD18E0uUe1bWbb_egcD 15:44:20 INFO - Expected file size: 16409325 15:44:20 INFO - Obtained file size: 16409325 15:44:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:44:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:44:26 INFO - Fetch https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:44:27 INFO - Http code: 200 15:44:27 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 15:44:27 INFO - x-amz-cf-id: aIzE79GeaN8zHm6mow5OZzDtvJAHhEbY5Y5r1G4RBGV3l51Qx4hkRA== 15:44:27 INFO - x-amz-version-id: QO6tj62507omW.Ei.fFz5P88Joup9x_l 15:44:28 INFO - Expected file size: 14409028 15:44:28 INFO - Obtained file size: 14409028 15:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:28 INFO - trying https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:44:28 INFO - Downloading https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:44:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/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 15:44:31 INFO - Downloaded 111300740 bytes. 15:44:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:44:31 INFO - mkdir: /builds/slave/test/properties 15:44:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:44:31 INFO - Writing to file /builds/slave/test/properties/build_url 15:44:31 INFO - Contents: 15:44:31 INFO - build_url:https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:44:31 INFO - Running post-action listener: _resource_record_post_action 15:44:31 INFO - Running post-action listener: set_extra_try_arguments 15:44:31 INFO - [mozharness: 2016-10-25 22:44:31.989943Z] Finished download-and-extract step (success) 15:44:31 INFO - [mozharness: 2016-10-25 22:44:31.990110Z] Running populate-webroot step. 15:44:31 INFO - Running pre-action listener: _resource_record_pre_action 15:44:31 INFO - Running main action method: populate_webroot 15:44:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:31 INFO - '--tppagecycles', 15:44:31 INFO - '7'], 15:44:31 INFO - 'tests': ['tsvgm']}}, 15:44:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:44:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:44:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['damp', 'tps']}, 15:44:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'tests': ['damp', 'tps']}, 15:44:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['dromaeo_dom']}, 15:44:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['basic_compositor_video']}, 15:44:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:44:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['tsvgx', 15:44:32 INFO - 'tsvgr_opacity', 15:44:32 INFO - 'tart', 15:44:32 INFO - 'tscrollx', 15:44:32 INFO - 'cart']}, 15:44:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:32 INFO - 'tsvgr_opacity', 15:44:32 INFO - 'tart', 15:44:32 INFO - 'tscrollx', 15:44:32 INFO - 'cart']}, 15:44:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['tp5o']}, 15:44:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'tests': ['tp5o']}, 15:44:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s', 15:44:32 INFO - '--xperf_path', 15:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:32 INFO - 'tests': ['tp5n']}, 15:44:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:32 INFO - 'talos_options': ['--xperf_path', 15:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:32 INFO - 'tests': ['tp5n']}}, 15:44:32 INFO - 'talos.zip': {'path': '', 15:44:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:32 INFO - Running post-action listener: _resource_record_post_action 15:44:32 INFO - [mozharness: 2016-10-25 22:44:32.008678Z] Finished populate-webroot step (success) 15:44:32 INFO - [mozharness: 2016-10-25 22:44:32.008784Z] Running create-virtualenv step. 15:44:32 INFO - Running pre-action listener: _resource_record_pre_action 15:44:32 INFO - Running main action method: create_virtualenv 15:44:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:32 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 15:44:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:32 INFO - Using real prefix '/tools/python27' 15:44:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:32 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:35 INFO - Installing pip.................done. 15:44:35 INFO - Return code: 0 15:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:44:35 INFO - Reading from file tmpfile_stdout 15:44:35 INFO - Output received: 15:44:35 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) 15:44:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:35 INFO - 'HOME': '/Users/cltbld', 15:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:35 INFO - 'LOGNAME': 'cltbld', 15:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:35 INFO - 'MOZ_NO_REMOTE': '1', 15:44:35 INFO - 'NO_EM_RESTART': '1', 15:44:35 INFO - 'PAGER': '/bin/cat', 15:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:35 INFO - 'PWD': '/builds/slave/test', 15:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:35 INFO - 'SHELL': '/bin/bash', 15:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:35 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:35 INFO - 'USER': 'cltbld', 15:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:35 INFO - 'XPC_FLAGS': '0x0', 15:44:35 INFO - 'XPC_SERVICE_NAME': '0', 15:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:36 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 15:44:36 INFO - Cleaning up... 15:44:36 INFO - Return code: 0 15:44:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44:36 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')]} 15:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:36 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')]} 15:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:36 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:36 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 15:44:36 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 15:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:36 INFO - 'HOME': '/Users/cltbld', 15:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:36 INFO - 'LOGNAME': 'cltbld', 15:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:36 INFO - 'MOZ_NO_REMOTE': '1', 15:44:36 INFO - 'NO_EM_RESTART': '1', 15:44:36 INFO - 'PAGER': '/bin/cat', 15:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:36 INFO - 'PWD': '/builds/slave/test', 15:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:36 INFO - 'SHELL': '/bin/bash', 15:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:36 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:36 INFO - 'USER': 'cltbld', 15:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:36 INFO - 'XPC_FLAGS': '0x0', 15:44:36 INFO - 'XPC_SERVICE_NAME': '0', 15:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:36 INFO - Downloading/unpacking psutil>=3.1.1 15:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:40 INFO - Installing collected packages: psutil 15:44:40 INFO - Running setup.py install for psutil 15:44:40 INFO - building 'psutil._psutil_osx' extension 15:44:40 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 15:44:40 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 15:44:40 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 15:44:40 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 15:44:40 INFO - building 'psutil._psutil_posix' extension 15:44:40 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 15:44:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:44:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:44:40 INFO - ^ 15:44:41 INFO - 1 warning generated. 15:44:41 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 15:44:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:41 INFO - Successfully installed psutil 15:44:41 INFO - Cleaning up... 15:44:41 INFO - Return code: 0 15:44:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44:41 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')]} 15:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:41 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')]} 15:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:41 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:41 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 15:44:41 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 15:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:41 INFO - 'HOME': '/Users/cltbld', 15:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:41 INFO - 'LOGNAME': 'cltbld', 15:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:41 INFO - 'MOZ_NO_REMOTE': '1', 15:44:41 INFO - 'NO_EM_RESTART': '1', 15:44:41 INFO - 'PAGER': '/bin/cat', 15:44:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:41 INFO - 'PWD': '/builds/slave/test', 15:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:41 INFO - 'SHELL': '/bin/bash', 15:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:41 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:41 INFO - 'USER': 'cltbld', 15:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:41 INFO - 'XPC_FLAGS': '0x0', 15:44:41 INFO - 'XPC_SERVICE_NAME': '0', 15:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:44 INFO - Installing collected packages: mozsystemmonitor 15:44:44 INFO - Running setup.py install for mozsystemmonitor 15:44:44 INFO - Successfully installed mozsystemmonitor 15:44:44 INFO - Cleaning up... 15:44:44 INFO - Return code: 0 15:44:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44:44 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')]} 15:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:44 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')]} 15:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:44 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:44 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 15:44:44 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 15:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:44 INFO - 'HOME': '/Users/cltbld', 15:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:44 INFO - 'LOGNAME': 'cltbld', 15:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:44 INFO - 'MOZ_NO_REMOTE': '1', 15:44:44 INFO - 'NO_EM_RESTART': '1', 15:44:44 INFO - 'PAGER': '/bin/cat', 15:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:44 INFO - 'PWD': '/builds/slave/test', 15:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:44 INFO - 'SHELL': '/bin/bash', 15:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:44 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:44 INFO - 'USER': 'cltbld', 15:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:44 INFO - 'XPC_FLAGS': '0x0', 15:44:44 INFO - 'XPC_SERVICE_NAME': '0', 15:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:44 INFO - Downloading/unpacking jsonschema==2.5.1 15:44:44 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 15:44:44 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 15:44:44 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 15:44:44 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 15:44:44 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 15:44:44 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 15:44:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:44:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:47 INFO - Downloading functools32-3.2.3-2.tar.gz 15:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:44:47 INFO - warning: no files found matching '*.txt' 15:44:47 INFO - no previously-included directories found matching 'build' 15:44:47 INFO - no previously-included directories found matching 'dist' 15:44:47 INFO - no previously-included directories found matching '.git*' 15:44:47 INFO - Installing collected packages: jsonschema, functools32 15:44:47 INFO - Running setup.py install for functools32 15:44:47 INFO - warning: no files found matching '*.txt' 15:44:47 INFO - no previously-included directories found matching 'build' 15:44:47 INFO - no previously-included directories found matching 'dist' 15:44:47 INFO - no previously-included directories found matching '.git*' 15:44:47 INFO - Successfully installed jsonschema functools32 15:44:47 INFO - Cleaning up... 15:44:47 INFO - Return code: 0 15:44:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:47 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:47 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 15:44:47 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 15:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:47 INFO - 'HOME': '/Users/cltbld', 15:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:47 INFO - 'LOGNAME': 'cltbld', 15:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:47 INFO - 'MOZ_NO_REMOTE': '1', 15:44:47 INFO - 'NO_EM_RESTART': '1', 15:44:47 INFO - 'PAGER': '/bin/cat', 15:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:47 INFO - 'PWD': '/builds/slave/test', 15:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:47 INFO - 'SHELL': '/bin/bash', 15:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:47 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:47 INFO - 'USER': 'cltbld', 15:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:47 INFO - 'XPC_FLAGS': '0x0', 15:44:47 INFO - 'XPC_SERVICE_NAME': '0', 15:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:44:48 INFO - Cleaning up... 15:44:48 INFO - Return code: 0 15:44:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:44:48 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')]} 15:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:48 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')]} 15:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:48 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:48 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 15:44:48 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 15:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:48 INFO - 'HOME': '/Users/cltbld', 15:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:48 INFO - 'LOGNAME': 'cltbld', 15:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:48 INFO - 'MOZ_NO_REMOTE': '1', 15:44:48 INFO - 'NO_EM_RESTART': '1', 15:44:48 INFO - 'PAGER': '/bin/cat', 15:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:48 INFO - 'PWD': '/builds/slave/test', 15:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:48 INFO - 'SHELL': '/bin/bash', 15:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:48 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:48 INFO - 'USER': 'cltbld', 15:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:48 INFO - 'XPC_FLAGS': '0x0', 15:44:48 INFO - 'XPC_SERVICE_NAME': '0', 15:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:48 INFO - Downloading/unpacking blobuploader==1.2.4 15:44:48 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 15:44:48 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 15:44:48 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 15:44:48 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 15:44:48 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 15:44:48 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 15:44:50 INFO - Downloading blobuploader-1.2.4.tar.gz 15:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:44:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:44:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 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 15:44:51 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 15:44:52 INFO - Downloading docopt-0.6.1.tar.gz 15:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:44:52 INFO - Installing collected packages: blobuploader, requests, docopt 15:44:52 INFO - Running setup.py install for blobuploader 15:44:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:44:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:44:52 INFO - Running setup.py install for requests 15:44:52 INFO - Running setup.py install for docopt 15:44:53 INFO - Successfully installed blobuploader requests docopt 15:44:53 INFO - Cleaning up... 15:44:53 INFO - Return code: 0 15:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:53 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:53 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 15:44:53 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 15:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:53 INFO - 'HOME': '/Users/cltbld', 15:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:53 INFO - 'LOGNAME': 'cltbld', 15:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:53 INFO - 'MOZ_NO_REMOTE': '1', 15:44:53 INFO - 'NO_EM_RESTART': '1', 15:44:53 INFO - 'PAGER': '/bin/cat', 15:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:53 INFO - 'PWD': '/builds/slave/test', 15:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:53 INFO - 'SHELL': '/bin/bash', 15:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:53 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:53 INFO - 'USER': 'cltbld', 15:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:53 INFO - 'XPC_FLAGS': '0x0', 15:44:53 INFO - 'XPC_SERVICE_NAME': '0', 15:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:53 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-gPqOmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:53 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-mbimJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:53 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Zrz6p5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:53 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Ih02DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:53 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-e2e9Dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:53 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-dqBDKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Cjlqu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-URxnLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-7g5W0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-mtG2hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-4N5HVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-IixRRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-DmmY62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-jvLFUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-u8xtUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:54 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-P7xzye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:55 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-m5ejCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:44:55 INFO - Running setup.py install for manifestparser 15:44:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:44:55 INFO - Running setup.py install for mozcrash 15:44:55 INFO - Running setup.py install for mozdebug 15:44:55 INFO - Running setup.py install for mozdevice 15:44:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:44:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:44:55 INFO - Running setup.py install for mozfile 15:44:55 INFO - Running setup.py install for mozhttpd 15:44:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Running setup.py install for mozinfo 15:44:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Running setup.py install for mozInstall 15:44:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Running setup.py install for mozleak 15:44:56 INFO - Running setup.py install for mozlog 15:44:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Running setup.py install for moznetwork 15:44:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:44:56 INFO - Running setup.py install for mozprocess 15:44:56 INFO - Running setup.py install for mozprofile 15:44:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Running setup.py install for mozrunner 15:44:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Running setup.py install for mozscreenshot 15:44:57 INFO - Running setup.py install for moztest 15:44:57 INFO - Running setup.py install for mozversion 15:44:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:44:57 INFO - Cleaning up... 15:44:57 INFO - Return code: 0 15:44:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:57 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:44:57 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 15:44:57 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 15:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:57 INFO - 'HOME': '/Users/cltbld', 15:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:57 INFO - 'LOGNAME': 'cltbld', 15:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:57 INFO - 'MOZ_NO_REMOTE': '1', 15:44:57 INFO - 'NO_EM_RESTART': '1', 15:44:57 INFO - 'PAGER': '/bin/cat', 15:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:57 INFO - 'PWD': '/builds/slave/test', 15:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:57 INFO - 'SHELL': '/bin/bash', 15:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:44:57 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:57 INFO - 'USER': 'cltbld', 15:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:57 INFO - 'XPC_FLAGS': '0x0', 15:44:57 INFO - 'XPC_SERVICE_NAME': '0', 15:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-f2NkYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-AwO6oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-xq3YM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-LISw5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Ou0i5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-RXzHru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-PqymDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-xfcJJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:58 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-zs0i8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:58 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)) 15:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-piHsVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-UEwIdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-5gbt3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-f5jXa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-7eq6dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-vNrjcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-b41YJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:59 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-cGDLti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:59 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)) 15:44:59 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)) 15:44:59 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)) 15:44:59 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)) 15:44:59 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)) 15:44:59 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)) 15:44:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:45:02 INFO - Downloading blessings-1.6.tar.gz 15:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:02 INFO - Installing collected packages: blessings 15:45:02 INFO - Running setup.py install for blessings 15:45:02 INFO - Successfully installed blessings 15:45:02 INFO - Cleaning up... 15:45:02 INFO - Return code: 0 15:45:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:03 INFO - Reading from file tmpfile_stdout 15:45:03 INFO - Current package versions: 15:45:03 INFO - blessings == 1.6 15:45:03 INFO - blobuploader == 1.2.4 15:45:03 INFO - docopt == 0.6.1 15:45:03 INFO - functools32 == 3.2.3-2 15:45:03 INFO - jsonschema == 2.5.1 15:45:03 INFO - manifestparser == 1.1 15:45:03 INFO - mozInstall == 1.12 15:45:03 INFO - mozcrash == 0.17 15:45:03 INFO - mozdebug == 0.1 15:45:03 INFO - mozdevice == 0.48 15:45:03 INFO - mozfile == 1.2 15:45:03 INFO - mozhttpd == 0.7 15:45:03 INFO - mozinfo == 0.9 15:45:03 INFO - mozleak == 0.1 15:45:03 INFO - mozlog == 3.4 15:45:03 INFO - moznetwork == 0.27 15:45:03 INFO - mozprocess == 0.23 15:45:03 INFO - mozprofile == 0.28 15:45:03 INFO - mozrunner == 6.12 15:45:03 INFO - mozscreenshot == 0.1 15:45:03 INFO - mozsystemmonitor == 0.3 15:45:03 INFO - moztest == 0.7 15:45:03 INFO - mozversion == 1.4 15:45:03 INFO - psutil == 3.1.1 15:45:03 INFO - requests == 1.2.3 15:45:03 INFO - wsgiref == 0.1.2 15:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:03 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:45:03 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 15:45:03 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 15:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:03 INFO - 'HOME': '/Users/cltbld', 15:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:03 INFO - 'LOGNAME': 'cltbld', 15:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:03 INFO - 'MOZ_NO_REMOTE': '1', 15:45:03 INFO - 'NO_EM_RESTART': '1', 15:45:03 INFO - 'PAGER': '/bin/cat', 15:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:03 INFO - 'PWD': '/builds/slave/test', 15:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:03 INFO - 'SHELL': '/bin/bash', 15:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:45:03 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:03 INFO - 'USER': 'cltbld', 15:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:03 INFO - 'XPC_FLAGS': '0x0', 15:45:03 INFO - 'XPC_SERVICE_NAME': '0', 15:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 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)) 15:45:03 INFO - Cleaning up... 15:45:03 INFO - Return code: 0 15:45:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:03 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 0x108d1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecd84e9c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, '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.MyPhAdDVuH/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.lwybFe5tpU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 15:45:03 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 15:45:03 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 15:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:03 INFO - 'HOME': '/Users/cltbld', 15:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:03 INFO - 'LOGNAME': 'cltbld', 15:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:03 INFO - 'MOZ_NO_REMOTE': '1', 15:45:03 INFO - 'NO_EM_RESTART': '1', 15:45:03 INFO - 'PAGER': '/bin/cat', 15:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:03 INFO - 'PWD': '/builds/slave/test', 15:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:03 INFO - 'SHELL': '/bin/bash', 15:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:45:03 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:03 INFO - 'USER': 'cltbld', 15:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:03 INFO - 'XPC_FLAGS': '0x0', 15:45:03 INFO - 'XPC_SERVICE_NAME': '0', 15:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:03 INFO - Cleaning up... 15:45:03 INFO - Return code: 0 15:45:03 INFO - Running post-action listener: _resource_record_post_action 15:45:03 INFO - Running post-action listener: _start_resource_monitoring 15:45:03 INFO - Starting resource monitoring. 15:45:03 INFO - [mozharness: 2016-10-25 22:45:03.601313Z] Finished create-virtualenv step (success) 15:45:03 INFO - [mozharness: 2016-10-25 22:45:03.602308Z] Running install step. 15:45:03 INFO - Running pre-action listener: _resource_record_pre_action 15:45:03 INFO - Running main action method: install 15:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:03 INFO - Reading from file tmpfile_stdout 15:45:03 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:03 INFO - Reading from file tmpfile_stdout 15:45:03 INFO - Output received: 15:45:03 INFO - Usage: mozinstall [options] installer 15:45:03 INFO - Options: 15:45:03 INFO - -h, --help show this help message and exit 15:45:03 INFO - -d DEST, --destination=DEST 15:45:03 INFO - Directory to install application into. [default: 15:45:03 INFO - "/builds/slave/test"] 15:45:03 INFO - --app=APP Application being installed. [default: firefox] 15:45:03 INFO - mkdir: /builds/slave/test/build/application 15:45:03 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'] 15:45:03 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 15:45:37 INFO - Reading from file tmpfile_stdout 15:45:37 INFO - Output received: 15:45:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:45:37 INFO - Running post-action listener: _resource_record_post_action 15:45:37 INFO - [mozharness: 2016-10-25 22:45:37.934991Z] Finished install step (success) 15:45:37 INFO - [mozharness: 2016-10-25 22:45:37.935143Z] Running run-tests step. 15:45:37 INFO - Running pre-action listener: _resource_record_pre_action 15:45:37 INFO - Running main action method: run_tests 15:45:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:37 INFO - 2016-10-25 15:45:37.949 screenresolution[1942:15071] starting screenresolution argv=screenresolution get 15:45:37 INFO - 2016-10-25 15:45:37.952 screenresolution[1942:15071] Display 0: 1600x1200x32@60 15:45:37 INFO - 2016-10-25 15:45:37.958 screenresolution[1943:15074] starting screenresolution argv=screenresolution list 15:45:37 INFO - Available Modes on Display 0 15:45:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:38 INFO - Intel Iris: 15:45:38 INFO - Chipset Model: Intel Iris 15:45:38 INFO - Type: GPU 15:45:38 INFO - Bus: Built-In 15:45:38 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:38 INFO - Vendor: Intel (0x8086) 15:45:38 INFO - Device ID: 0x0a2e 15:45:38 INFO - Revision ID: 0x0009 15:45:38 INFO - Displays: 15:45:38 INFO - Display: 15:45:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:38 INFO - Display Serial Number: 1600x1200 60 15:45:38 INFO - Main Display: Yes 15:45:38 INFO - Mirror: Off 15:45:38 INFO - Online: Yes 15:45:38 INFO - Rotation: Supported 15:45:38 INFO - Return code: 0 15:45:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:45:38 INFO - Python 2.7.3 15:45:38 INFO - Return code: 0 15:45:38 INFO - grabbing minidump binary from tooltool 15:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:38 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 0x108b32d30>, '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 0x108958db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b2ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:45:38 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 15:45:38 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 15:45:38 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 15:45:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:38 INFO - Return code: 0 15:45:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:45:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:45:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:45:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:45:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:45:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0283 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPhAdDVuH/Render', 15:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:38 INFO - 'HOME': '/Users/cltbld', 15:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:38 INFO - 'LOGNAME': 'cltbld', 15:45:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:45:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:38 INFO - 'MOZ_NO_REMOTE': '1', 15:45:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:45:38 INFO - 'NO_EM_RESTART': '1', 15:45:38 INFO - 'PAGER': '/bin/cat', 15:45:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:38 INFO - 'PWD': '/builds/slave/test', 15:45:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:38 INFO - 'SHELL': '/bin/bash', 15:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwybFe5tpU/Listeners', 15:45:38 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 15:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:38 INFO - 'USER': 'cltbld', 15:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:38 INFO - 'XPC_FLAGS': '0x0', 15:45:38 INFO - 'XPC_SERVICE_NAME': '0', 15:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:45:38 INFO - mozversion application_buildid: 20161025130125 15:45:38 INFO - mozversion application_changeset: 0c5eca45fc7f3eee4295e70f77110869a0196a87 15:45:38 INFO - mozversion application_display_name: Nightly 15:45:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:38 INFO - mozversion application_name: Firefox 15:45:38 INFO - mozversion application_remotingname: firefox 15:45:38 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:45:38 INFO - mozversion application_vendor: Mozilla 15:45:38 INFO - mozversion application_version: 52.0a1 15:45:38 INFO - mozversion platform_buildid: 20161025130125 15:45:38 INFO - mozversion platform_changeset: 0c5eca45fc7f3eee4295e70f77110869a0196a87 15:45:38 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:45:38 INFO - mozversion platform_version: 52.0a1 15:45:38 INFO - using testdate: 1477435538 15:45:38 INFO - actual date: 1477435538 15:45:38 INFO - starting webserver on 'localhost:51502' 15:45:38 INFO - SUITE-START | Running 2 tests 15:45:38 INFO - TEST-START | dromaeo_css 15:45:38 INFO - operating with platform_type : mac_ 15:45:38 INFO - Initialising browser for dromaeo_css test... 15:45:38 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp2Rg_AX/profile http://localhost:51502/getInfo.html) 15:45:40 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 15:45:40 INFO - PROCESS | 1953 | colorDepth:24 15:45:40 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 15:45:40 INFO - PROCESS | 1953 | __metrics 15:45:41 INFO - PROCESS | 1953 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:45:41 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:45:41 INFO - TEST-INFO | 1953: exit 0 15:45:41 INFO - Browser initialized. 15:45:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:45:41 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp2Rg_AX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:53 INFO - PROCESS | 1955 | RSS: Main: 241778688 15:45:53 INFO - PROCESS | 1955 | 15:46:44 INFO - PROCESS | 1955 | 1477435604990 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] 15:48:57 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51502/tests/dromaeo/cssquery-ext.html) 15:48:59 INFO - PROCESS | 1955 | RSS: Main: 359587840 15:48:59 INFO - PROCESS | 1955 | 15:52:02 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-ext.html (next: http://localhost:51502/tests/dromaeo/cssquery-jquery.html) 15:52:02 INFO - PROCESS | 1955 | RSS: Main: 373432320 15:52:02 INFO - PROCESS | 1955 | 15:55:04 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51502/tests/dromaeo/cssquery-mootools.html) 15:55:05 INFO - PROCESS | 1955 | RSS: Main: 364847104 15:55:05 INFO - PROCESS | 1955 | 15:58:07 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51502/tests/dromaeo/cssquery-prototype.html) 15:58:08 INFO - PROCESS | 1955 | RSS: Main: 333074432 15:58:08 INFO - PROCESS | 1955 | 16:01:12 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51502/tests/dromaeo/cssquery-yui.html) 16:01:12 INFO - PROCESS | 1955 | RSS: Main: 327360512 16:01:12 INFO - PROCESS | 1955 | 16:04:15 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-yui.html (next: http://localhost:51502/tests/dromaeo/cssquery-dojo.html) 16:04:16 INFO - PROCESS | 1955 | RSS: Main: 493240320 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | __start_tp_report 16:04:16 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 16:04:16 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 16:04:16 INFO - PROCESS | 1955 | |i|pagename|runs| 16:04:16 INFO - PROCESS | 1955 | |0;dojo.html;3232.466643003903;3248.967510324897;3254.625718042425;3271.950920736188;3298.5876765404323;5427.674339539625;5490.780368785248;5539.750711217995;5586.972065139668;5672.06410942194;5644.096944488882;5683.602147849642;5688.744006519706;5693.946619875326;5707.971460142704;5120.590352771769;5148.459411761767;5153.046686363016;5191.117510023305;5208.479152084799;5223.955208958205;5258.684478931257;5262.842114736564;5264.999650066494;5290.894182116355;5162.664426812264;5211.947880521184;5213.895722085556;5219.71291578963;5229.764660590264;5261.789528418859;5269.762860671279;5278.630495865291;5295.7881684732565;5333.986542556901;5083.669561478492;5095.0574143783515;5137.229415587654;5203.9219411708855;5207.895842083175;5519;5585.441455854423;5588;5629.324481062258;5657.377688454267;6171.197744293258;6178.07328900664;6191.318954914956;6193.81418557444;6201.441870231656;6099.158463004126;6190.84522886927;6190.969045154746;6235.095911092866;6260.342664020258;4806.1829488987305;5758.078707406795;5789.94210057894;5795.014847475939;5859.677717725566;6187.133801267826;6203.286622038433;6207.96896015517;6215.378462153749;6217.564770466114;5989.9101513477335;6059.36376680451;6101.145839582462;6113.786017489367;6119.84700382494;6092.664903430354;6098.613770999311;6139.1630231105555;6142;6200.224971878515;6105.32841387447;6131.901258721728;6135.5398345124295;6141.148492690813;6158.938410615926;6136.938630613726;6144.047672610753;6147.354527774519;6152.661603611755;6162.26052873649;4204.810783514749;4726.71639701619;4851.248056551241;4851.56335929768;4856.1016212000895;4390.780460977002;4396.802143903532;4441.422615060086;4447.110577884436;4485.416895803524;5682.204491371212;5732.856678582985;5734.197212390269;5739.339975902742;5739.913901291484;6079.726412311456;6111.37903775109;6111.480524155408;6227.439530442282;6233.252009758768;5730.770769169187;5751.338596061507;5753.827385178452;5785.8553536161935;5841.532677385799;5354.651947623392;5361.919571206435;5374.27447294618;5383.084875571162;5387;6135.018317212141;6208.1308616793685;6209.379062093754;6245.781397651061;6257.217847769029;6128.693565321805;6140.688304616564;6191;6204.13142160107;6255.24937007562;6172.6913654318005;6226.906596400967;6239.06414037898;6260.40526150015;6286;5978.103284507359;6159.72281247336;6167.352427994996;6176.382361763787;6194.163787888758;6112.047575716935;6146.078088286793;6158.261008678987;6203.534734894811;6254.312025677263;6020.830644280432;6105.004892149239;6113.877722445615;6150.600210986271;6158.3841615838055;5490.0941344677685;6107.683651265568;6116.731440059295;6136.3965394158;6137.836677219377;5521.641093328921;5853.560982926383;5901.704914754159;5911.527077833849;5943.078822782476;6118.223266370805;6118.862843290023;6119.571629985859;6120.050983828446;6120.416658368064;5802.158686990447;5958.6424814511265;5966;5967.701614919323;6018.548608854266 16:04:16 INFO - PROCESS | 1955 | |1;ext.html;480.16451374608187;498.6758606905511;519.3067255214289;523.0036779934225;542.4277387356337;27140.45719085617;31493.64608016637;31496.842515787448;32399.704011839498;32457.837710811473;35551.288974220566;42762.93092672685;43280.99999999992;45495.545044549544;45586.544134558644;9420.670276540322;9583.377080489781;9588.376755510904;9592.80814383712;9606.471644059575;9344.906550934506;9433.716988490356;9436.858447123299;9437.575309111073;9439.244860411185;9439.05609439054;9485.146336829685;9488.478133702643;9498;9540.950495445495;9390.671326503574;9438.103380178873;9459.19596834263;9469.479178645171;9485.668001619979;9488.667896623654;9547.522623868848;9548.761280967961;9565;9585.424874507551;40903.7954810227;41185;41213.79393103045;41289.587104129176;41311.58688413107;3384.069380920242;3424.400729872262;3468.254325320043;3469.9653003469894;3575.6603122703564;30007.849960750056;31446.842765786023;31465.8426707865;31482;31551.2138543374;3223.564818749462;3285;3330.2340461693702;3356.043527594624;3366.444536651474;489067;494106;494260.5286973578;495754;496005;59847.700761495915;60230.397696023356;60344.39655603476;60364.698176509715;60527;64530.032049519294;64679.353206467335;64735.352646473875;64754.352456475775;64767.676161619835;14877.33052012662;15053.623659408602;15087.24563771807;15095.4716584919;15117.924410377878;12049.819252711217;12098.153129280865;12111.636650900487;12112.97039751631;12120.636380908587;13709.520166794315;14582.781258281135;15229.705475034747;16090.793190510525;16753.581160471087;5494.450554944554;6463.870722585522;6675.232348279913;6679.9332006679315;6733.966330168416;807.5195258820992;866.8309679612415;920.0661328751323;1024.0067134879155;1049.7270709615555;28077;28523.857380712965;28729.56905646417;30208.09375718732;31095.533566996513;1024.6055268721595;1059.888711685262;1076.5048077884262;1076.531708706715;1076.8061748885275;12135.271883687006;14787.408503659948;15183.848161518243;15260.542183734506;15923.601909952344;497171;624271;624988;627570;631302;57173.428265717645;57177.71411143001;57201;57377.713111434176;57391.42608573944;1256.309030033478;1305.2103477396038;1380.4961189166083;1403.635054885737;1420.6022313752167;29413.97051103201;30490;30676.597209404026;30768.38463230678;31093;50351.74824126002;50859.2371114441;50945;50981.74509127431;51026;534649.2280617463;538241;539370.6062939321;541417;572494;25527.489450211262;25608;25647.487050258518;25699.743002569736;25954.22137335883;48052;48104.75947620379;48451.03097938092;48524;49242.75378623084;38976;39535.40696889492;39953.80023099982;40878.591214088665;41305.380419294335;38857.80571097126;38918;39310.41034384544;40356.59643403528;40449;38114.61885381111;38635.80682096684;38844.36756670324;39050.41424378694;39077.21845563129;32038.35923281568;34554.963351099505;35027.82486087553;35251.82374088216;35430.8228458856;23227.187048453223;25347.746522535275;25556.361090972874;25583.83092355771;26069.608955865297 16:04:16 INFO - PROCESS | 1955 | |2;jquery.html;352.87120201126595;372.6217888842824;374.76951674720056;382.5657878308121;405.92490389277987;14596.270186490672;14994.700105997874;15384.846151538468;19726.309579164696;20156.798432015676;18350.816491835078;19233.32683356083;20177.798222017776;20756.584868302623;20984.370468885958;5688.9146662799985;5982.970085149568;5986.820395388124;5986.97006514969;5995.040793473046;5177;6517.706703198343;6609;6651.467882569383;6657.700403481855;5730.226419433386;6037;6567.408933196011;6574.079628851964;6591.835204119888;6062.393760623946;6101.60339577926;6107.358727333655;6107.74013614963;6109.114178444101;5815.796447124372;5898.738979801713;5914.260717410324;5991.101334799815;5998.460138587505;13355.933220333933;13434.70857108721;13593.932030339785;25242.49515009708;25633.487330253476;1824.927002919895;1978.7427634407436;2154.192177933275;2233.2630232023394;2336.6144586143264;9816.901830981671;10025.749356266078;10047.24645651579;10501.21240906935;10618.309809862334;1541.5529496446018;1774.4233124234675;1842.797292297846;1848.3530764232546;1901.2965202874886;75611.62194188994;114285.4285728566;118586;118959;130057.99999999905;10306.433146176882;10563.947180264204;11319.717007074725;11595.362255076052;11674.007709344802;14683.265836708122;14711.337989790485;14715;14721.779173312409;14737.926310368593;7279.6360181990685;7689.192634773268;8385.874211886827;8390.202930721573;8526.147385261549;6919.827004324831;8048.631732605471;8162.795930101676;8320.086380132603;8343.165683431609;3876.7480113792512;4423.225935461282;4466.843660471868;4511.300748384006;4617.515160908123;1875.9531011724816;1921.8104532120112;1923.7595300587427;1931.777845547752;1935.3129638978241;772.9265719756727;937.4890684576922;937.7514958535993;970.6505657963128;1052.1109662335314;14927.776083358758;19791.723787257255;20375.898120509304;21155.365339039854;21243;924.9907500924904;928.3872644054892;944.471096186138;958.4393130019017;992.0327680511443;10321.329113607742;11125.554977800959;11133.332000080021;11142.27575207609;11195.104391648649;551672;562954;564066;569293;586648;88553.55723221472;122509.38745306217;123341;125236;125961;987.2299606307092;1022.2486472442748;1064.2975636080305;1065.387402243713;1066.6426747039673;18028;18241.635167296845;18541;18695;18697.6260474787;50485;50834.49165508297;50861.72845678888;50907;50977.745111274206;66792.6660366695;67908.64182716417;68166.97749533661;68998.65500672464;69555.30444695488;7626.504277222185;7941.126476087741;8025.799355016171;8196.918030819616;8405.663773448994;9197.954010230174;10291.897081029094;11994.160408771302;12908.16096953695;13069.803952940523;11224.494897729459;11351.716207094558;11369.829452558053;11378.43107844621;11437.570303712037;9291.907080929375;10839.295445795999;11106.888931110585;11108.61119860801;11178.217524773188;11320.830187547028;11491.367974761133;11548.42257887119;11568.363739994045;11758;7916.208379162038;8117.431779775359;8137.715179968673;8217.383696222927;8374.40886231614;8388.664453421794;10553.947230264106;11145.72135696614;11474.598389056342;11514.94242528782 16:04:16 INFO - PROCESS | 1955 | |3;mootools.html;1366.0642459914966;1524.8703860171888;1557.9844201557985;1777.466759972008;1949.298252629054;7745.2641999010075;8219.301359384452;8445.662173513052;8822.250108740765;8823.16179962904;8626.913730862689;8717.738467845958;8725.563721813916;8725.650973961034;8735.606897689619;2293.6330187170056;2322.605157123293;2322.8954697038675;2324.2213858357472;2324.2678556254727;2276.8633881967135;2313.90744370225;2316.3861576682193;2325.2210509479346;2330.0563271874917;2270.2508172303183;2297.058206135485;2303.7005189325364;2307.526956973816;2309.7921187093157;2253.5718213539376;2261.389424855288;2270.591293567157;2271.3867255840914;2272.420532764151;2285.451491641994;2286.8284878634004;2291.4271432141963;2291.9770802291932;2293.633018717014;8611.13888611134;8690.130986901322;8742.256908162813;8743.650253989832;8744.693935712283;5105.617078719074;5293.444188360244;5313.734313284359;5316.4683531646915;5323.787048518055;3406.5741782277214;3541.11472131967;3623.2572322673786;3631.5279013728045;3634.6365363463706;1035.254616675992;1070.314998401024;1075.2365820267541;1080.8811030786608;1081.3565928272665;11445.427728613537;11480.483378247998;11575.479103440366;11579.652610421701;11594.594189203423;4269.252880745919;4315.244832154361;4493.550644935481;4500.369948207253;4505.977470112629;8990.820183596292;8995.32535059873;8998.460092394478;9019.233365163904;9020.8646870297;5159.871003224875;5168.328117344721;5170.45710200431;5171.793128274902;5183.300254465599;4091.447654566655;4331.45856767904;4489.1021795641;4495.550444955544;4836.105320515716;3471.1842716961496;3736.7010639148803;3756.8309426075893;3773.981130094387;3778.0554861284677;990.5245482168599;1106.1482658353023;1132.8867113288604;1143.2968724234536;1145.5188820695328;886.7916039730658;899.5906862377733;916.5142474488532;919.8390281700675;923.072312326108;5639.436056394327;5680.97159514208;6000.969995149996;6025.728842202111;6027.276726792813;940.6378544260372;943.118184497497;949.6657196638733;962.3648392061206;980.4117529482396;3806.676432503268;3831.252905683365;3875.418687196943;3971.5829837867186;4059;10273.897261027443;10748.140148788078;10752.032317091343;10762.892371076345;10775.946120269506;10730.89269107299;10739.409332486788;10739.892601074045;10741.677749667366;10752.731181720519;7658.846823063507;8054.556999364974;8132.878006829902;8265.917340826634;8275.131111233355;5213.869653258699;5660.830175094754;5980.790672326448;6026.849328766816;6137.110119032626;9412.764680883032;9431.292653051185;9470.668526601536;9702.951485242811;9829.557669904729;7730.806729831799;9192.448453092837;9654.469004204837;9961.750956226153;10056.69829905104;7458.925410745823;7730.458867879194;7733.845323093619;7733.8839917400155;7739.961300193689;7998.240167184108;8176.305014073632;8261.215184557457;8338.583070846313;8354.540500272544;5927.4665280125;6742.460603151834;7417.925820741723;7477.102747670315;7496.700131994659;6896.793096207121;7313.451491138292;7363.300486453778;7370.44721645868;7372.48392612512;6898.827529311808;7006.649667516705;7008.509404341851;7012.368886800213;7017.193022802443;4839.758012099452;4852.781624826962;4948.777305021211;4981.277714731416;5149.2276158576515;4122.876313710593;4980.975095124501;5085.821996230115;5089.694618322913;5164.457731938242 16:04:16 INFO - PROCESS | 1955 | |4;prototype.html;262.89090027638525;293.0563585255478;305.84249111707476;306.8956554771377;309.26240915416724;5176.7575781812375;5286.259923610698;5291.391489978653;5293.100172970595;5313.946860531393;4862.294967229758;4949.50050224636;5075.796968121271;5080.593552515799;5241.737913104339;3174.476211425112;3611.9231441921;3806.9428958565645;3973.4635824163656;4055.4119652650497;3347.681970212826;3989.381645844884;4015.7188996770237;4023.939640905389;4092;3411.812350320731;3767.3783825668734;3949.341276663794;3967;3978.9455794214555;3325.9833700831464;3941.2511622791862;3973.3048265113434;3989.5212574491247;4020.0050962033324;3424.5376874121926;3427.1260828488575;3429.725621950238;3437.054809927265;3441.7934923904645;5057.772400241998;5079.034983353151;5083.872903177413;5102.668326558799;5110.616703747197;4240.770176648768;4340.522542520352;4477.865664030084;4504.729716217004;4513.932291015604;5439.3200849893765;5445.319335083114;5453.682185071329;5456.863578410532;5573.637713548606;3734.439834024891;3750.1749615084636;3752.4934133891848;3760.191558814868;3781.6596506314563;5377.11277639189;5707.315122185323;5731.656100633933;5748.080307150836;5756.251687280626;3251.5935508061493;3796.974816799504;3799.917023648247;3808.8095595220684;3820.426935959573;3280.6227283862186;3332;3346.481295399217;3474.071890101018;3531.375567239099;3988.561258261589;4318.654507639381;4361.258586040381;4523.231050721385;4536.88657783558;4496.505384407713;4927.137750893579;5209.348831396075;5439;5486.561075113981;1184.7571247894161;1314.9211047336998;1318.808772727949;1319.7492476429447;1321.3549131331554;722.9024081748896;840.277361469136;841.6113412868815;847.6694089305176;866.8743032260287;4491.843914920791;4560.384348113029;4626.982063945927;5084;5098.246946648842;4716.740579268173;5271.341082364705;5522.364928033247;5529.7604470189635;5548.334199895958;4809;5031.698098114125;5039.647224694236;5177.068127737043;5246.868828279247;2674.422090966339;2920.985395073011;2958.9791521924903;2969.8069625474277;3000.2799328161086;5749.626274292157;5754.769809207585;5758.280214973129;5760.810891861066;5772.971135144381;5208.958208358344;5409.215663728737;5795.391483894215;5843.094320380348;5871.060630299132;5064.366954130734;5139.948600514022;5287.7620507078045;5804.984127777622;5813.94186058134;4857.587105095965;5328.014317351302;5471.863203419946;5699.971500142473;5714.799712161203;4932.45742968266;5415.019960329076;5642.633228840113;5656.123300888369;5680.204771331934;5343.3587969443915;5777.422257774189;5788.90010897928;5817;5822.854428639318;3306.470964645645;3647.425448744355;3752.512173417438;3784.508013958168;3807.9428808568446;5654.915176272441;5673.631213971079;5677.545796336366;5678.586600694771;5680.602357834912;4737.147313483606;5505.476979686923;5507.972460137674;5546.142042415836;5583.469570390806;4903.5586797188425;5198.636095473281;5205.947940520698;5449.427810079885;5512.649400896646;4166;4998.725070121032;5099.5410413063;5402.324709411324;5437.972810135924;4435.179491794029;4741.691790033637;5204.895902082013;5385.596080293915;5415.943890941228;4764.857054288377;4920.311156438173;5150.690958542499;5418.918716219338;5453.754581043783 16:04:16 INFO - PROCESS | 1955 | |5;yui.html;635.5233574818886;722.9710811567534;785.9528428294299;827.7475370012146;830.1490971753955;13803.102798318103;15481.07113573185;15803;15817.209139543034;15817.971831830937;13907.513237036706;14392.856071439282;14401;14404.783928241084;14796.112233265985;718.9748358807443;743.9516431431939;749.2694622742837;891.1534042659483;902.1835239108584;902.1294450854924;907.9727608171765;914.1681070226063;917.1516347378681;917.2982668258787;961.8124465729186;981.7938232971085;983.9360441571275;984.2913102566141;986.6694657289817;754.811297175706;777.6694904665494;784.9921500784976;979.3291595257272;980.1570649241611;578.5949835115437;583.3233449198951;692.944564434844;714.5855403865745;716.4018044932487;15240.46658366963;15278.847211527853;15303.311350989232;15309.157996310198;15319.923400382926;13645.727085458235;13803.654908627264;13857.026419895466;13920.37358318878;14202.608826028329;12851.871481285161;12900.870991290154;12909;13033.44753153659;13483.66290842727;4471.150481408522;4522.29904364824;4552.749598772066;4716.174669432836;4716.716996980193;109942;113050;115203;115215.84784152135;116433.83566164314;9256.259499240045;10776.461176941122;10968.670939871816;11064.668059958214;11167;22090.88954555217;22132.114715411524;22190.889045554668;22205;22438.775612243997;7528.774136775905;7567.091948966159;7573.697052117854;7633.961830190925;7733.033370828646;4259.297215959332;4309.733591824822;4330.133973205308;4348.673849461303;4426.955730442719;3354.8658053677577;3438.6561343865415;3460;3481.164520515058;3492.5285086512995;556.8385168301228;615.8645098078416;627.8869803435334;629.0878226571398;632.3139393757824;597.3219407029367;608.2245137449787;630.9810705678836;642.8103709405736;658.7595527632476;8051.436399452099;8208.835823283502;8809.471431714117;8832.249258813074;8903.777405564913;605.2585582661293;628.202183227308;634.8730253949136;647.9481641468672;648.1120864415781;4104.322786740214;4420.734755914656;4423.336499525032;4430.3575981483145;4549.909001819945;158255;159478;159729;160434;164582;97127.0287297118;132513.0123048155;135298.3235083818;135910.32044839713;137536.99704023855;113016.30475542873;118867;119033;119565;119850;8143.348532117535;8168.795780105307;8249.216324449168;8584.484930903916;8663.263622591898;50954.243273346976;61906;64556.03165952608;65021;65236.67381663251;90361;91313.7171570716;95566;97147.51426242823;100189.49905250427;11618.709532261422;11704.70738231551;11764;11769.293842369485;11805.173637845268;12289.52961143544;12306.384680766105;12332.81500777471;12481.00151987857;12571.182873113585;11602.29240045373;11675.88324116748;11737.41312934367;11754.29474231549;11826.46780894879;9774.609015639579;11415.258008229439;11729.530818767154;11873.406329683654;12277.079219058427;11500.565016553914;11572.18994670365;11574.131940104695;11707.941460292644;12002.39988000579;9432.528373581155;9706.272029597667;9978.451185184884;10025.699229023141;10352.792944141225;8678.154938023708;9048.909510904807;9957.759504111516;10072.345297555636;10113.29206955506 16:04:16 INFO - PROCESS | 1955 | __end_tp_report 16:04:16 INFO - PROCESS | 1955 | __start_cc_report 16:04:16 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1399 16:04:16 INFO - PROCESS | 1955 | __end_cc_report 16:04:16 INFO - PROCESS | 1955 | __startTimestamp1477436656162__endTimestamp 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | ------- Summary: start ------- 16:04:16 INFO - PROCESS | 1955 | Number of tests: 6 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5694.74 Median:5958.64 stddev:645.10 (10.8%) stddev-sans-first:617.48 16:04:16 INFO - PROCESS | 1955 | Values: 3232.5 3249.0 3254.6 3272.0 3298.6 5427.7 5490.8 5539.8 5587.0 5672.1 5644.1 5683.6 5688.7 5693.9 5708.0 5120.6 5148.5 5153.0 5191.1 5208.5 5224.0 5258.7 5262.8 5265.0 5290.9 5162.7 5211.9 5213.9 5219.7 5229.8 5261.8 5269.8 5278.6 5295.8 5334.0 5083.7 5095.1 5137.2 5203.9 5207.9 5519.0 5585.4 5588.0 5629.3 5657.4 6171.2 6178.1 6191.3 6193.8 6201.4 6099.2 6190.8 6191.0 6235.1 6260.3 4806.2 5758.1 5789.9 5795.0 5859.7 6187.1 6203.3 6208.0 6215.4 6217.6 5989.9 6059.4 6101.1 6113.8 6119.8 6092.7 6098.6 6139.2 6142.0 6200.2 6105.3 6131.9 6135.5 6141.1 6158.9 6136.9 6144.0 6147.4 6152.7 6162.3 4204.8 4726.7 4851.2 4851.6 4856.1 4390.8 4396.8 4441.4 4447.1 4485.4 5682.2 5732.9 5734.2 5739.3 5739.9 6079.7 6111.4 6111.5 6227.4 6233.3 5730.8 5751.3 5753.8 5785.9 5841.5 5354.7 5361.9 5374.3 5383.1 5387.0 6135.0 6208.1 6209.4 6245.8 6257.2 6128.7 6140.7 6191.0 6204.1 6255.2 6172.7 6226.9 6239.1 6260.4 6286.0 5978.1 6159.7 6167.4 6176.4 6194.2 6112.0 6146.1 6158.3 6203.5 6254.3 6020.8 6105.0 6113.9 6150.6 6158.4 5490.1 6107.7 6116.7 6136.4 6137.8 5521.6 5853.6 5901.7 5911.5 5943.1 6118.2 6118.9 6119.6 6120.1 6120.4 5802.2 5958.6 5966.0 5967.7 6018.5 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:68131.30 Median:40663.80 stddev:146658.04 (360.7%) stddev-sans-first:146981.49 16:04:16 INFO - PROCESS | 1955 | Values: 480.2 498.7 519.3 523.0 542.4 27140.5 31493.6 31496.8 32399.7 32457.8 35551.3 42762.9 43281.0 45495.5 45586.5 9420.7 9583.4 9588.4 9592.8 9606.5 9344.9 9433.7 9436.9 9437.6 9439.2 9439.1 9485.1 9488.5 9498.0 9541.0 9390.7 9438.1 9459.2 9469.5 9485.7 9488.7 9547.5 9548.8 9565.0 9585.4 40903.8 41185.0 41213.8 41289.6 41311.6 3384.1 3424.4 3468.3 3470.0 3575.7 30007.8 31446.8 31465.8 31482.0 31551.2 3223.6 3285.0 3330.2 3356.0 3366.4 489067.0 494106.0 494260.5 495754.0 496005.0 59847.7 60230.4 60344.4 60364.7 60527.0 64530.0 64679.4 64735.4 64754.4 64767.7 14877.3 15053.6 15087.2 15095.5 15117.9 12049.8 12098.2 12111.6 12113.0 12120.6 13709.5 14582.8 15229.7 16090.8 16753.6 5494.5 6463.9 6675.2 6679.9 6734.0 807.5 866.8 920.1 1024.0 1049.7 28077.0 28523.9 28729.6 30208.1 31095.5 1024.6 1059.9 1076.5 1076.5 1076.8 12135.3 14787.4 15183.8 15260.5 15923.6 497171.0 624271.0 624988.0 627570.0 631302.0 57173.4 57177.7 57201.0 57377.7 57391.4 1256.3 1305.2 1380.5 1403.6 1420.6 29414.0 30490.0 30676.6 30768.4 31093.0 50351.7 50859.2 50945.0 50981.7 51026.0 534649.2 538241.0 539370.6 541417.0 572494.0 25527.5 25608.0 25647.5 25699.7 25954.2 48052.0 48104.8 48451.0 48524.0 49242.8 38976.0 39535.4 39953.8 40878.6 41305.4 38857.8 38918.0 39310.4 40356.6 40449.0 38114.6 38635.8 38844.4 39050.4 39077.2 32038.4 34555.0 35027.8 35251.8 35430.8 23227.2 25347.7 25556.4 25583.8 26069.6 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:32962.95 Median:2284.94 stddev:94518.12 (4136.6%) stddev-sans-first:94751.56 16:04:16 INFO - PROCESS | 1955 | Values: 352.9 372.6 374.8 382.6 405.9 14596.3 14994.7 15384.8 19726.3 20156.8 18350.8 19233.3 20177.8 20756.6 20984.4 5688.9 5983.0 5986.8 5987.0 5995.0 5177.0 6517.7 6609.0 6651.5 6657.7 5730.2 6037.0 6567.4 6574.1 6591.8 6062.4 6101.6 6107.4 6107.7 6109.1 5815.8 5898.7 5914.3 5991.1 5998.5 13355.9 13434.7 13593.9 25242.5 25633.5 1824.9 1978.7 2154.2 2233.3 2336.6 9816.9 10025.7 10047.2 10501.2 10618.3 1541.6 1774.4 1842.8 1848.4 1901.3 75611.6 114285.4 118586.0 118959.0 130058.0 10306.4 10563.9 11319.7 11595.4 11674.0 14683.3 14711.3 14715.0 14721.8 14737.9 7279.6 7689.2 8385.9 8390.2 8526.1 6919.8 8048.6 8162.8 8320.1 8343.2 3876.7 4423.2 4466.8 4511.3 4617.5 1876.0 1921.8 1923.8 1931.8 1935.3 772.9 937.5 937.8 970.7 1052.1 14927.8 19791.7 20375.9 21155.4 21243.0 925.0 928.4 944.5 958.4 992.0 10321.3 11125.6 11133.3 11142.3 11195.1 551672.0 562954.0 564066.0 569293.0 586648.0 88553.6 122509.4 123341.0 125236.0 125961.0 987.2 1022.2 1064.3 1065.4 1066.6 18028.0 18241.6 18541.0 18695.0 18697.6 50485.0 50834.5 50861.7 50907.0 50977.7 66792.7 67908.6 68167.0 68998.7 69555.3 7626.5 7941.1 8025.8 8196.9 8405.7 9198.0 10291.9 11994.2 12908.2 13069.8 11224.5 11351.7 11369.8 11378.4 11437.6 9291.9 10839.3 11106.9 11108.6 11178.2 11320.8 11491.4 11548.4 11568.4 11758.0 7916.2 8117.4 8137.7 8217.4 8374.4 8388.7 10553.9 11145.7 11474.6 11514.9 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5585.19 Median:5162.16 stddev:3130.63 (60.6%) stddev-sans-first:3123.36 16:04:16 INFO - PROCESS | 1955 | Values: 1366.1 1524.9 1558.0 1777.5 1949.3 7745.3 8219.3 8445.7 8822.3 8823.2 8626.9 8717.7 8725.6 8725.7 8735.6 2293.6 2322.6 2322.9 2324.2 2324.3 2276.9 2313.9 2316.4 2325.2 2330.1 2270.3 2297.1 2303.7 2307.5 2309.8 2253.6 2261.4 2270.6 2271.4 2272.4 2285.5 2286.8 2291.4 2292.0 2293.6 8611.1 8690.1 8742.3 8743.7 8744.7 5105.6 5293.4 5313.7 5316.5 5323.8 3406.6 3541.1 3623.3 3631.5 3634.6 1035.3 1070.3 1075.2 1080.9 1081.4 11445.4 11480.5 11575.5 11579.7 11594.6 4269.3 4315.2 4493.6 4500.4 4506.0 8990.8 8995.3 8998.5 9019.2 9020.9 5159.9 5168.3 5170.5 5171.8 5183.3 4091.4 4331.5 4489.1 4495.6 4836.1 3471.2 3736.7 3756.8 3774.0 3778.1 990.5 1106.1 1132.9 1143.3 1145.5 886.8 899.6 916.5 919.8 923.1 5639.4 5681.0 6001.0 6025.7 6027.3 940.6 943.1 949.7 962.4 980.4 3806.7 3831.3 3875.4 3971.6 4059.0 10273.9 10748.1 10752.0 10762.9 10775.9 10730.9 10739.4 10739.9 10741.7 10752.7 7658.8 8054.6 8132.9 8265.9 8275.1 5213.9 5660.8 5980.8 6026.8 6137.1 9412.8 9431.3 9470.7 9703.0 9829.6 7730.8 9192.4 9654.5 9961.8 10056.7 7458.9 7730.5 7733.8 7733.9 7740.0 7998.2 8176.3 8261.2 8338.6 8354.5 5927.5 6742.5 7417.9 7477.1 7496.7 6896.8 7313.5 7363.3 7370.4 7372.5 6898.8 7006.6 7008.5 7012.4 7017.2 4839.8 4852.8 4948.8 4981.3 5149.2 4122.9 4981.0 5085.8 5089.7 5164.5 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4416.59 Median:5048.71 stddev:1371.34 (27.2%) stddev-sans-first:1339.27 16:04:16 INFO - PROCESS | 1955 | Values: 262.9 293.1 305.8 306.9 309.3 5176.8 5286.3 5291.4 5293.1 5313.9 4862.3 4949.5 5075.8 5080.6 5241.7 3174.5 3611.9 3806.9 3973.5 4055.4 3347.7 3989.4 4015.7 4023.9 4092.0 3411.8 3767.4 3949.3 3967.0 3978.9 3326.0 3941.3 3973.3 3989.5 4020.0 3424.5 3427.1 3429.7 3437.1 3441.8 5057.8 5079.0 5083.9 5102.7 5110.6 4240.8 4340.5 4477.9 4504.7 4513.9 5439.3 5445.3 5453.7 5456.9 5573.6 3734.4 3750.2 3752.5 3760.2 3781.7 5377.1 5707.3 5731.7 5748.1 5756.3 3251.6 3797.0 3799.9 3808.8 3820.4 3280.6 3332.0 3346.5 3474.1 3531.4 3988.6 4318.7 4361.3 4523.2 4536.9 4496.5 4927.1 5209.3 5439.0 5486.6 1184.8 1314.9 1318.8 1319.7 1321.4 722.9 840.3 841.6 847.7 866.9 4491.8 4560.4 4627.0 5084.0 5098.2 4716.7 5271.3 5522.4 5529.8 5548.3 4809.0 5031.7 5039.6 5177.1 5246.9 2674.4 2921.0 2959.0 2969.8 3000.3 5749.6 5754.8 5758.3 5760.8 5773.0 5209.0 5409.2 5795.4 5843.1 5871.1 5064.4 5139.9 5287.8 5805.0 5813.9 4857.6 5328.0 5471.9 5700.0 5714.8 4932.5 5415.0 5642.6 5656.1 5680.2 5343.4 5777.4 5788.9 5817.0 5822.9 3306.5 3647.4 3752.5 3784.5 3807.9 5654.9 5673.6 5677.5 5678.6 5680.6 4737.1 5505.5 5508.0 5546.1 5583.5 4903.6 5198.6 5205.9 5449.4 5512.6 4166.0 4998.7 5099.5 5402.3 5438.0 4435.2 4741.7 5204.9 5385.6 5415.9 4764.9 4920.3 5150.7 5418.9 5453.8 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:25221.96 Median:4339.40 stddev:41611.65 (958.9%) stddev-sans-first:41687.44 16:04:16 INFO - PROCESS | 1955 | Values: 635.5 723.0 786.0 827.7 830.1 13803.1 15481.1 15803.0 15817.2 15818.0 13907.5 14392.9 14401.0 14404.8 14796.1 719.0 744.0 749.3 891.2 902.2 902.1 908.0 914.2 917.2 917.3 961.8 981.8 983.9 984.3 986.7 754.8 777.7 785.0 979.3 980.2 578.6 583.3 692.9 714.6 716.4 15240.5 15278.8 15303.3 15309.2 15319.9 13645.7 13803.7 13857.0 13920.4 14202.6 12851.9 12900.9 12909.0 13033.4 13483.7 4471.2 4522.3 4552.7 4716.2 4716.7 109942.0 113050.0 115203.0 115215.8 116433.8 9256.3 10776.5 10968.7 11064.7 11167.0 22090.9 22132.1 22190.9 22205.0 22438.8 7528.8 7567.1 7573.7 7634.0 7733.0 4259.3 4309.7 4330.1 4348.7 4427.0 3354.9 3438.7 3460.0 3481.2 3492.5 556.8 615.9 627.9 629.1 632.3 597.3 608.2 631.0 642.8 658.8 8051.4 8208.8 8809.5 8832.2 8903.8 605.3 628.2 634.9 647.9 648.1 4104.3 4420.7 4423.3 4430.4 4549.9 158255.0 159478.0 159729.0 160434.0 164582.0 97127.0 132513.0 135298.3 135910.3 137537.0 113016.3 118867.0 119033.0 119565.0 119850.0 8143.3 8168.8 8249.2 8584.5 8663.3 50954.2 61906.0 64556.0 65021.0 65236.7 90361.0 91313.7 95566.0 97147.5 100189.5 11618.7 11704.7 11764.0 11769.3 11805.2 12289.5 12306.4 12332.8 12481.0 12571.2 11602.3 11675.9 11737.4 11754.3 11826.5 9774.6 11415.3 11729.5 11873.4 12277.1 11500.6 11572.2 11574.1 11707.9 12002.4 9432.5 9706.3 9978.5 10025.7 10352.8 8678.2 9048.9 9957.8 10072.3 10113.3 16:04:16 INFO - PROCESS | 1955 | -------- Summary: end -------- 16:04:16 INFO - PROCESS | 1955 | 16:04:16 INFO - TEST-INFO | 1955: exit 0 16:04:16 INFO - TEST-OK | dromaeo_css | took 1118257ms 16:04:16 INFO - TEST-START | kraken 16:04:16 INFO - operating with platform_type : mac_ 16:04:16 INFO - Initialising browser for kraken test... 16:04:16 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpODaWEt/profile http://localhost:51502/getInfo.html) 16:04:19 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 16:04:19 INFO - PROCESS | 1959 | colorDepth:24 16:04:19 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 16:04:19 INFO - PROCESS | 1959 | __metrics 16:04:19 INFO - PROCESS | 1959 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:04:19 INFO - PROCESS | 1959 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:04:20 INFO - TEST-INFO | 1959: exit 0 16:04:20 INFO - Browser initialized. 16:04:20 INFO - Running cycle 1/1 for kraken test... 16:04:20 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpODaWEt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:32 INFO - PROCESS | 1960 | RSS: Main: 241795072 16:04:32 INFO - PROCESS | 1960 | 16:04:34 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-beat-detection) 16:04:35 INFO - PROCESS | 1960 | RSS: Main: 292372480 16:04:35 INFO - PROCESS | 1960 | 16:04:38 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-dft) 16:04:39 INFO - PROCESS | 1960 | RSS: Main: 295280640 16:04:39 INFO - PROCESS | 1960 | 16:04:42 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-fft) 16:04:43 INFO - PROCESS | 1960 | RSS: Main: 384548864 16:04:43 INFO - PROCESS | 1960 | 16:04:45 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-oscillator) 16:04:46 INFO - PROCESS | 1960 | RSS: Main: 286105600 16:04:46 INFO - PROCESS | 1960 | 16:04:48 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51502/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:48 INFO - PROCESS | 1960 | RSS: Main: 290394112 16:04:48 INFO - PROCESS | 1960 | 16:04:51 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51502/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:52 INFO - PROCESS | 1960 | RSS: Main: 329289728 16:04:52 INFO - PROCESS | 1960 | 16:04:54 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51502/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:55 INFO - PROCESS | 1960 | RSS: Main: 329830400 16:04:55 INFO - PROCESS | 1960 | 16:04:58 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51502/tests/kraken/driver.html?testName=json-parse-financial) 16:04:59 INFO - PROCESS | 1960 | RSS: Main: 330178560 16:04:59 INFO - PROCESS | 1960 | 16:05:00 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51502/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:05:01 INFO - PROCESS | 1960 | RSS: Main: 302809088 16:05:01 INFO - PROCESS | 1960 | 16:05:03 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:05:03 INFO - PROCESS | 1960 | RSS: Main: 307396608 16:05:03 INFO - PROCESS | 1960 | 16:05:05 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:05:06 INFO - PROCESS | 1960 | RSS: Main: 311013376 16:05:06 INFO - PROCESS | 1960 | 16:05:09 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:10 INFO - PROCESS | 1960 | RSS: Main: 321101824 16:05:10 INFO - PROCESS | 1960 | 16:05:13 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:13 INFO - PROCESS | 1960 | RSS: Main: 321105920 16:05:13 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51502/tests/kraken/driver.html?testName=ai-astar) 16:05:15 INFO - PROCESS | 1960 | RSS: Main: 365543424 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | __start_tp_report 16:05:15 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 16:05:15 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 16:05:15 INFO - PROCESS | 1960 | |i|pagename|runs| 16:05:15 INFO - PROCESS | 1960 | |0;ai-astar;112;112;111;112;111;113;111;112;112;111 16:05:15 INFO - PROCESS | 1960 | |1;audio-beat-detection;149;118;114;149;130;98;135;163;175;111 16:05:15 INFO - PROCESS | 1960 | |2;audio-dft;197;256;192;186;181;184;183;180;184;181 16:05:15 INFO - PROCESS | 1960 | |3;audio-fft;79;59;58;89;61;58;62;58;81;57 16:05:15 INFO - PROCESS | 1960 | |4;audio-oscillator;85;71;92;69;67;68;92;66;67;91 16:05:15 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;87;85;86;84;86;84;88;129;88;84 16:05:15 INFO - PROCESS | 1960 | |6;imaging-darkroom;131;136;93;93;93;93;93;130;93;92 16:05:15 INFO - PROCESS | 1960 | |7;imaging-desaturate;83;81;79;79;80;99;128;135;80;84 16:05:15 INFO - PROCESS | 1960 | |8;json-parse-financial;57;55;55;53;52;55;54;53;53;53 16:05:15 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;65;67;76;81;74;67;69;70;70;70 16:05:15 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;90;86;87;87;89;88;85;85;86;86 16:05:15 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;149;144;164;165;157;152;180;166;187;148 16:05:15 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;172;169;170;172;171;172;173;171;171;168 16:05:15 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;70;69;69;68;69;69;67;68;68;68 16:05:15 INFO - PROCESS | 1960 | __end_tp_report 16:05:15 INFO - PROCESS | 1960 | __start_cc_report 16:05:15 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1996 16:05:15 INFO - PROCESS | 1960 | __end_cc_report 16:05:15 INFO - PROCESS | 1960 | __startTimestamp1477436715964__endTimestamp 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | ------- Summary: start ------- 16:05:15 INFO - PROCESS | 1960 | Number of tests: 14 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 16:05:15 INFO - PROCESS | 1960 | Values: 112.0 112.0 111.0 112.0 111.0 113.0 111.0 112.0 112.0 111.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:149.00 stddev:24.65 (16.5%) stddev-sans-first:25.56 16:05:15 INFO - PROCESS | 1960 | Values: 149.0 118.0 114.0 149.0 130.0 98.0 135.0 163.0 175.0 111.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:192.40 Median:185.00 stddev:22.97 (12.4%) stddev-sans-first:24.31 16:05:15 INFO - PROCESS | 1960 | Values: 197.0 256.0 192.0 186.0 181.0 184.0 183.0 180.0 184.0 181.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.50 stddev:11.95 (19.4%) stddev-sans-first:11.74 16:05:15 INFO - PROCESS | 1960 | Values: 79.0 59.0 58.0 89.0 61.0 58.0 62.0 58.0 81.0 57.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:78.00 stddev:11.60 (14.9%) stddev-sans-first:11.92 16:05:15 INFO - PROCESS | 1960 | Values: 85.0 71.0 92.0 69.0 67.0 68.0 92.0 66.0 67.0 91.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:86.00 stddev:13.76 (16.0%) stddev-sans-first:14.54 16:05:15 INFO - PROCESS | 1960 | Values: 87.0 85.0 86.0 84.0 86.0 84.0 88.0 129.0 88.0 84.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:104.70 Median:93.00 stddev:19.13 (20.6%) stddev-sans-first:17.77 16:05:15 INFO - PROCESS | 1960 | Values: 131.0 136.0 93.0 93.0 93.0 93.0 93.0 130.0 93.0 92.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:92.80 Median:80.50 stddev:21.29 (26.4%) stddev-sans-first:22.28 16:05:15 INFO - PROCESS | 1960 | Values: 83.0 81.0 79.0 79.0 80.0 99.0 128.0 135.0 80.0 84.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.49 (2.7%) stddev-sans-first:1.12 16:05:15 INFO - PROCESS | 1960 | Values: 57.0 55.0 55.0 53.0 52.0 55.0 54.0 53.0 53.0 53.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.00 stddev:4.82 (6.9%) stddev-sans-first:4.61 16:05:15 INFO - PROCESS | 1960 | Values: 65.0 67.0 76.0 81.0 74.0 67.0 69.0 70.0 70.0 70.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.00 stddev:1.66 (1.9%) stddev-sans-first:1.33 16:05:15 INFO - PROCESS | 1960 | Values: 90.0 86.0 87.0 87.0 89.0 88.0 85.0 85.0 86.0 86.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:161.20 Median:164.50 stddev:14.09 (8.6%) stddev-sans-first:14.23 16:05:15 INFO - PROCESS | 1960 | Values: 149.0 144.0 164.0 165.0 157.0 152.0 180.0 166.0 187.0 148.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:171.50 stddev:1.52 (0.9%) stddev-sans-first:1.56 16:05:15 INFO - PROCESS | 1960 | Values: 172.0 169.0 170.0 172.0 171.0 172.0 173.0 171.0 171.0 168.0 16:05:15 INFO - PROCESS | 1960 | 16:05:15 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:0.85 (1.2%) stddev-sans-first:0.71 16:05:15 INFO - PROCESS | 1960 | Values: 70.0 69.0 69.0 68.0 69.0 69.0 67.0 68.0 68.0 68.0 16:05:15 INFO - PROCESS | 1960 | -------- Summary: end -------- 16:05:15 INFO - PROCESS | 1960 | 16:05:16 INFO - TEST-INFO | 1960: exit 0 16:05:16 INFO - TEST-OK | kraken | took 60075ms 16:05:16 INFO - SUITE-END | took 1178s 16:05:17 INFO - Completed test suite (00:19:38) 16:05:17 INFO - javascript benchmark 16:05:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.466643003903, 3248.967510324897, 3254.625718042425, 3271.950920736188, 3298.5876765404323, 5427.674339539625, 5490.780368785248, 5539.750711217995, 5586.972065139668, 5672.06410942194, 5644.096944488882, 5683.602147849642, 5688.744006519706, 5693.946619875326, 5707.971460142704, 5120.590352771769, 5148.459411761767, 5153.046686363016, 5191.117510023305, 5208.479152084799, 5223.955208958205, 5258.684478931257, 5262.842114736564, 5264.999650066494, 5290.894182116355, 5162.664426812264, 5211.947880521184, 5213.895722085556, 5219.71291578963, 5229.764660590264, 5261.789528418859, 5269.762860671279, 5278.630495865291, 5295.7881684732565, 5333.986542556901, 5083.669561478492, 5095.0574143783515, 5137.229415587654, 5203.9219411708855, 5207.895842083175, 5519.0, 5585.441455854423, 5588.0, 5629.324481062258, 5657.377688454267, 6171.197744293258, 6178.07328900664, 6191.318954914956, 6193.81418557444, 6201.441870231656, 6099.158463004126, 6190.84522886927, 6190.969045154746, 6235.095911092866, 6260.342664020258, 4806.1829488987305, 5758.078707406795, 5789.94210057894, 5795.014847475939, 5859.677717725566, 6187.133801267826, 6203.286622038433, 6207.96896015517, 6215.378462153749, 6217.564770466114, 5989.9101513477335, 6059.36376680451, 6101.145839582462, 6113.786017489367, 6119.84700382494, 6092.664903430354, 6098.613770999311, 6139.1630231105555, 6142.0, 6200.224971878515, 6105.32841387447, 6131.901258721728, 6135.5398345124295, 6141.148492690813, 6158.938410615926, 6136.938630613726, 6144.047672610753, 6147.354527774519, 6152.661603611755, 6162.26052873649, 4204.810783514749, 4726.71639701619, 4851.248056551241, 4851.56335929768, 4856.1016212000895, 4390.780460977002, 4396.802143903532, 4441.422615060086, 4447.110577884436, 4485.416895803524, 5682.204491371212, 5732.856678582985, 5734.197212390269, 5739.339975902742, 5739.913901291484, 6079.726412311456, 6111.37903775109, 6111.480524155408, 6227.439530442282, 6233.252009758768, 5730.770769169187, 5751.338596061507, 5753.827385178452, 5785.8553536161935, 5841.532677385799, 5354.651947623392, 5361.919571206435, 5374.27447294618, 5383.084875571162, 5387.0, 6135.018317212141, 6208.1308616793685, 6209.379062093754, 6245.781397651061, 6257.217847769029, 6128.693565321805, 6140.688304616564, 6191.0, 6204.13142160107, 6255.24937007562, 6172.6913654318005, 6226.906596400967, 6239.06414037898, 6260.40526150015, 6286.0, 5978.103284507359, 6159.72281247336, 6167.352427994996, 6176.382361763787, 6194.163787888758, 6112.047575716935, 6146.078088286793, 6158.261008678987, 6203.534734894811, 6254.312025677263, 6020.830644280432, 6105.004892149239, 6113.877722445615, 6150.600210986271, 6158.3841615838055, 5490.0941344677685, 6107.683651265568, 6116.731440059295, 6136.3965394158, 6137.836677219377, 5521.641093328921, 5853.560982926383, 5901.704914754159, 5911.527077833849, 5943.078822782476, 6118.223266370805, 6118.862843290023, 6119.571629985859, 6120.050983828446, 6120.416658368064, 5802.158686990447, 5958.6424814511265, 5966.0, 5967.701614919323, 6018.548608854266], "value": 5652.023001408086, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.16451374608187, 498.6758606905511, 519.3067255214289, 523.0036779934225, 542.4277387356337, 27140.45719085617, 31493.64608016637, 31496.842515787448, 32399.704011839498, 32457.837710811473, 35551.288974220566, 42762.93092672685, 43280.99999999992, 45495.545044549544, 45586.544134558644, 9420.670276540322, 9583.377080489781, 9588.376755510904, 9592.80814383712, 9606.471644059575, 9344.906550934506, 9433.716988490356, 9436.858447123299, 9437.575309111073, 9439.244860411185, 9439.05609439054, 9485.146336829685, 9488.478133702643, 9498.0, 9540.950495445495, 9390.671326503574, 9438.103380178873, 9459.19596834263, 9469.479178645171, 9485.668001619979, 9488.667896623654, 9547.522623868848, 9548.761280967961, 9565.0, 9585.424874507551, 40903.7954810227, 41185.0, 41213.79393103045, 41289.587104129176, 41311.58688413107, 3384.069380920242, 3424.400729872262, 3468.254325320043, 3469.9653003469894, 3575.6603122703564, 30007.849960750056, 31446.842765786023, 31465.8426707865, 31482.0, 31551.2138543374, 3223.564818749462, 3285.0, 3330.2340461693702, 3356.043527594624, 3366.444536651474, 489067.0, 494106.0, 494260.5286973578, 495754.0, 496005.0, 59847.700761495915, 60230.397696023356, 60344.39655603476, 60364.698176509715, 60527.0, 64530.032049519294, 64679.353206467335, 64735.352646473875, 64754.352456475775, 64767.676161619835, 14877.33052012662, 15053.623659408602, 15087.24563771807, 15095.4716584919, 15117.924410377878, 12049.819252711217, 12098.153129280865, 12111.636650900487, 12112.97039751631, 12120.636380908587, 13709.520166794315, 14582.781258281135, 15229.705475034747, 16090.793190510525, 16753.581160471087, 5494.450554944554, 6463.870722585522, 6675.232348279913, 6679.9332006679315, 6733.966330168416, 807.5195258820992, 866.8309679612415, 920.0661328751323, 1024.0067134879155, 1049.7270709615555, 28077.0, 28523.857380712965, 28729.56905646417, 30208.09375718732, 31095.533566996513, 1024.6055268721595, 1059.888711685262, 1076.5048077884262, 1076.531708706715, 1076.8061748885275, 12135.271883687006, 14787.408503659948, 15183.848161518243, 15260.542183734506, 15923.601909952344, 497171.0, 624271.0, 624988.0, 627570.0, 631302.0, 57173.428265717645, 57177.71411143001, 57201.0, 57377.713111434176, 57391.42608573944, 1256.309030033478, 1305.2103477396038, 1380.4961189166083, 1403.635054885737, 1420.6022313752167, 29413.97051103201, 30490.0, 30676.597209404026, 30768.38463230678, 31093.0, 50351.74824126002, 50859.2371114441, 50945.0, 50981.74509127431, 51026.0, 534649.2280617463, 538241.0, 539370.6062939321, 541417.0, 572494.0, 25527.489450211262, 25608.0, 25647.487050258518, 25699.743002569736, 25954.22137335883, 48052.0, 48104.75947620379, 48451.03097938092, 48524.0, 49242.75378623084, 38976.0, 39535.40696889492, 39953.80023099982, 40878.591214088665, 41305.380419294335, 38857.80571097126, 38918.0, 39310.41034384544, 40356.59643403528, 40449.0, 38114.61885381111, 38635.80682096684, 38844.36756670324, 39050.41424378694, 39077.21845563129, 32038.35923281568, 34554.963351099505, 35027.82486087553, 35251.82374088216, 35430.8228458856, 23227.187048453223, 25347.746522535275, 25556.361090972874, 25583.83092355771, 26069.608955865297], "value": 19536.203093223427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.87120201126595, 372.6217888842824, 374.76951674720056, 382.5657878308121, 405.92490389277987, 14596.270186490672, 14994.700105997874, 15384.846151538468, 19726.309579164696, 20156.798432015676, 18350.816491835078, 19233.32683356083, 20177.798222017776, 20756.584868302623, 20984.370468885958, 5688.9146662799985, 5982.970085149568, 5986.820395388124, 5986.97006514969, 5995.040793473046, 5177.0, 6517.706703198343, 6609.0, 6651.467882569383, 6657.700403481855, 5730.226419433386, 6037.0, 6567.408933196011, 6574.079628851964, 6591.835204119888, 6062.393760623946, 6101.60339577926, 6107.358727333655, 6107.74013614963, 6109.114178444101, 5815.796447124372, 5898.738979801713, 5914.260717410324, 5991.101334799815, 5998.460138587505, 13355.933220333933, 13434.70857108721, 13593.932030339785, 25242.49515009708, 25633.487330253476, 1824.927002919895, 1978.7427634407436, 2154.192177933275, 2233.2630232023394, 2336.6144586143264, 9816.901830981671, 10025.749356266078, 10047.24645651579, 10501.21240906935, 10618.309809862334, 1541.5529496446018, 1774.4233124234675, 1842.797292297846, 1848.3530764232546, 1901.2965202874886, 75611.62194188994, 114285.4285728566, 118586.0, 118959.0, 130057.99999999905, 10306.433146176882, 10563.947180264204, 11319.717007074725, 11595.362255076052, 11674.007709344802, 14683.265836708122, 14711.337989790485, 14715.0, 14721.779173312409, 14737.926310368593, 7279.6360181990685, 7689.192634773268, 8385.874211886827, 8390.202930721573, 8526.147385261549, 6919.827004324831, 8048.631732605471, 8162.795930101676, 8320.086380132603, 8343.165683431609, 3876.7480113792512, 4423.225935461282, 4466.843660471868, 4511.300748384006, 4617.515160908123, 1875.9531011724816, 1921.8104532120112, 1923.7595300587427, 1931.777845547752, 1935.3129638978241, 772.9265719756727, 937.4890684576922, 937.7514958535993, 970.6505657963128, 1052.1109662335314, 14927.776083358758, 19791.723787257255, 20375.898120509304, 21155.365339039854, 21243.0, 924.9907500924904, 928.3872644054892, 944.471096186138, 958.4393130019017, 992.0327680511443, 10321.329113607742, 11125.554977800959, 11133.332000080021, 11142.27575207609, 11195.104391648649, 551672.0, 562954.0, 564066.0, 569293.0, 586648.0, 88553.55723221472, 122509.38745306217, 123341.0, 125236.0, 125961.0, 987.2299606307092, 1022.2486472442748, 1064.2975636080305, 1065.387402243713, 1066.6426747039673, 18028.0, 18241.635167296845, 18541.0, 18695.0, 18697.6260474787, 50485.0, 50834.49165508297, 50861.72845678888, 50907.0, 50977.745111274206, 66792.6660366695, 67908.64182716417, 68166.97749533661, 68998.65500672464, 69555.30444695488, 7626.504277222185, 7941.126476087741, 8025.799355016171, 8196.918030819616, 8405.663773448994, 9197.954010230174, 10291.897081029094, 11994.160408771302, 12908.16096953695, 13069.803952940523, 11224.494897729459, 11351.716207094558, 11369.829452558053, 11378.43107844621, 11437.570303712037, 9291.907080929375, 10839.295445795999, 11106.888931110585, 11108.61119860801, 11178.217524773188, 11320.830187547028, 11491.367974761133, 11548.42257887119, 11568.363739994045, 11758.0, 7916.208379162038, 8117.431779775359, 8137.715179968673, 8217.383696222927, 8374.40886231614, 8388.664453421794, 10553.947230264106, 11145.72135696614, 11474.598389056342, 11514.94242528782], "value": 9290.106283879235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.0642459914966, 1524.8703860171888, 1557.9844201557985, 1777.466759972008, 1949.298252629054, 7745.2641999010075, 8219.301359384452, 8445.662173513052, 8822.250108740765, 8823.16179962904, 8626.913730862689, 8717.738467845958, 8725.563721813916, 8725.650973961034, 8735.606897689619, 2293.6330187170056, 2322.605157123293, 2322.8954697038675, 2324.2213858357472, 2324.2678556254727, 2276.8633881967135, 2313.90744370225, 2316.3861576682193, 2325.2210509479346, 2330.0563271874917, 2270.2508172303183, 2297.058206135485, 2303.7005189325364, 2307.526956973816, 2309.7921187093157, 2253.5718213539376, 2261.389424855288, 2270.591293567157, 2271.3867255840914, 2272.420532764151, 2285.451491641994, 2286.8284878634004, 2291.4271432141963, 2291.9770802291932, 2293.633018717014, 8611.13888611134, 8690.130986901322, 8742.256908162813, 8743.650253989832, 8744.693935712283, 5105.617078719074, 5293.444188360244, 5313.734313284359, 5316.4683531646915, 5323.787048518055, 3406.5741782277214, 3541.11472131967, 3623.2572322673786, 3631.5279013728045, 3634.6365363463706, 1035.254616675992, 1070.314998401024, 1075.2365820267541, 1080.8811030786608, 1081.3565928272665, 11445.427728613537, 11480.483378247998, 11575.479103440366, 11579.652610421701, 11594.594189203423, 4269.252880745919, 4315.244832154361, 4493.550644935481, 4500.369948207253, 4505.977470112629, 8990.820183596292, 8995.32535059873, 8998.460092394478, 9019.233365163904, 9020.8646870297, 5159.871003224875, 5168.328117344721, 5170.45710200431, 5171.793128274902, 5183.300254465599, 4091.447654566655, 4331.45856767904, 4489.1021795641, 4495.550444955544, 4836.105320515716, 3471.1842716961496, 3736.7010639148803, 3756.8309426075893, 3773.981130094387, 3778.0554861284677, 990.5245482168599, 1106.1482658353023, 1132.8867113288604, 1143.2968724234536, 1145.5188820695328, 886.7916039730658, 899.5906862377733, 916.5142474488532, 919.8390281700675, 923.072312326108, 5639.436056394327, 5680.97159514208, 6000.969995149996, 6025.728842202111, 6027.276726792813, 940.6378544260372, 943.118184497497, 949.6657196638733, 962.3648392061206, 980.4117529482396, 3806.676432503268, 3831.252905683365, 3875.418687196943, 3971.5829837867186, 4059.0, 10273.897261027443, 10748.140148788078, 10752.032317091343, 10762.892371076345, 10775.946120269506, 10730.89269107299, 10739.409332486788, 10739.892601074045, 10741.677749667366, 10752.731181720519, 7658.846823063507, 8054.556999364974, 8132.878006829902, 8265.917340826634, 8275.131111233355, 5213.869653258699, 5660.830175094754, 5980.790672326448, 6026.849328766816, 6137.110119032626, 9412.764680883032, 9431.292653051185, 9470.668526601536, 9702.951485242811, 9829.557669904729, 7730.806729831799, 9192.448453092837, 9654.469004204837, 9961.750956226153, 10056.69829905104, 7458.925410745823, 7730.458867879194, 7733.845323093619, 7733.8839917400155, 7739.961300193689, 7998.240167184108, 8176.305014073632, 8261.215184557457, 8338.583070846313, 8354.540500272544, 5927.4665280125, 6742.460603151834, 7417.925820741723, 7477.102747670315, 7496.700131994659, 6896.793096207121, 7313.451491138292, 7363.300486453778, 7370.44721645868, 7372.48392612512, 6898.827529311808, 7006.649667516705, 7008.509404341851, 7012.368886800213, 7017.193022802443, 4839.758012099452, 4852.781624826962, 4948.777305021211, 4981.277714731416, 5149.2276158576515, 4122.876313710593, 4980.975095124501, 5085.821996230115, 5089.694618322913, 5164.457731938242], "value": 4489.3753214399085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.89090027638525, 293.0563585255478, 305.84249111707476, 306.8956554771377, 309.26240915416724, 5176.7575781812375, 5286.259923610698, 5291.391489978653, 5293.100172970595, 5313.946860531393, 4862.294967229758, 4949.50050224636, 5075.796968121271, 5080.593552515799, 5241.737913104339, 3174.476211425112, 3611.9231441921, 3806.9428958565645, 3973.4635824163656, 4055.4119652650497, 3347.681970212826, 3989.381645844884, 4015.7188996770237, 4023.939640905389, 4092.0, 3411.812350320731, 3767.3783825668734, 3949.341276663794, 3967.0, 3978.9455794214555, 3325.9833700831464, 3941.2511622791862, 3973.3048265113434, 3989.5212574491247, 4020.0050962033324, 3424.5376874121926, 3427.1260828488575, 3429.725621950238, 3437.054809927265, 3441.7934923904645, 5057.772400241998, 5079.034983353151, 5083.872903177413, 5102.668326558799, 5110.616703747197, 4240.770176648768, 4340.522542520352, 4477.865664030084, 4504.729716217004, 4513.932291015604, 5439.3200849893765, 5445.319335083114, 5453.682185071329, 5456.863578410532, 5573.637713548606, 3734.439834024891, 3750.1749615084636, 3752.4934133891848, 3760.191558814868, 3781.6596506314563, 5377.11277639189, 5707.315122185323, 5731.656100633933, 5748.080307150836, 5756.251687280626, 3251.5935508061493, 3796.974816799504, 3799.917023648247, 3808.8095595220684, 3820.426935959573, 3280.6227283862186, 3332.0, 3346.481295399217, 3474.071890101018, 3531.375567239099, 3988.561258261589, 4318.654507639381, 4361.258586040381, 4523.231050721385, 4536.88657783558, 4496.505384407713, 4927.137750893579, 5209.348831396075, 5439.0, 5486.561075113981, 1184.7571247894161, 1314.9211047336998, 1318.808772727949, 1319.7492476429447, 1321.3549131331554, 722.9024081748896, 840.277361469136, 841.6113412868815, 847.6694089305176, 866.8743032260287, 4491.843914920791, 4560.384348113029, 4626.982063945927, 5084.0, 5098.246946648842, 4716.740579268173, 5271.341082364705, 5522.364928033247, 5529.7604470189635, 5548.334199895958, 4809.0, 5031.698098114125, 5039.647224694236, 5177.068127737043, 5246.868828279247, 2674.422090966339, 2920.985395073011, 2958.9791521924903, 2969.8069625474277, 3000.2799328161086, 5749.626274292157, 5754.769809207585, 5758.280214973129, 5760.810891861066, 5772.971135144381, 5208.958208358344, 5409.215663728737, 5795.391483894215, 5843.094320380348, 5871.060630299132, 5064.366954130734, 5139.948600514022, 5287.7620507078045, 5804.984127777622, 5813.94186058134, 4857.587105095965, 5328.014317351302, 5471.863203419946, 5699.971500142473, 5714.799712161203, 4932.45742968266, 5415.019960329076, 5642.633228840113, 5656.123300888369, 5680.204771331934, 5343.3587969443915, 5777.422257774189, 5788.90010897928, 5817.0, 5822.854428639318, 3306.470964645645, 3647.425448744355, 3752.512173417438, 3784.508013958168, 3807.9428808568446, 5654.915176272441, 5673.631213971079, 5677.545796336366, 5678.586600694771, 5680.602357834912, 4737.147313483606, 5505.476979686923, 5507.972460137674, 5546.142042415836, 5583.469570390806, 4903.5586797188425, 5198.636095473281, 5205.947940520698, 5449.427810079885, 5512.649400896646, 4166.0, 4998.725070121032, 5099.5410413063, 5402.324709411324, 5437.972810135924, 4435.179491794029, 4741.691790033637, 5204.895902082013, 5385.596080293915, 5415.943890941228, 4764.857054288377, 4920.311156438173, 5150.690958542499, 5418.918716219338, 5453.754581043783], "value": 3975.1765859779107, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.5233574818886, 722.9710811567534, 785.9528428294299, 827.7475370012146, 830.1490971753955, 13803.102798318103, 15481.07113573185, 15803.0, 15817.209139543034, 15817.971831830937, 13907.513237036706, 14392.856071439282, 14401.0, 14404.783928241084, 14796.112233265985, 718.9748358807443, 743.9516431431939, 749.2694622742837, 891.1534042659483, 902.1835239108584, 902.1294450854924, 907.9727608171765, 914.1681070226063, 917.1516347378681, 917.2982668258787, 961.8124465729186, 981.7938232971085, 983.9360441571275, 984.2913102566141, 986.6694657289817, 754.811297175706, 777.6694904665494, 784.9921500784976, 979.3291595257272, 980.1570649241611, 578.5949835115437, 583.3233449198951, 692.944564434844, 714.5855403865745, 716.4018044932487, 15240.46658366963, 15278.847211527853, 15303.311350989232, 15309.157996310198, 15319.923400382926, 13645.727085458235, 13803.654908627264, 13857.026419895466, 13920.37358318878, 14202.608826028329, 12851.871481285161, 12900.870991290154, 12909.0, 13033.44753153659, 13483.66290842727, 4471.150481408522, 4522.29904364824, 4552.749598772066, 4716.174669432836, 4716.716996980193, 109942.0, 113050.0, 115203.0, 115215.84784152135, 116433.83566164314, 9256.259499240045, 10776.461176941122, 10968.670939871816, 11064.668059958214, 11167.0, 22090.88954555217, 22132.114715411524, 22190.889045554668, 22205.0, 22438.775612243997, 7528.774136775905, 7567.091948966159, 7573.697052117854, 7633.961830190925, 7733.033370828646, 4259.297215959332, 4309.733591824822, 4330.133973205308, 4348.673849461303, 4426.955730442719, 3354.8658053677577, 3438.6561343865415, 3460.0, 3481.164520515058, 3492.5285086512995, 556.8385168301228, 615.8645098078416, 627.8869803435334, 629.0878226571398, 632.3139393757824, 597.3219407029367, 608.2245137449787, 630.9810705678836, 642.8103709405736, 658.7595527632476, 8051.436399452099, 8208.835823283502, 8809.471431714117, 8832.249258813074, 8903.777405564913, 605.2585582661293, 628.202183227308, 634.8730253949136, 647.9481641468672, 648.1120864415781, 4104.322786740214, 4420.734755914656, 4423.336499525032, 4430.3575981483145, 4549.909001819945, 158255.0, 159478.0, 159729.0, 160434.0, 164582.0, 97127.0287297118, 132513.0123048155, 135298.3235083818, 135910.32044839713, 137536.99704023855, 113016.30475542873, 118867.0, 119033.0, 119565.0, 119850.0, 8143.348532117535, 8168.795780105307, 8249.216324449168, 8584.484930903916, 8663.263622591898, 50954.243273346976, 61906.0, 64556.03165952608, 65021.0, 65236.67381663251, 90361.0, 91313.7171570716, 95566.0, 97147.51426242823, 100189.49905250427, 11618.709532261422, 11704.70738231551, 11764.0, 11769.293842369485, 11805.173637845268, 12289.52961143544, 12306.384680766105, 12332.81500777471, 12481.00151987857, 12571.182873113585, 11602.29240045373, 11675.88324116748, 11737.41312934367, 11754.29474231549, 11826.46780894879, 9774.609015639579, 11415.258008229439, 11729.530818767154, 11873.406329683654, 12277.079219058427, 11500.565016553914, 11572.18994670365, 11574.131940104695, 11707.941460292644, 12002.39988000579, 9432.528373581155, 9706.272029597667, 9978.451185184884, 10025.699229023141, 10352.792944141225, 8678.154938023708, 9048.909510904807, 9957.759504111516, 10072.345297555636, 10113.29206955506], "value": 7639.37409855334, "unit": "score"}], "name": "dromaeo_css", "value": 7204.745461964101, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 111.0, 112.0, 111.0, 113.0, 111.0, 112.0, 112.0, 111.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 114.0, 149.0, 130.0, 98.0, 135.0, 163.0, 175.0, 111.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 256.0, 192.0, 186.0, 181.0, 184.0, 183.0, 180.0, 184.0, 181.0], "value": 192.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 89.0, 61.0, 58.0, 62.0, 58.0, 81.0, 57.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 92.0, 69.0, 67.0, 68.0, 92.0, 66.0, 67.0, 91.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 84.0, 86.0, 84.0, 88.0, 129.0, 88.0, 84.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 136.0, 93.0, 93.0, 93.0, 93.0, 93.0, 130.0, 93.0, 92.0], "value": 104.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 79.0, 79.0, 80.0, 99.0, 128.0, 135.0, 80.0, 84.0], "value": 92.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 53.0, 52.0, 55.0, 54.0, 53.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 76.0, 81.0, 74.0, 67.0, 69.0, 70.0, 70.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 87.0, 87.0, 89.0, 88.0, 85.0, 85.0, 86.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 144.0, 164.0, 165.0, 157.0, 152.0, 180.0, 166.0, 187.0, 148.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 170.0, 172.0, 171.0, 172.0, 173.0, 171.0, 171.0, 168.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 68.0, 69.0, 69.0, 67.0, 68.0, 68.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1481.3000000000002, "alertThreshold": 2.0}]} 16:05:17 INFO - Return code: 0 16:05:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:17 INFO - # TBPL SUCCESS # 16:05:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:05:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:05:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:05:17 INFO - 2016-10-25 16:05:17.313 screenresolution[1964:17283] starting screenresolution argv=screenresolution get 16:05:17 INFO - 2016-10-25 16:05:17.316 screenresolution[1964:17283] Display 0: 1600x1200x32@60 16:05:17 INFO - 2016-10-25 16:05:17.322 screenresolution[1965:17286] starting screenresolution argv=screenresolution list 16:05:17 INFO - Available Modes on Display 0 16:05:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:05:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:05:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:05:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:05:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:05:17 INFO - Intel Iris: 16:05:17 INFO - Chipset Model: Intel Iris 16:05:17 INFO - Type: GPU 16:05:17 INFO - Bus: Built-In 16:05:17 INFO - VRAM (Dynamic, Max): 1536 MB 16:05:17 INFO - Vendor: Intel (0x8086) 16:05:17 INFO - Device ID: 0x0a2e 16:05:17 INFO - Revision ID: 0x0009 16:05:17 INFO - Displays: 16:05:17 INFO - Display: 16:05:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:05:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:05:17 INFO - Display Serial Number: 1600x1200 60 16:05:17 INFO - Main Display: Yes 16:05:17 INFO - Mirror: Off 16:05:17 INFO - Online: Yes 16:05:17 INFO - Rotation: Supported 16:05:17 INFO - Return code: 0 16:05:17 INFO - Running post-action listener: _resource_record_post_action 16:05:17 INFO - [mozharness: 2016-10-25 23:05:17.556181Z] Finished run-tests step (success) 16:05:17 INFO - Running post-run listener: _resource_record_post_run 16:05:17 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 598016; Write bytes: 707363328; Read time: 5; Write time: 1979 16:05:17 INFO - TinderboxPrint: CPU usage
53.6% 16:05:17 INFO - TinderboxPrint: I/O read bytes / time
598,016 / 5 16:05:17 INFO - TinderboxPrint: I/O write bytes / time
707,363,328 / 1,979 16:05:17 INFO - TinderboxPrint: CPU idle
2,250.9 (46.4%) 16:05:17 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 16:05:17 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 16:05:17 INFO - TinderboxPrint: Swap in / out
405,475,328 / 0 16:05:17 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399626752; Write bytes: 394245632; Read time: 29859; Write time: 808 16:05:17 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 557056; Write bytes: 298503168; Read time: 4; Write time: 1123 16:05:18 INFO - Running post-run listener: _upload_blobber_files 16:05:18 INFO - Blob upload gear active. 16:05:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:18 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 16:05:18 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'] 16:05:18 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 16:05:18 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:20 INFO - (blobuploader) - INFO - Done attempting. 16:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:05:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:21 INFO - (blobuploader) - INFO - Done attempting. 16:05:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:23 INFO - (blobuploader) - INFO - Done attempting. 16:05:23 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:23 INFO - Return code: 0 16:05:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0194e39d80cafae0c324df3c7fc40cc037a9eec7f67fa40d10d9e05aceab14900491b9080364966e4dcf7ee0bb93044f4af5ad6033ed2fddc72401c836c88840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27fb7cc622892c30b25f00350edbc4f7ee4fa780a5de94e9725d88e5b2de7450911ce272ce8ef3887bfcf94a2220a506c76cd0aec494db2eddecbf74df25991f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/286eb27871d09b567b1176b2f734496d90697386abc1beddb48b827a764ce4dbb16a9b4dabbade9060c5ead819ae7dc386b4ed36e1a2e3d3e7fda74161155777"} 16:05:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:23 INFO - Contents: 16:05:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0194e39d80cafae0c324df3c7fc40cc037a9eec7f67fa40d10d9e05aceab14900491b9080364966e4dcf7ee0bb93044f4af5ad6033ed2fddc72401c836c88840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27fb7cc622892c30b25f00350edbc4f7ee4fa780a5de94e9725d88e5b2de7450911ce272ce8ef3887bfcf94a2220a506c76cd0aec494db2eddecbf74df25991f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/286eb27871d09b567b1176b2f734496d90697386abc1beddb48b827a764ce4dbb16a9b4dabbade9060c5ead819ae7dc386b4ed36e1a2e3d3e7fda74161155777"} 16:05:23 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:23 INFO - Copying logs to upload dir... 16:05:23 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.485950 ========= master_lag: 1.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-25 16:05:24.971135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 16:05:24.976129) ========= 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.MyPhAdDVuH/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.lwybFe5tpU/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0194e39d80cafae0c324df3c7fc40cc037a9eec7f67fa40d10d9e05aceab14900491b9080364966e4dcf7ee0bb93044f4af5ad6033ed2fddc72401c836c88840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27fb7cc622892c30b25f00350edbc4f7ee4fa780a5de94e9725d88e5b2de7450911ce272ce8ef3887bfcf94a2220a506c76cd0aec494db2eddecbf74df25991f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/286eb27871d09b567b1176b2f734496d90697386abc1beddb48b827a764ce4dbb16a9b4dabbade9060c5ead819ae7dc386b4ed36e1a2e3d3e7fda74161155777"} build_url:https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/GvMgaLy6RxqTfcFbxgICwg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0194e39d80cafae0c324df3c7fc40cc037a9eec7f67fa40d10d9e05aceab14900491b9080364966e4dcf7ee0bb93044f4af5ad6033ed2fddc72401c836c88840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27fb7cc622892c30b25f00350edbc4f7ee4fa780a5de94e9725d88e5b2de7450911ce272ce8ef3887bfcf94a2220a506c76cd0aec494db2eddecbf74df25991f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/286eb27871d09b567b1176b2f734496d90697386abc1beddb48b827a764ce4dbb16a9b4dabbade9060c5ead819ae7dc386b4ed36e1a2e3d3e7fda74161155777"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 16:05:25.009703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 16:05:25.010029) ========= 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.MyPhAdDVuH/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.lwybFe5tpU/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.005346 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 16:05:25.058364) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 16:05:25.058665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 16:05:25.058990) ========= ========= Total master_lag: 1.47 =========