builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0111 starttime: 1487988670.86 results: success (0) buildid: 20170224164222 builduid: ffd4f137d91f4a478ae4bc67f4ee898f revision: c3ea1dcedccd25a6620c82e6e2d7b9d602865057 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:10.855374) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:10.855782) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:10.856084) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:11.011110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:11.011409) ========= 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.39VOq46vRp/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.sm6vHZzIiC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 --2017-02-24 18:11:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.7M=0.001s 2017-02-24 18:11:11 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.482848 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:11.519917) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:11.520276) ========= 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.39VOq46vRp/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.sm6vHZzIiC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.019343 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:11.571903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-24 18:11:11.572294) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3ea1dcedccd25a6620c82e6e2d7b9d602865057 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c3ea1dcedccd25a6620c82e6e2d7b9d602865057 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39VOq46vRp/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.sm6vHZzIiC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 2017-02-24 18:11:11,660 truncating revision to first 12 chars 2017-02-24 18:11:11,660 Setting DEBUG logging. 2017-02-24 18:11:11,660 attempt 1/10 2017-02-24 18:11:11,660 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3ea1dcedccd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-24 18:11:13,153 unpacking tar archive at: mozilla-central-c3ea1dcedccd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.740414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-24 18:11:13.336898) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:13.337239) ========= script_repo_revision: c3ea1dcedccd25a6620c82e6e2d7b9d602865057 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:13.337628) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:13.337902) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 18:11:13.357814) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-02-24 18:11:13.358165) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39VOq46vRp/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.sm6vHZzIiC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:11:13 INFO - MultiFileLogger online at 20170224 18:11:13 in /builds/slave/test 18:11:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:11:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:13 INFO - {'append_to_log': False, 18:11:13 INFO - 'base_work_dir': '/builds/slave/test', 18:11:13 INFO - 'blob_upload_branch': 'Firefox', 18:11:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:13 INFO - 'branch': 'Firefox', 18:11:13 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:13 INFO - 'config_files': ('talos/mac_config.py',), 18:11:13 INFO - 'default_actions': ('clobber', 18:11:13 INFO - 'read-buildbot-config', 18:11:13 INFO - 'download-and-extract', 18:11:13 INFO - 'populate-webroot', 18:11:13 INFO - 'create-virtualenv', 18:11:13 INFO - 'install', 18:11:13 INFO - 'run-tests'), 18:11:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:13 INFO - 'download_minidump_stackwalk': True, 18:11:13 INFO - 'download_symbols': 'ondemand', 18:11:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:11:13 INFO - 'tooltool.py': '/tools/tooltool.py', 18:11:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:11:13 INFO - '/tools/misc-python/virtualenv.py')}, 18:11:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:13 INFO - 'gecko_profile': False, 18:11:13 INFO - 'gecko_profile_interval': 0, 18:11:13 INFO - 'installer_path': 'installer.exe', 18:11:13 INFO - 'log_level': 'info', 18:11:13 INFO - 'log_name': 'talos', 18:11:13 INFO - 'log_to_console': True, 18:11:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:11:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:11:13 INFO - 'opt_config_files': (), 18:11:13 INFO - 'pip_index': False, 18:11:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:11:13 INFO - 'cmd': ('bash', 18:11:13 INFO - '-c', 18:11:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:11:13 INFO - 'enabled': True, 18:11:13 INFO - 'halt_on_failure': False, 18:11:13 INFO - 'name': 'check_screen_resolution'},), 18:11:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:11:13 INFO - 'cmd': ('bash', 18:11:13 INFO - '-c', 18:11:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:11:13 INFO - 'enabled': True, 18:11:13 INFO - 'halt_on_failure': False, 18:11:13 INFO - 'name': 'check_screen_resolution'},), 18:11:13 INFO - 'run_cmd_checks_enabled': True, 18:11:13 INFO - 'suite': 'dromaeojs', 18:11:13 INFO - 'system_bits': '32', 18:11:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:13 INFO - 'title': 't-yosemite-r7-0111', 18:11:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:13 INFO - 'use_talos_json': True, 18:11:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:13 INFO - 'work_dir': 'build'} 18:11:13 INFO - [mozharness: 2017-02-25 02:11:13.575713Z] Running clobber step. 18:11:13 INFO - Running pre-action listener: _resource_record_pre_action 18:11:13 INFO - Running main action method: clobber 18:11:13 INFO - rmtree: /builds/slave/test/build 18:11:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:15 INFO - Running post-action listener: _resource_record_post_action 18:11:15 INFO - [mozharness: 2017-02-25 02:11:15.208471Z] Finished clobber step (success) 18:11:15 INFO - [mozharness: 2017-02-25 02:11:15.208629Z] Running read-buildbot-config step. 18:11:15 INFO - Running pre-action listener: _resource_record_pre_action 18:11:15 INFO - Running main action method: read_buildbot_config 18:11:15 INFO - Using buildbot properties: 18:11:15 INFO - { 18:11:15 INFO - "project": "", 18:11:15 INFO - "product": "firefox", 18:11:15 INFO - "script_repo_revision": "production", 18:11:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:11:15 INFO - "repository": "", 18:11:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:11:15 INFO - "buildid": "20170224164222", 18:11:15 INFO - "pgo_build": "False", 18:11:15 INFO - "basedir": "/builds/slave/test", 18:11:15 INFO - "buildnumber": 54, 18:11:15 INFO - "slavename": "t-yosemite-r7-0111", 18:11:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:11:15 INFO - "platform": "yosemite_r7", 18:11:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:11:15 INFO - "branch": "mozilla-central", 18:11:15 INFO - "repo_path": "mozilla-central", 18:11:15 INFO - "revision": "c3ea1dcedccd25a6620c82e6e2d7b9d602865057", 18:11:15 INFO - "stage_platform": "macosx64", 18:11:15 INFO - "builduid": "ffd4f137d91f4a478ae4bc67f4ee898f", 18:11:15 INFO - "slavebuilddir": "test" 18:11:15 INFO - } 18:11:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:11:15 INFO - Running post-action listener: _resource_record_post_action 18:11:15 INFO - [mozharness: 2017-02-25 02:11:15.212012Z] Finished read-buildbot-config step (success) 18:11:15 INFO - [mozharness: 2017-02-25 02:11:15.212133Z] Running download-and-extract step. 18:11:15 INFO - Running pre-action listener: _resource_record_pre_action 18:11:15 INFO - Running main action method: download_and_extract 18:11:15 INFO - mkdir: /builds/slave/test/build/tests 18:11:15 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')]} 18:11:15 INFO - trying https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:11:15 INFO - Downloading https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:11:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 18:11:16 INFO - Downloaded 1199 bytes. 18:11:16 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:11:16 INFO - Using the following test package requirements: 18:11:16 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:11:16 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:11:16 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:11:16 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'jsshell-mac.zip'], 18:11:16 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:11:16 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:11:16 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:11:16 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:11:16 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:11:16 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:11:16 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:11:16 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:11:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:11:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:11:16 INFO - Fetch https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:11:18 INFO - Expected file size: 51455388 18:11:18 INFO - Obtained file size: 51455388 18:11:33 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:11:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:11:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:11:33 INFO - Fetch https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:11:36 INFO - Expected file size: 51455388 18:11:36 INFO - Obtained file size: 51455388 18:11:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:11:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:11:50 INFO - Fetch https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:11:51 INFO - Expected file size: 14404306 18:11:51 INFO - Obtained file size: 14404306 18:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:52 INFO - trying https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:11:52 INFO - Downloading https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:11:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:11:54 INFO - Downloaded 74960889 bytes. 18:11:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:11:54 INFO - mkdir: /builds/slave/test/properties 18:11:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:54 INFO - Contents: 18:11:54 INFO - build_url:https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:11:54 INFO - Running post-action listener: _resource_record_post_action 18:11:54 INFO - Running post-action listener: set_extra_try_arguments 18:11:54 INFO - [mozharness: 2017-02-25 02:11:54.901677Z] Finished download-and-extract step (success) 18:11:54 INFO - [mozharness: 2017-02-25 02:11:54.901792Z] Running populate-webroot step. 18:11:54 INFO - Running pre-action listener: _resource_record_pre_action 18:11:54 INFO - Running main action method: populate_webroot 18:11:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:54 INFO - '--tppagecycles', 18:11:54 INFO - '7'], 18:11:54 INFO - 'tests': ['tsvgm']}}, 18:11:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:11:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:11:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:11:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:11:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['damp', 'tps']}, 18:11:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'tests': ['damp', 'tps']}, 18:11:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['dromaeo_dom']}, 18:11:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['basic_compositor_video']}, 18:11:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:11:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['a11yr', 18:11:54 INFO - 'ts_paint', 18:11:54 INFO - 'tpaint', 18:11:54 INFO - 'sessionrestore', 18:11:54 INFO - 'sessionrestore_no_auto_restore', 18:11:54 INFO - 'tabpaint']}, 18:11:54 INFO - 'other-e10s': {'tests': ['a11yr', 18:11:54 INFO - 'ts_paint', 18:11:54 INFO - 'tpaint', 18:11:54 INFO - 'sessionrestore', 18:11:54 INFO - 'sessionrestore_no_auto_restore', 18:11:54 INFO - 'tabpaint']}, 18:11:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:11:54 INFO - 'ts_paint', 18:11:54 INFO - 'tpaint', 18:11:54 INFO - 'sessionrestore', 18:11:54 INFO - 'sessionrestore_no_auto_restore', 18:11:54 INFO - 'tabpaint']}, 18:11:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:11:54 INFO - 'ts_paint', 18:11:54 INFO - 'tpaint', 18:11:54 INFO - 'sessionrestore', 18:11:54 INFO - 'sessionrestore_no_auto_restore', 18:11:54 INFO - 'tabpaint']}, 18:11:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['a11yr', 18:11:54 INFO - 'ts_paint', 18:11:54 INFO - 'tpaint', 18:11:54 INFO - 'sessionrestore', 18:11:54 INFO - 'sessionrestore_no_auto_restore', 18:11:54 INFO - 'tabpaint']}, 18:11:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['a11yr', 18:11:54 INFO - 'ts_paint', 18:11:54 INFO - 'tpaint', 18:11:54 INFO - 'sessionrestore', 18:11:54 INFO - 'sessionrestore_no_auto_restore', 18:11:54 INFO - 'tabpaint']}, 18:11:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['tsvgx', 18:11:54 INFO - 'tsvgr_opacity', 18:11:54 INFO - 'tart', 18:11:54 INFO - 'tscrollx', 18:11:54 INFO - 'cart', 18:11:54 INFO - 'tsvg_static']}, 18:11:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:11:54 INFO - 'tsvgr_opacity', 18:11:54 INFO - 'tart', 18:11:54 INFO - 'tscrollx', 18:11:54 INFO - 'cart', 18:11:54 INFO - 'tsvg_static']}, 18:11:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'talos_options': ['--disable-e10s'], 18:11:54 INFO - 'tests': ['tp5o']}, 18:11:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:11:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'talos_options': ['--disable-e10s', 18:11:54 INFO - '--xperf_path', 18:11:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:54 INFO - 'tests': ['tp5n']}, 18:11:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:11:54 INFO - 'talos_options': ['--xperf_path', 18:11:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:54 INFO - 'tests': ['tp5n']}}, 18:11:54 INFO - 'talos.zip': {'path': '', 18:11:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:54 INFO - Running post-action listener: _resource_record_post_action 18:11:54 INFO - [mozharness: 2017-02-25 02:11:54.919551Z] Finished populate-webroot step (success) 18:11:54 INFO - [mozharness: 2017-02-25 02:11:54.919677Z] Running create-virtualenv step. 18:11:54 INFO - Running pre-action listener: _resource_record_pre_action 18:11:54 INFO - Running main action method: create_virtualenv 18:11:54 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:54 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 18:11:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:55 INFO - Using real prefix '/tools/python27' 18:11:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:55 INFO - Installing distribute.............................................................................................................................................................................................done. 18:11:58 INFO - Installing pip.................done. 18:11:58 INFO - Return code: 0 18:11:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:11:58 INFO - Reading from file tmpfile_stdout 18:11:58 INFO - Output received: 18:11:58 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) 18:11:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:11:58 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')]} 18:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:58 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')]} 18:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:58 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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:11:58 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 18:11:58 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 18:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:58 INFO - 'HOME': '/Users/cltbld', 18:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:58 INFO - 'LOGNAME': 'cltbld', 18:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:58 INFO - 'MOZ_NO_REMOTE': '1', 18:11:58 INFO - 'NO_EM_RESTART': '1', 18:11:58 INFO - 'PAGER': '/bin/cat', 18:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:58 INFO - 'PWD': '/builds/slave/test', 18:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:58 INFO - 'SHELL': '/bin/bash', 18:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:11:58 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:58 INFO - 'USER': 'cltbld', 18:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:58 INFO - 'XPC_FLAGS': '0x0', 18:11:58 INFO - 'XPC_SERVICE_NAME': '0', 18:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:59 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 18:11:59 INFO - Cleaning up... 18:11:59 INFO - Return code: 0 18:11:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:11:59 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')]} 18:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:59 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')]} 18:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:59 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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:11:59 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 18:11:59 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 18:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:59 INFO - 'HOME': '/Users/cltbld', 18:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:59 INFO - 'LOGNAME': 'cltbld', 18:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:59 INFO - 'MOZ_NO_REMOTE': '1', 18:11:59 INFO - 'NO_EM_RESTART': '1', 18:11:59 INFO - 'PAGER': '/bin/cat', 18:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:59 INFO - 'PWD': '/builds/slave/test', 18:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:59 INFO - 'SHELL': '/bin/bash', 18:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:11:59 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:59 INFO - 'USER': 'cltbld', 18:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:59 INFO - 'XPC_FLAGS': '0x0', 18:11:59 INFO - 'XPC_SERVICE_NAME': '0', 18:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:59 INFO - Downloading/unpacking psutil>=3.1.1 18:11: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 18:11: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 18:11: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 18:11: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 18:11: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 18:11: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 18:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:03 INFO - Installing collected packages: psutil 18:12:03 INFO - Running setup.py install for psutil 18:12:03 INFO - building 'psutil._psutil_osx' extension 18:12:03 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 18:12:03 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 18:12:03 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 18:12:03 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 18:12:03 INFO - building 'psutil._psutil_posix' extension 18:12:03 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 18:12:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:12:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:12:03 INFO - ^ 18:12:04 INFO - 1 warning generated. 18:12:04 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 18:12:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:04 INFO - Successfully installed psutil 18:12:04 INFO - Cleaning up... 18:12:04 INFO - Return code: 0 18:12:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:12:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:04 INFO - 'HOME': '/Users/cltbld', 18:12:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:04 INFO - 'LOGNAME': 'cltbld', 18:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:04 INFO - 'MOZ_NO_REMOTE': '1', 18:12:04 INFO - 'NO_EM_RESTART': '1', 18:12:04 INFO - 'PAGER': '/bin/cat', 18:12:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:04 INFO - 'PWD': '/builds/slave/test', 18:12:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:04 INFO - 'SHELL': '/bin/bash', 18:12:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:04 INFO - 'USER': 'cltbld', 18:12:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:04 INFO - 'XPC_FLAGS': '0x0', 18:12:04 INFO - 'XPC_SERVICE_NAME': '0', 18:12:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:04 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 18:12:04 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 18:12:04 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 18:12:04 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 18:12:04 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 18:12:04 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 18:12:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:07 INFO - Installing collected packages: mozsystemmonitor 18:12:07 INFO - Running setup.py install for mozsystemmonitor 18:12:07 INFO - Successfully installed mozsystemmonitor 18:12:07 INFO - Cleaning up... 18:12:07 INFO - Return code: 0 18:12:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:07 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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:07 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 18:12:07 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 18:12:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:07 INFO - 'HOME': '/Users/cltbld', 18:12:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:07 INFO - 'LOGNAME': 'cltbld', 18:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:07 INFO - 'MOZ_NO_REMOTE': '1', 18:12:07 INFO - 'NO_EM_RESTART': '1', 18:12:07 INFO - 'PAGER': '/bin/cat', 18:12:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:07 INFO - 'PWD': '/builds/slave/test', 18:12:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:07 INFO - 'SHELL': '/bin/bash', 18:12:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:07 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:07 INFO - 'USER': 'cltbld', 18:12:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:07 INFO - 'XPC_FLAGS': '0x0', 18:12:07 INFO - 'XPC_SERVICE_NAME': '0', 18:12:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:07 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:10 INFO - warning: no files found matching '*.txt' 18:12:10 INFO - no previously-included directories found matching 'build' 18:12:10 INFO - no previously-included directories found matching 'dist' 18:12:10 INFO - no previously-included directories found matching '.git*' 18:12:10 INFO - Installing collected packages: jsonschema, functools32 18:12:10 INFO - Running setup.py install for functools32 18:12:10 INFO - warning: no files found matching '*.txt' 18:12:10 INFO - no previously-included directories found matching 'build' 18:12:10 INFO - no previously-included directories found matching 'dist' 18:12:10 INFO - no previously-included directories found matching '.git*' 18:12:10 INFO - Successfully installed jsonschema functools32 18:12:10 INFO - Cleaning up... 18:12:11 INFO - Return code: 0 18:12:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:11 INFO - 'HOME': '/Users/cltbld', 18:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:11 INFO - 'LOGNAME': 'cltbld', 18:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:11 INFO - 'MOZ_NO_REMOTE': '1', 18:12:11 INFO - 'NO_EM_RESTART': '1', 18:12:11 INFO - 'PAGER': '/bin/cat', 18:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:11 INFO - 'PWD': '/builds/slave/test', 18:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:11 INFO - 'SHELL': '/bin/bash', 18:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:11 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:11 INFO - 'USER': 'cltbld', 18:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:11 INFO - 'XPC_FLAGS': '0x0', 18:12:11 INFO - 'XPC_SERVICE_NAME': '0', 18:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:11 INFO - Cleaning up... 18:12:11 INFO - Return code: 0 18:12:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:11 INFO - 'HOME': '/Users/cltbld', 18:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:11 INFO - 'LOGNAME': 'cltbld', 18:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:11 INFO - 'MOZ_NO_REMOTE': '1', 18:12:11 INFO - 'NO_EM_RESTART': '1', 18:12:11 INFO - 'PAGER': '/bin/cat', 18:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:11 INFO - 'PWD': '/builds/slave/test', 18:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:11 INFO - 'SHELL': '/bin/bash', 18:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:11 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:11 INFO - 'USER': 'cltbld', 18:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:11 INFO - 'XPC_FLAGS': '0x0', 18:12:11 INFO - 'XPC_SERVICE_NAME': '0', 18:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:11 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:14 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:15 INFO - Downloading docopt-0.6.1.tar.gz 18:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:15 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:15 INFO - Running setup.py install for blobuploader 18:12:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:15 INFO - Running setup.py install for requests 18:12:16 INFO - Running setup.py install for docopt 18:12:16 INFO - Successfully installed blobuploader requests docopt 18:12:16 INFO - Cleaning up... 18:12:16 INFO - Return code: 0 18:12:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:16 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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:16 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 18:12:16 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 18:12:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:16 INFO - 'HOME': '/Users/cltbld', 18:12:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:16 INFO - 'LOGNAME': 'cltbld', 18:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:16 INFO - 'MOZ_NO_REMOTE': '1', 18:12:16 INFO - 'NO_EM_RESTART': '1', 18:12:16 INFO - 'PAGER': '/bin/cat', 18:12:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:16 INFO - 'PWD': '/builds/slave/test', 18:12:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:16 INFO - 'SHELL': '/bin/bash', 18:12:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:16 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:16 INFO - 'USER': 'cltbld', 18:12:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:16 INFO - 'XPC_FLAGS': '0x0', 18:12:16 INFO - 'XPC_SERVICE_NAME': '0', 18:12:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DLxe2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-P3Ljng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-d1jsPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-230Skx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wdZO0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-w8Qiq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xYRNUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-5SBHce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YT7V8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7swjN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-kUi50N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-K0WQdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WabiMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0Oa0QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-h5IFaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-tVgSXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VfpXsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:18 INFO - Running setup.py install for manifestparser 18:12:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:18 INFO - Running setup.py install for mozcrash 18:12:18 INFO - Running setup.py install for mozdebug 18:12:18 INFO - Running setup.py install for mozdevice 18:12:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozfile 18:12:19 INFO - Running setup.py install for mozhttpd 18:12:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozinfo 18:12:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozInstall 18:12:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for mozleak 18:12:19 INFO - Running setup.py install for mozlog 18:12:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:19 INFO - Running setup.py install for moznetwork 18:12:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Running setup.py install for mozprocess 18:12:20 INFO - Running setup.py install for mozprofile 18:12:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Running setup.py install for mozrunner 18:12:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Running setup.py install for mozscreenshot 18:12:20 INFO - Running setup.py install for moztest 18:12:20 INFO - Running setup.py install for mozversion 18:12:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:21 INFO - Cleaning up... 18:12:21 INFO - Return code: 0 18:12:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:21 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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:21 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 18:12:21 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 18:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:21 INFO - 'HOME': '/Users/cltbld', 18:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:21 INFO - 'LOGNAME': 'cltbld', 18:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:21 INFO - 'MOZ_NO_REMOTE': '1', 18:12:21 INFO - 'NO_EM_RESTART': '1', 18:12:21 INFO - 'PAGER': '/bin/cat', 18:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:21 INFO - 'PWD': '/builds/slave/test', 18:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:21 INFO - 'SHELL': '/bin/bash', 18:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:21 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:21 INFO - 'USER': 'cltbld', 18:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:21 INFO - 'XPC_FLAGS': '0x0', 18:12:21 INFO - 'XPC_SERVICE_NAME': '0', 18:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wCaDVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8n3Cnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-QZp37A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-PVgc6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-AmzbPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8o7LuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:21 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)) 18:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DQSFzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-IN8nLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0n33d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YifD6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EpOorQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Z9VRYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Jp9MNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-nnQ3BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DM5Gxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:22 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)) 18:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-z2Y5k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 18:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RJQ5Fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:23 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)) 18:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:23 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)) 18:12:23 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:23 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:25 INFO - Downloading blessings-1.6.tar.gz 18:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:25 INFO - Installing collected packages: blessings 18:12:25 INFO - Running setup.py install for blessings 18:12:26 INFO - Successfully installed blessings 18:12:26 INFO - Cleaning up... 18:12:26 INFO - Return code: 0 18:12:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:26 INFO - Reading from file tmpfile_stdout 18:12:26 INFO - Current package versions: 18:12:26 INFO - blessings == 1.6 18:12:26 INFO - blobuploader == 1.2.4 18:12:26 INFO - docopt == 0.6.1 18:12:26 INFO - functools32 == 3.2.3-2 18:12:26 INFO - jsonschema == 2.5.1 18:12:26 INFO - manifestparser == 1.1 18:12:26 INFO - mozInstall == 1.12 18:12:26 INFO - mozcrash == 1.0 18:12:26 INFO - mozdebug == 0.1 18:12:26 INFO - mozdevice == 0.49 18:12:26 INFO - mozfile == 1.2 18:12:26 INFO - mozhttpd == 0.7 18:12:26 INFO - mozinfo == 0.9 18:12:26 INFO - mozleak == 0.1 18:12:26 INFO - mozlog == 3.4 18:12:26 INFO - moznetwork == 0.27 18:12:26 INFO - mozprocess == 0.25 18:12:26 INFO - mozprofile == 0.28 18:12:26 INFO - mozrunner == 6.13 18:12:26 INFO - mozscreenshot == 0.1 18:12:26 INFO - mozsystemmonitor == 0.3 18:12:26 INFO - moztest == 0.8 18:12:26 INFO - mozversion == 1.4 18:12:26 INFO - psutil == 3.1.1 18:12:26 INFO - requests == 1.2.3 18:12:26 INFO - wsgiref == 0.1.2 18:12:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:26 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 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:26 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 18:12:26 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 18:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:26 INFO - 'HOME': '/Users/cltbld', 18:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:26 INFO - 'LOGNAME': 'cltbld', 18:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:26 INFO - 'MOZ_NO_REMOTE': '1', 18:12:26 INFO - 'NO_EM_RESTART': '1', 18:12:26 INFO - 'PAGER': '/bin/cat', 18:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:26 INFO - 'PWD': '/builds/slave/test', 18:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:26 INFO - 'SHELL': '/bin/bash', 18:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:26 INFO - 'USER': 'cltbld', 18:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:26 INFO - 'XPC_FLAGS': '0x0', 18:12:26 INFO - 'XPC_SERVICE_NAME': '0', 18:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 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)) 18:12:26 INFO - Cleaning up... 18:12:26 INFO - Return code: 0 18:12:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ddeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe48aca62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, '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.39VOq46vRp/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.sm6vHZzIiC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 18:12:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:26 INFO - 'HOME': '/Users/cltbld', 18:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:26 INFO - 'LOGNAME': 'cltbld', 18:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:26 INFO - 'MOZ_NO_REMOTE': '1', 18:12:26 INFO - 'NO_EM_RESTART': '1', 18:12:26 INFO - 'PAGER': '/bin/cat', 18:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:26 INFO - 'PWD': '/builds/slave/test', 18:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:26 INFO - 'SHELL': '/bin/bash', 18:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:26 INFO - 'USER': 'cltbld', 18:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:26 INFO - 'XPC_FLAGS': '0x0', 18:12:26 INFO - 'XPC_SERVICE_NAME': '0', 18:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:26 INFO - Cleaning up... 18:12:26 INFO - Return code: 0 18:12:26 INFO - Running post-action listener: _resource_record_post_action 18:12:26 INFO - Running post-action listener: _start_resource_monitoring 18:12:26 INFO - Starting resource monitoring. 18:12:26 INFO - [mozharness: 2017-02-25 02:12:26.974745Z] Finished create-virtualenv step (success) 18:12:26 INFO - [mozharness: 2017-02-25 02:12:26.975729Z] Running install step. 18:12:26 INFO - Running pre-action listener: _resource_record_pre_action 18:12:26 INFO - Running main action method: install 18:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:27 INFO - Reading from file tmpfile_stdout 18:12:27 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:27 INFO - Reading from file tmpfile_stdout 18:12:27 INFO - Output received: 18:12:27 INFO - Usage: mozinstall [options] installer 18:12:27 INFO - Options: 18:12:27 INFO - -h, --help show this help message and exit 18:12:27 INFO - -d DEST, --destination=DEST 18:12:27 INFO - Directory to install application into. [default: 18:12:27 INFO - "/builds/slave/test"] 18:12:27 INFO - --app=APP Application being installed. [default: firefox] 18:12:27 INFO - mkdir: /builds/slave/test/build/application 18:12:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:12:47 INFO - Reading from file tmpfile_stdout 18:12:47 INFO - Output received: 18:12:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:12:47 INFO - Running post-action listener: _resource_record_post_action 18:12:47 INFO - [mozharness: 2017-02-25 02:12:47.339022Z] Finished install step (success) 18:12:47 INFO - [mozharness: 2017-02-25 02:12:47.339168Z] Running run-tests step. 18:12:47 INFO - Running pre-action listener: _resource_record_pre_action 18:12:47 INFO - Running main action method: run_tests 18:12:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:12:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:12:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:12:47 INFO - 2017-02-24 18:12:47.354 screenresolution[1910:19109] starting screenresolution argv=screenresolution get 18:12:47 INFO - 2017-02-24 18:12:47.357 screenresolution[1910:19109] Display 0: 1600x1200x32@60 18:12:47 INFO - 2017-02-24 18:12:47.363 screenresolution[1911:19111] starting screenresolution argv=screenresolution list 18:12:47 INFO - Available Modes on Display 0 18:12:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:12:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:12:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:12:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:12:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:12:47 INFO - Intel Iris: 18:12:47 INFO - Chipset Model: Intel Iris 18:12:47 INFO - Type: GPU 18:12:47 INFO - Bus: Built-In 18:12:47 INFO - VRAM (Dynamic, Max): 1536 MB 18:12:47 INFO - Vendor: Intel (0x8086) 18:12:47 INFO - Device ID: 0x0a2e 18:12:47 INFO - Revision ID: 0x0009 18:12:47 INFO - Displays: 18:12:47 INFO - Display: 18:12:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:12:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:12:47 INFO - Display Serial Number: 1600x1200 60 18:12:47 INFO - Main Display: Yes 18:12:47 INFO - Mirror: Off 18:12:47 INFO - Online: Yes 18:12:47 INFO - Rotation: Supported 18:12:47 INFO - Return code: 0 18:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:47 INFO - Python 2.7.3 18:12:47 INFO - Return code: 0 18:12:47 INFO - grabbing minidump binary from tooltool 18:12: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')]} 18:12:47 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 0x103bbcd30>, '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 0x103ae3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bb1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:47 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 18:12:47 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 18:12:47 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 18:12:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:47 INFO - Return code: 0 18:12:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:12:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:47 INFO - ENV: RUST_BACKTRACE is now 1 18:12:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:12:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 18:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 18:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39VOq46vRp/Render', 18:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:47 INFO - 'HOME': '/Users/cltbld', 18:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:47 INFO - 'LOGNAME': 'cltbld', 18:12:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:47 INFO - 'MOZ_NO_REMOTE': '1', 18:12:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:47 INFO - 'NO_EM_RESTART': '1', 18:12:47 INFO - 'PAGER': '/bin/cat', 18:12:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:47 INFO - 'PWD': '/builds/slave/test', 18:12:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:47 INFO - 'RUST_BACKTRACE': '1', 18:12:47 INFO - 'SHELL': '/bin/bash', 18:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sm6vHZzIiC/Listeners', 18:12:47 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 18:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:47 INFO - 'USER': 'cltbld', 18:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:47 INFO - 'XPC_FLAGS': '0x0', 18:12:47 INFO - 'XPC_SERVICE_NAME': '0', 18:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 18:12:47 INFO - mozversion application_buildid: 20170224164222 18:12:47 INFO - mozversion application_changeset: c3ea1dcedccd25a6620c82e6e2d7b9d602865057 18:12:47 INFO - mozversion application_display_name: Nightly 18:12:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:47 INFO - mozversion application_name: Firefox 18:12:47 INFO - mozversion application_remotingname: firefox 18:12:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:12:47 INFO - mozversion application_vendor: Mozilla 18:12:47 INFO - mozversion application_version: 54.0a1 18:12:47 INFO - mozversion platform_buildid: 20170224164222 18:12:47 INFO - mozversion platform_changeset: c3ea1dcedccd25a6620c82e6e2d7b9d602865057 18:12:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:12:47 INFO - mozversion platform_version: 54.0a1 18:12:47 INFO - using testdate: 1487988767 18:12:47 INFO - actual date: 1487988767 18:12:47 INFO - starting webserver on 'localhost:53091' 18:12:47 INFO - SUITE-START | Running 2 tests 18:12:47 INFO - TEST-START | dromaeo_css 18:12:47 INFO - operating with platform_type : mac_ 18:12:47 INFO - Initialising browser for dromaeo_css test... 18:12:47 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmp7OWjrQ/profile http://localhost:53091/getInfo.html) 18:12:50 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 18:12:50 INFO - PROCESS | 1921 | colorDepth:24 18:12:50 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 18:12:50 INFO - PROCESS | 1921 | __metrics 18:12:50 INFO - PROCESS | 1921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:12:50 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:12:51 INFO - TEST-INFO | 1921: exit 0 18:12:51 INFO - Browser initialized. 18:12:51 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:51 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmp7OWjrQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:03 INFO - PROCESS | 1923 | RSS: Main: 231723008 18:13:03 INFO - PROCESS | 1923 | 18:16:05 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53091/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53091/tests/dromaeo/cssquery-ext.html) 18:16:09 INFO - PROCESS | 1923 | RSS: Main: 522870784 18:16:09 INFO - PROCESS | 1923 | 18:17:53 INFO - PROCESS | 1923 | ************************* 18:17:53 INFO - PROCESS | 1923 | A coding exception was thrown and uncaught in a Task. 18:17:53 INFO - PROCESS | 1923 | 18:17:53 ERROR - PROCESS | 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:17:53 INFO - PROCESS | 1923 | Full stack: 18:17:53 INFO - PROCESS | 1923 | ************************* 18:19:12 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53091/tests/dromaeo/cssquery-ext.html (next: http://localhost:53091/tests/dromaeo/cssquery-jquery.html) 18:19:13 INFO - PROCESS | 1923 | RSS: Main: 371445760 18:19:13 INFO - PROCESS | 1923 | 18:22:15 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53091/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53091/tests/dromaeo/cssquery-mootools.html) 18:22:16 INFO - PROCESS | 1923 | RSS: Main: 345513984 18:22:16 INFO - PROCESS | 1923 | 18:25:18 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53091/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53091/tests/dromaeo/cssquery-prototype.html) 18:25:19 INFO - PROCESS | 1923 | RSS: Main: 336904192 18:25:19 INFO - PROCESS | 1923 | 18:28:22 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53091/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53091/tests/dromaeo/cssquery-yui.html) 18:28:23 INFO - PROCESS | 1923 | RSS: Main: 338788352 18:28:23 INFO - PROCESS | 1923 | 18:31:26 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53091/tests/dromaeo/cssquery-yui.html (next: http://localhost:53091/tests/dromaeo/cssquery-dojo.html) 18:31:27 INFO - PROCESS | 1923 | RSS: Main: 474877952 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | __start_tp_report 18:31:27 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 18:31:27 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 18:31:27 INFO - PROCESS | 1923 | |i|pagename|runs| 18:31:27 INFO - PROCESS | 1923 | |0;dojo.html;3185.569948057013;3208.4866421372576;3215.5980502437196;3234.9191270218257;3244.2376041630214;5353.411124776271;5387.811426600069;5408.432114627966;5499.230107784908;5531.308586426697;5382.0312343778105;5407.405185429599;5418.647787893794;5428.75570599323;5437.564994800417;4914.926276105853;4939.703617782927;4958.380202474691;4983.8504844854715;5049.570786483153;3807.6953843692577;5052.545270925616;5060.696358218519;5061.240813877929;5083.542481176693;5061.797528098871;5065.696058236499;5071.847844564669;5073.046876952363;5074.8731281718065;5061.848144555669;5069.391672999227;5072.467390923936;5072.771725272371;5081.364829396325;4957.082939656175;5005.824796132154;5032.84901452957;5033.84898453047;5042.268871013719;5282.524572788429;5493;5510.91733623996;5515.227868098469;5516.034693928546;5970.761169553212;6006.21919150512;6009.278886533601;6016.368281330485;6033.9396606033815;5648.181013752983;5701.743421546041;5716.8856622867725;5743.54051675865;5796.13058041293;5340.305760251182;5358.062339090644;5385.919211211835;5389.865253368658;5397.379301380352;4323.567643235714;6179.536534759911;6193.752249909953;6212.316645169028;6243.594166379171;5887.293524777054;5898.705064746746;5900.881982360329;5912.704364781744;5912.852178695481;5809.854753631109;5886.1465087562065;5905.527557795366;6049;6054.485368743706;5684.602077854511;5813;5815.680137592389;5817.854553636193;5819.73811178498;4892.730899800546;5819.447152520504;5865.912011319833;5870.559708021916;5881.676507792027;4785.617150627942;4789.904201915942;4794.664373493822;4797.112534181188;4800.591949684316;4300.0969796343115;4306.655467562588;4323.416338794285;4363.629091527192;4366.12677464502;4356.6514678825615;5426.457354264621;5445.1015582429245;5518.503334699896;5554.2779438672715;6093.086037094383;6097.542684298695;6132.478739307209;6133.110698948627;6141.109539116893;5510.366307874566;5522.91715624266;5525.917111243334;5531.3915469298345;5537.1140617501005;4705.858824235278;4816.675414261106;5264.315638966987;5269.262303277545;5285.180796976475;6105.308121371101;6116.153172518083;6117.705611546597;6119.959572021111;6134.9079763803575;5746;5846.239988801429;5897.144913987534;5910.143029260819;5967.6419414835245;6017.759289628541;6173.290071641819;6173.660448675366;6241.8751624966335;6248.843778905381;5975.40245975399;5985.760569577168;5990.4908082813445;5993.921094203085;5999.1601175836295;5824.068149096124;5894.970525147518;6043.153958445909;6055.303640081271;6075.453209211192;4849.587785038311;5564.4713752193475;5694.629849059798;5718.656880587178;5737.881113182889;5683.60214784961;5693.629914055573;5697.3448053474385;5699.658020518782;5710.086386178192;5566.610337276352;5584.246126772996;5616.634918730269;5621.971890140523;5632.5212356950115;5568.693721845338;5749.281339832521;5775.220345253505;5818.8254352369495;5861.824145275648;5712.685802280915;5737.139429085671;5741.13882917549;5744.224529688606;5747.252857128547 18:31:27 INFO - PROCESS | 1923 | |1;ext.html;458.9586937175653;482.99517004829966;483.62760674280804;485.63819954134175;496.10205527994344;27310;27985.58021629677;28008.719912800865;28038.15885523435;28041.29896752577;28671;32424.837875810586;39268.41097383542;41466;43281.783591082;9096.590653420579;9296.302777291696;9302.02328755481;9305.720828375159;9306.674266400678;9186;9287.860682089775;9297.44215347078;9299.395539289959;9300.534973251344;6818.1136452261135;7430.331270185682;7609.961950190269;7634.312911837934;7659.425543084263;7304.269573042707;11034.393108379076;11287.943560282147;11313.151513636429;11427;11043.006129448308;11114.277571957797;11121.944390278079;11143.108551315876;11147.996680298813;30894.691053089406;38211.044723881845;38306.80846595749;38377.04057398559;38379.808100959315;3692.981535092334;3696.6379671987042;3708.072981754561;3715.7008420009906;3742.8315725792404;28921.42157156845;29207.853960730274;29559.81760729568;29974.400511989857;30609.234769130726;3055.9694403055905;3071.370369074357;3088.722015018659;3129.906102816919;3194.217416732892;367608;432387;433726;433731.83134084445;434222;55246.723766381714;55555;55616.16575751367;55646;55853.44146558564;47471.525284747404;60060.89786857439;60139;60192;60282.09576856351;12895.548655797002;13227.140235884637;15160.31778569967;15180;15431.38274469031;11835.526578936746;11976.101792365607;11983.34091624969;11984;12014.519419223308;16400.343986240656;16649;16677.16614169311;16679.332826686797;16718.665626687398;6133.570650054453;7019.754308599277;7042.295770422917;7098.68055937484;7102.92897071033;685.9862802743917;832.4713806732768;852.2925971443686;852.8018134521008;862.0689655172433;24454;27983.860080699873;28035.579466308023;28662.856685716437;29021.98423055183;1008.7125169326706;1049.3913530152493;1059.6026490066224;1062.3519653011656;1078.665613659768;11705.941470292593;14030.71938561223;14402.711945761235;14927.253637318308;15058.698826023417;550373;552098;552215;554878;555209;55288;55352.44647553554;55365.723171383885;55397.1690424644;55687.443125569036;1314.5859054397833;1355.9932200338935;1385.161977003898;1386.577093986344;1391;26942;27457.31356716018;28309.858450707616;28375.43249135047;28703;48779.75610121927;49255.76860578514;49290.26064608962;49330.013399731084;49366;511442.1841488276;516729;517751;518149.18508148886;518449;21144.577108457437;22634.660480092483;23840;43082;43092.78453607712;39579.80210098931;45265.54734452745;45766.542334576225;46021;46513.53486465092;33911.491327630596;34803;35345.823270883484;38567.80716096402;38701.6129838698;36315.09212269714;36546.90359289225;36711;37987.05032374213;37987.43018854775;36508.63491365052;36806.07984800402;37373.252534949694;37751;37838;29288.84916692051;33016;33115;33267.33465330728;33280.500792488616;23851;24195.948493085707;24302.513949720553;24766.752332476444;24822.37944051413 18:31:27 INFO - PROCESS | 1923 | |2;jquery.html;359.8776416018553;383.19337793943765;392.26646171658996;393.45506473534164;422.9767362795047;13371.465141394345;13718.382672779724;15141;20485.283015094476;21108.366748997516;17539.561510962234;20717.792822071773;21921.780782192174;22024.229151979685;22048;6040.395960403946;6043.758249670019;6077.7568897244055;6196.752129914798;6263.686815659199;6013.308469526017;6096.146539484475;6125.264968203822;6158.969205153968;6172.907406388886;3848.826802793881;4055.35114381699;5373.81191658292;6732.249615100085;6779.254282028973;6579.51960808819;6729.562578432435;6858.554193977376;6872.278410766848;6887.345702158312;5936.020556608154;5939;5941.881162376728;5947.613405128653;5950.702464876782;25559.488810223876;26110.477790444274;26115.73884261171;26141.73858261412;26501.939922403275;2326.482362641187;2679.8660066996767;2694.717054709247;2696.9595456068173;2740.7670348020442;12387.442565084593;14945.551633451014;15151.545453636409;15509.922450387789;15868.365265389371;1542.2674229740783;1804.9548761281073;1814.23802003159;1826.5433641589602;1827.4883032750852;113208.43395783051;115588;115885;116472.99999999916;116652;11211.439428028565;11395.715107122389;11398.031167350702;11424.257423267461;11631.300662675229;14462.783058254134;14471.421143154155;14492.202928838804;14516.854831451761;14517;8138.715144969898;8440.746777596682;8444.197801208875;8586;8647.135286471303;8272.1727827218;8275.668973241123;8332.750017499484;8469.788255293666;8490.702825401082;5939.020061689773;6877.346652068046;6958.617276049866;6985.476089293324;7021.789346319619;1862.422648978821;1884.24635107111;1895.933642322512;1899.838513726321;1900.667383207933;986.8865080515833;992.6079198716467;1013.4527355228241;1013.7820368620781;1026.3995562595817;18431.631367372578;18771.061446927873;20514.589708205753;21283.51015428905;21288.254911078348;983.4590974963884;989.7179303898498;1015.1219195396077;1028.454918892973;1035.8756949166147;10704.732381690364;11059.536023427845;11123.72190695239;11151.275167114112;11257.718557035976;628984;713275;718359;730250;738929;126665.96673316204;126738;129370.05944910645;129624;132351;1050.5167622893402;1055.8257887448485;1075.295681328731;1078.7249251440833;1080.9675709728722;17700.822991769917;18058.909705451915;18440.90779546094;18518.796278241873;18541.907290463463;49921.50078499169;53251.733741331045;53296.20612598878;53379.46620533745;54186.3744087678;73342.63328683322;74061.51876962323;75049.62475187589;75387.86918196121;77854.610726946;5664;7122.821929451805;7802.414818888493;8454.957725211581;12529.310887900889;11467.598634047768;13849.307534623431;13853.030287879752;14309.57071287863;14528.782068268772;11838.230515016496;11879.703007424883;12191.573294934635;12237.20458170216;12259.325737084546;10024.398536087858;11509.424528773361;11756.412179390825;11990.760184796429;12158;10278.537465814206;11846.407679615812;12252.142350035412;12292.262464252173;12309.199902006341;12874.034447416669;12904.548340808027;12915.031372646901;13313.46746130144;13350.799738003743;11810.586629467927;12033.157678962389;12077.75844483123;12198.51205951752;12222.572209972608 18:31:27 INFO - PROCESS | 1923 | |3;mootools.html;1435.6267370483674;1447.0955652717053;1448.1528305941024;1463.1001933810705;1470.1913947328967;7944.443888927777;8342.83314333713;8349.582520873955;8350.457220280678;8350.624221910013;7815;8150.144234855344;8153.143919888415;8243.793905152374;8264.752057438287;2235.3629215673536;2269.897854596547;2270.20542810016;2272.8409011369213;2276.7381751098587;2242.159190303636;2273.227102785053;2274.3177046885867;2274.488240145962;2280.1563421534056;2084.603925254197;2099.2862426774896;2109.831213502916;2111.989440052805;2136.9893150534226;2220.100859152046;2222.888855557224;2243.0354947372543;2250.8311876609155;2251.020805949417;2189.715337006195;2261.7738226177416;2262.4796296851814;2266.7733226677365;2268.0814270220585;8257.95871020647;8325.66697332106;8334.333253339719;8341.332693384515;8350.916490835074;4959.355283813117;5133.25567792668;5144.897102057976;5157.4068982067165;5160.096983027993;2896.000879696501;3119.3449375631144;3208.5508028875975;3427.0746898337557;3427.5715535558056;1166.539217009285;1169.4678921090883;1214.2228973456995;1224.7121926347302;1229.9508019679201;10512.894871051345;10549.789004219949;10611.045005949425;10621;10621.893781062168;3680;4065.89835254121;4254.87235382939;4319.3952846601505;4380.496242932104;8520.105388934073;8526.019507756564;8532.8720069199;8536.274416674529;8541.829163416822;4954.851354459372;4975.502449754996;4979.502049795064;4981.576565991932;4982.231307885649;4451.710638808467;4462.486814016364;4623.098495793288;4628.143793398232;4632.980744236263;3460.532828068229;3484.8257587120543;3518.82405879705;3559.839807208682;3574.5353104096303;1102.1513434655271;1104.7735214281054;1107.8725946516092;1119.3339962722168;1126.1047467263545;731.7950973727364;734.6657270941708;758.518340853562;767.877139657652;795.2167115391335;5226.320578324793;5373.7581808818695;5470.835874923759;5536;5590.580706447033;545.6725964421315;561.1834780394516;566.8582854286428;573.6328749600259;577.9595428319977;3017.7736669749856;3340.799552026886;3568.8750893719116;3736.5889752127246;3810.161764411825;9264.90735092654;9707.514624268759;9716.95141524302;9718.951405242928;9735.123838854537;8668.956655216683;9670.032996700274;9671.806563868684;9687.17658998979;9698.95150524257;5846.7076646167525;6610.636414997221;6910.2053263874295;7570.5836179010685;7655.617219139021;4566.70316429431;5438.293021907127;5496.093144631251;5555.388907220284;5610.691411972381;8773.824523509622;8956.552172391224;8972.37193396456;8984.191422771983;8990.280777537911;8741.300695944183;8979.326550508607;9161.404508706913;9236.815263694823;9245.260379169786;6449.3873082057125;6483.837904052436;6485.513586480938;6501;6535.5425120241125;7595.962020189863;7659.4255430841795;7670.8082297943;7676.80807979805;7677.385809135368;6788.524803263724;6935.618540980093;6945.895811562729;7019.08751862275;7113.253108423748;6812.352826481477;6816.420604248694;6877.690503927235;6892.310768923067;6906.205786334435;6041.969790151021;6262.248530176408;6267.4045965633195;6275.811725648238;6277.340879207617;4722.291656251589;4773.522647735199;4801.567858892577;4842.280990248552;4934.777934993006;4674.976625116853;4720.504347043511;4836.709797412166;4893.0916942393205;5035.370578677665 18:31:27 INFO - PROCESS | 1923 | |4;prototype.html;352.858856457417;356.89293212036387;365.87560229521955;370.4850258141186;370.76456450154177;4282.507511636163;5084.008618319429;5191.480851914806;5202.505761952613;5279.392869819972;4028.959710402895;4282.635975942041;4739.857804265877;4956.801727930879;5181.922271165936;3763.322601931651;4086.346184610463;4089.3661482470266;4091.4272001919753;4126.236646220451;3703.3889408247605;3761.755485893422;4117.773522456264;4123.165191489788;4141.716068018924;3368.8652453901814;3750.1749615084636;4077.7961101944934;4083.734557253769;4093.2427500912277;4030.9395359069636;4056.0873803394297;4067.3898915162667;4072.409500622408;4096.91806163875;3148.7008734170217;3587.318409502186;3598.7660802048044;3599.226166374242;3599.7120230381506;4053.8783836484954;4634.976825115853;4790.80836766529;4848.806047758086;4993.90012199754;4700.059988002379;4709.317149013408;4719.5752382285755;4732.053589282157;4769.451513075971;4535.841245556423;4737;4887.242477416006;5421.322334708161;5548.750306236229;3826.827792749305;3870.678712760403;3976.16500534888;3980.900477488086;3994.0613955720382;4955.481608058283;5451.754671039813;5715.514181294595;5778.855528611776;5806.390329015477;3492.2666240089357;4046.959530404717;4051.8379264829077;4058.6347228749555;4064.7154699171365;4070.7964601769795;4097.955021469506;4098.73358231714;4105.753654780723;4175.513446272396;4412.977935110304;4655.6973796703105;4657.347971284023;4689.554492323224;4691.1555919934735;4579.519150489217;4781.737004464788;4855.053264613366;4891.486393928657;5443.401225865152;1178.8880056394628;1197.8382918306093;1278.367208231931;1291.9599722223513;1306.2496255018132;850.7958090058341;858.3991206155716;916.5737931861717;932.3473568502076;948.3029972969862;4518;4814.15752243356;4874.683145595525;4888.804447822118;4896.890474548687;4588.564086411786;4881.144530449656;4994.850154495371;5032.748362581856;5250.291210686509;4188.895277618084;4420.58004489573;4847.927281090787;4855.514448555116;4894.657373983858;2215.5679642469563;2496.3883848457244;2777.0976880836693;2789.302674331417;2808.4944709951997;5350.491703288181;5577.860553486114;5596.977632078632;5604;5610.747516361774;5055.317532133115;5750.5112065474905;5772.740226689809;5794.536437085023;5822.7670893163795;4932.556069953722;5244.542139181742;5524.475174858382;5798.94201057984;5863.70681465925;4926.5073492650445;5193.20895746115;5293;5583.664980101207;5691.658500489983;5333.493258168654;5347.893042139213;5561.63849349791;5576.693281869536;5595.944040559542;4736.621070314435;4870.464248932685;5842.640481722941;5855.10635427436;5857.297124345106;3122.7814053016073;3340.1148695595202;3641.471986561987;3645.65366290202;3840.9423858642704;5078.314427552233;5275.5996021809215;5298.6820790751135;5545.750441230235;5567.55459563242;4848.61211103118;5295.523402893758;5344.182977787571;5473.059397233985;5514.283143191359;4527.773611319355;4789.712617242976;5193.636445448782;5480.287562616834;5485.501888451431;4724.905501890012;4827.372441582572;5059.544640982329;5067.442581315979;5128.256392826457;5065;5160.896782064263;5310.654807437504;5347.1979203119845;5535.228726807383;4358.932061644918;4837.588804951619;4851.636127290397;5408.567314614743;5444.128939369682 18:31:27 INFO - PROCESS | 1923 | |5;yui.html;740.0379506641368;760.3992845651932;800.5076877720202;806.6168569929284;824.7237175546192;12278;15150.015249008835;15151;15197.848021519781;15212.467563635275;13840;14002;14005.719885602308;14008.719825603483;14025.719485610307;866.1295398124876;879.1384443245623;884.2395539835736;902.9638814447447;903.9683611073614;896.5562046786849;901.5672477210943;902.2826852652157;903.3631289940577;910.5765818894213;922.3128769067055;939.2720641502856;940.5485367023832;942.3309450290302;943.188857582482;934.4766930518863;935.8081593273431;936.3679516326521;936.5972631768371;942.1238248428958;694.4097517110466;696.1611258433576;696.6238231355062;696.6273043921528;696.6516741629185;14874.553763387115;15315;15369.615759605987;15515.99146055514;15730.89883708141;13607.931960340235;13917.373718182705;13931.651708707263;13971.301434928315;14168.929155354157;11570.768584628344;11616.303021818634;11676;11937.701557461047;12128.696782580506;3558.6619271169197;3669.5229620149476;3671.816409179557;3678.9080272993124;3771.6605505504635;100372;100388.9961100387;103731;103826;106424;10455.581776728846;10536.420496872746;10599.894001060045;10857.992565055762;11068.944655276671;21784.673229901564;22267.331980040624;22305.884705764645;22318.330450086523;22385.216517422137;7451.590162541004;7499.512531685423;7529.360004399688;7619.847603047908;7760.611969401507;4048.7773172475795;4132.628063474302;4137.482814648169;4147.0461793787;4187.560306167869;2803.89246247731;3518.0149558123253;3520.2783429396914;3520.771149875301;3525.488804123439;640.4620119099978;649.3506493506494;652.2336254900473;654.5189285874878;665.8568407792347;624.7063879976404;632.6393955445352;640.385230179024;666.3103687683224;689.741346994877;9348.392354496937;9519.904800952041;9545.809083818423;9748.561314740853;9808.068233517804;625.3996163682824;640.466370558376;671.2610758077461;680.6664734280266;686.7218776395567;4455.9554404456185;4724.196886529298;4771.165046116916;4796.760161991956;4848.0788650156355;94637;97815;98689;98717.01282987221;101486;48310.46170895732;57984;74003;89466.55266723756;92075;64991.02513462397;66503.66748166324;68140.29649258808;73579;75507.62246188734;7284.635768211674;10077.093061624195;10145;10153.543090560795;10195.847062293924;37824.43263350997;45155.32267016063;45172;46774.766126169154;46848.765756171;48689.26966095441;61912.38087619247;62966;64279;64804.675976619816;11946.867565424403;12235.388230588613;12401.317927514074;12454.31501267439;12610.991120710505;13961;14110.490626246674;14178.432862685377;14492.275386230436;14956.252187390804;12389.194702344319;12701.174423662433;12773;12996;13184.274864882524;12891.097623166663;12991.805122923353;13013.024023198488;13237.669058273621;13416.12795168311;12610.558630447891;12612.783740146204;12694.492220311084;12901.677458063623;12951.935240323737;10767.676969690921;10805.841589599802;10849.837252441062;11171.944140279247;11421.1434142438;7134.183211981773;7142.785716428515;8469.950817705745;10001.449920254456;10217.591296348062 18:31:27 INFO - PROCESS | 1923 | __end_tp_report 18:31:27 INFO - PROCESS | 1923 | __start_cc_report 18:31:27 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,3752 18:31:27 INFO - PROCESS | 1923 | __end_cc_report 18:31:27 INFO - PROCESS | 1923 | __startTimestamp1487989887204__endTimestamp 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | ------- Summary: start ------- 18:31:27 INFO - PROCESS | 1923 | Number of tests: 6 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | [#0] dojo.html Cycles:165 Average:5461.78 Median:5616.63 stddev:628.50 (11.2%) stddev-sans-first:604.53 18:31:27 INFO - PROCESS | 1923 | Values: 3185.6 3208.5 3215.6 3234.9 3244.2 5353.4 5387.8 5408.4 5499.2 5531.3 5382.0 5407.4 5418.6 5428.8 5437.6 4914.9 4939.7 4958.4 4983.9 5049.6 3807.7 5052.5 5060.7 5061.2 5083.5 5061.8 5065.7 5071.8 5073.0 5074.9 5061.8 5069.4 5072.5 5072.8 5081.4 4957.1 5005.8 5032.8 5033.8 5042.3 5282.5 5493.0 5510.9 5515.2 5516.0 5970.8 6006.2 6009.3 6016.4 6033.9 5648.2 5701.7 5716.9 5743.5 5796.1 5340.3 5358.1 5385.9 5389.9 5397.4 4323.6 6179.5 6193.8 6212.3 6243.6 5887.3 5898.7 5900.9 5912.7 5912.9 5809.9 5886.1 5905.5 6049.0 6054.5 5684.6 5813.0 5815.7 5817.9 5819.7 4892.7 5819.4 5865.9 5870.6 5881.7 4785.6 4789.9 4794.7 4797.1 4800.6 4300.1 4306.7 4323.4 4363.6 4366.1 4356.7 5426.5 5445.1 5518.5 5554.3 6093.1 6097.5 6132.5 6133.1 6141.1 5510.4 5522.9 5525.9 5531.4 5537.1 4705.9 4816.7 5264.3 5269.3 5285.2 6105.3 6116.2 6117.7 6120.0 6134.9 5746.0 5846.2 5897.1 5910.1 5967.6 6017.8 6173.3 6173.7 6241.9 6248.8 5975.4 5985.8 5990.5 5993.9 5999.2 5824.1 5895.0 6043.2 6055.3 6075.5 4849.6 5564.5 5694.6 5718.7 5737.9 5683.6 5693.6 5697.3 5699.7 5710.1 5566.6 5584.2 5616.6 5622.0 5632.5 5568.7 5749.3 5775.2 5818.8 5861.8 5712.7 5737.1 5741.1 5744.2 5747.3 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | [#1] ext.html Cycles:180 Average:62760.07 Median:3702.36 stddev:133304.26 (3600.5%) stddev-sans-first:133596.15 18:31:27 INFO - PROCESS | 1923 | Values: 459.0 483.0 483.6 485.6 496.1 27310.0 27985.6 28008.7 28038.2 28041.3 28671.0 32424.8 39268.4 41466.0 43281.8 9096.6 9296.3 9302.0 9305.7 9306.7 9186.0 9287.9 9297.4 9299.4 9300.5 6818.1 7430.3 7610.0 7634.3 7659.4 7304.3 11034.4 11287.9 11313.2 11427.0 11043.0 11114.3 11121.9 11143.1 11148.0 30894.7 38211.0 38306.8 38377.0 38379.8 3693.0 3696.6 3708.1 3715.7 3742.8 28921.4 29207.9 29559.8 29974.4 30609.2 3056.0 3071.4 3088.7 3129.9 3194.2 367608.0 432387.0 433726.0 433731.8 434222.0 55246.7 55555.0 55616.2 55646.0 55853.4 47471.5 60060.9 60139.0 60192.0 60282.1 12895.5 13227.1 15160.3 15180.0 15431.4 11835.5 11976.1 11983.3 11984.0 12014.5 16400.3 16649.0 16677.2 16679.3 16718.7 6133.6 7019.8 7042.3 7098.7 7102.9 686.0 832.5 852.3 852.8 862.1 24454.0 27983.9 28035.6 28662.9 29022.0 1008.7 1049.4 1059.6 1062.4 1078.7 11705.9 14030.7 14402.7 14927.3 15058.7 550373.0 552098.0 552215.0 554878.0 555209.0 55288.0 55352.4 55365.7 55397.2 55687.4 1314.6 1356.0 1385.2 1386.6 1391.0 26942.0 27457.3 28309.9 28375.4 28703.0 48779.8 49255.8 49290.3 49330.0 49366.0 511442.2 516729.0 517751.0 518149.2 518449.0 21144.6 22634.7 23840.0 43082.0 43092.8 39579.8 45265.5 45766.5 46021.0 46513.5 33911.5 34803.0 35345.8 38567.8 38701.6 36315.1 36546.9 36711.0 37987.1 37987.4 36508.6 36806.1 37373.3 37751.0 37838.0 29288.8 33016.0 33115.0 33267.3 33280.5 23851.0 24195.9 24302.5 24766.8 24822.4 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | [#2] jquery.html Cycles:180 Average:38284.46 Median:1897.89 stddev:116970.51 (6163.2%) stddev-sans-first:117263.98 18:31:27 INFO - PROCESS | 1923 | Values: 359.9 383.2 392.3 393.5 423.0 13371.5 13718.4 15141.0 20485.3 21108.4 17539.6 20717.8 21921.8 22024.2 22048.0 6040.4 6043.8 6077.8 6196.8 6263.7 6013.3 6096.1 6125.3 6159.0 6172.9 3848.8 4055.4 5373.8 6732.2 6779.3 6579.5 6729.6 6858.6 6872.3 6887.3 5936.0 5939.0 5941.9 5947.6 5950.7 25559.5 26110.5 26115.7 26141.7 26501.9 2326.5 2679.9 2694.7 2697.0 2740.8 12387.4 14945.6 15151.5 15509.9 15868.4 1542.3 1805.0 1814.2 1826.5 1827.5 113208.4 115588.0 115885.0 116473.0 116652.0 11211.4 11395.7 11398.0 11424.3 11631.3 14462.8 14471.4 14492.2 14516.9 14517.0 8138.7 8440.7 8444.2 8586.0 8647.1 8272.2 8275.7 8332.8 8469.8 8490.7 5939.0 6877.3 6958.6 6985.5 7021.8 1862.4 1884.2 1895.9 1899.8 1900.7 986.9 992.6 1013.5 1013.8 1026.4 18431.6 18771.1 20514.6 21283.5 21288.3 983.5 989.7 1015.1 1028.5 1035.9 10704.7 11059.5 11123.7 11151.3 11257.7 628984.0 713275.0 718359.0 730250.0 738929.0 126666.0 126738.0 129370.1 129624.0 132351.0 1050.5 1055.8 1075.3 1078.7 1081.0 17700.8 18058.9 18440.9 18518.8 18541.9 49921.5 53251.7 53296.2 53379.5 54186.4 73342.6 74061.5 75049.6 75387.9 77854.6 5664.0 7122.8 7802.4 8455.0 12529.3 11467.6 13849.3 13853.0 14309.6 14528.8 11838.2 11879.7 12191.6 12237.2 12259.3 10024.4 11509.4 11756.4 11990.8 12158.0 10278.5 11846.4 12252.1 12292.3 12309.2 12874.0 12904.5 12915.0 13313.5 13350.8 11810.6 12033.2 12077.8 12198.5 12222.6 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | [#3] mootools.html Cycles:180 Average:5214.23 Median:5193.21 stddev:2881.30 (55.5%) stddev-sans-first:2875.39 18:31:27 INFO - PROCESS | 1923 | Values: 1435.6 1447.1 1448.2 1463.1 1470.2 7944.4 8342.8 8349.6 8350.5 8350.6 7815.0 8150.1 8153.1 8243.8 8264.8 2235.4 2269.9 2270.2 2272.8 2276.7 2242.2 2273.2 2274.3 2274.5 2280.2 2084.6 2099.3 2109.8 2112.0 2137.0 2220.1 2222.9 2243.0 2250.8 2251.0 2189.7 2261.8 2262.5 2266.8 2268.1 8258.0 8325.7 8334.3 8341.3 8350.9 4959.4 5133.3 5144.9 5157.4 5160.1 2896.0 3119.3 3208.6 3427.1 3427.6 1166.5 1169.5 1214.2 1224.7 1230.0 10512.9 10549.8 10611.0 10621.0 10621.9 3680.0 4065.9 4254.9 4319.4 4380.5 8520.1 8526.0 8532.9 8536.3 8541.8 4954.9 4975.5 4979.5 4981.6 4982.2 4451.7 4462.5 4623.1 4628.1 4633.0 3460.5 3484.8 3518.8 3559.8 3574.5 1102.2 1104.8 1107.9 1119.3 1126.1 731.8 734.7 758.5 767.9 795.2 5226.3 5373.8 5470.8 5536.0 5590.6 545.7 561.2 566.9 573.6 578.0 3017.8 3340.8 3568.9 3736.6 3810.2 9264.9 9707.5 9717.0 9719.0 9735.1 8669.0 9670.0 9671.8 9687.2 9699.0 5846.7 6610.6 6910.2 7570.6 7655.6 4566.7 5438.3 5496.1 5555.4 5610.7 8773.8 8956.6 8972.4 8984.2 8990.3 8741.3 8979.3 9161.4 9236.8 9245.3 6449.4 6483.8 6485.5 6501.0 6535.5 7596.0 7659.4 7670.8 7676.8 7677.4 6788.5 6935.6 6945.9 7019.1 7113.3 6812.4 6816.4 6877.7 6892.3 6906.2 6042.0 6262.2 6267.4 6275.8 6277.3 4722.3 4773.5 4801.6 4842.3 4934.8 4675.0 4720.5 4836.7 4893.1 5035.4 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | [#4] prototype.html Cycles:180 Average:4374.67 Median:4820.76 stddev:1300.78 (27.0%) stddev-sans-first:1268.92 18:31:27 INFO - PROCESS | 1923 | Values: 352.9 356.9 365.9 370.5 370.8 4282.5 5084.0 5191.5 5202.5 5279.4 4029.0 4282.6 4739.9 4956.8 5181.9 3763.3 4086.3 4089.4 4091.4 4126.2 3703.4 3761.8 4117.8 4123.2 4141.7 3368.9 3750.2 4077.8 4083.7 4093.2 4030.9 4056.1 4067.4 4072.4 4096.9 3148.7 3587.3 3598.8 3599.2 3599.7 4053.9 4635.0 4790.8 4848.8 4993.9 4700.1 4709.3 4719.6 4732.1 4769.5 4535.8 4737.0 4887.2 5421.3 5548.8 3826.8 3870.7 3976.2 3980.9 3994.1 4955.5 5451.8 5715.5 5778.9 5806.4 3492.3 4047.0 4051.8 4058.6 4064.7 4070.8 4098.0 4098.7 4105.8 4175.5 4413.0 4655.7 4657.3 4689.6 4691.2 4579.5 4781.7 4855.1 4891.5 5443.4 1178.9 1197.8 1278.4 1292.0 1306.2 850.8 858.4 916.6 932.3 948.3 4518.0 4814.2 4874.7 4888.8 4896.9 4588.6 4881.1 4994.9 5032.7 5250.3 4188.9 4420.6 4847.9 4855.5 4894.7 2215.6 2496.4 2777.1 2789.3 2808.5 5350.5 5577.9 5597.0 5604.0 5610.7 5055.3 5750.5 5772.7 5794.5 5822.8 4932.6 5244.5 5524.5 5798.9 5863.7 4926.5 5193.2 5293.0 5583.7 5691.7 5333.5 5347.9 5561.6 5576.7 5595.9 4736.6 4870.5 5842.6 5855.1 5857.3 3122.8 3340.1 3641.5 3645.7 3840.9 5078.3 5275.6 5298.7 5545.8 5567.6 4848.6 5295.5 5344.2 5473.1 5514.3 4527.8 4789.7 5193.6 5480.3 5485.5 4724.9 4827.4 5059.5 5067.4 5128.3 5065.0 5160.9 5310.7 5347.2 5535.2 4358.9 4837.6 4851.6 5408.6 5444.1 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - PROCESS | 1923 | [#5] yui.html Cycles:180 Average:19040.48 Median:4142.26 stddev:27081.85 (653.8%) stddev-sans-first:27122.96 18:31:27 INFO - PROCESS | 1923 | Values: 740.0 760.4 800.5 806.6 824.7 12278.0 15150.0 15151.0 15197.8 15212.5 13840.0 14002.0 14005.7 14008.7 14025.7 866.1 879.1 884.2 903.0 904.0 896.6 901.6 902.3 903.4 910.6 922.3 939.3 940.5 942.3 943.2 934.5 935.8 936.4 936.6 942.1 694.4 696.2 696.6 696.6 696.7 14874.6 15315.0 15369.6 15516.0 15730.9 13607.9 13917.4 13931.7 13971.3 14168.9 11570.8 11616.3 11676.0 11937.7 12128.7 3558.7 3669.5 3671.8 3678.9 3771.7 100372.0 100389.0 103731.0 103826.0 106424.0 10455.6 10536.4 10599.9 10858.0 11068.9 21784.7 22267.3 22305.9 22318.3 22385.2 7451.6 7499.5 7529.4 7619.8 7760.6 4048.8 4132.6 4137.5 4147.0 4187.6 2803.9 3518.0 3520.3 3520.8 3525.5 640.5 649.4 652.2 654.5 665.9 624.7 632.6 640.4 666.3 689.7 9348.4 9519.9 9545.8 9748.6 9808.1 625.4 640.5 671.3 680.7 686.7 4456.0 4724.2 4771.2 4796.8 4848.1 94637.0 97815.0 98689.0 98717.0 101486.0 48310.5 57984.0 74003.0 89466.6 92075.0 64991.0 66503.7 68140.3 73579.0 75507.6 7284.6 10077.1 10145.0 10153.5 10195.8 37824.4 45155.3 45172.0 46774.8 46848.8 48689.3 61912.4 62966.0 64279.0 64804.7 11946.9 12235.4 12401.3 12454.3 12611.0 13961.0 14110.5 14178.4 14492.3 14956.3 12389.2 12701.2 12773.0 12996.0 13184.3 12891.1 12991.8 13013.0 13237.7 13416.1 12610.6 12612.8 12694.5 12901.7 12951.9 10767.7 10805.8 10849.8 11171.9 11421.1 7134.2 7142.8 8470.0 10001.4 10217.6 18:31:27 INFO - PROCESS | 1923 | -------- Summary: end -------- 18:31:27 INFO - PROCESS | 1923 | 18:31:27 INFO - TEST-INFO | 1923: exit 0 18:31:27 INFO - TEST-OK | dromaeo_css | took 1119959ms 18:31:27 INFO - TEST-START | kraken 18:31:27 INFO - operating with platform_type : mac_ 18:31:27 INFO - Initialising browser for kraken test... 18:31:27 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpGjWiWi/profile http://localhost:53091/getInfo.html) 18:31:30 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 18:31:30 INFO - PROCESS | 1927 | colorDepth:24 18:31:30 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 18:31:30 INFO - PROCESS | 1927 | __metrics 18:31:30 INFO - PROCESS | 1927 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:31:30 INFO - PROCESS | 1927 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:31 INFO - TEST-INFO | 1927: exit 0 18:31:31 INFO - Browser initialized. 18:31:31 INFO - Running cycle 1/1 for kraken test... 18:31:31 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpGjWiWi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:43 INFO - PROCESS | 1928 | RSS: Main: 230850560 18:31:43 INFO - PROCESS | 1928 | 18:31:46 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53091/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:47 INFO - PROCESS | 1928 | RSS: Main: 283508736 18:31:47 INFO - PROCESS | 1928 | 18:31:50 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53091/tests/kraken/driver.html?testName=audio-dft) 18:31:50 INFO - PROCESS | 1928 | RSS: Main: 285028352 18:31:50 INFO - PROCESS | 1928 | 18:31:53 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53091/tests/kraken/driver.html?testName=audio-fft) 18:31:54 INFO - PROCESS | 1928 | RSS: Main: 376119296 18:31:54 INFO - PROCESS | 1928 | 18:31:56 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53091/tests/kraken/driver.html?testName=audio-oscillator) 18:31:57 INFO - PROCESS | 1928 | RSS: Main: 280346624 18:31:57 INFO - PROCESS | 1928 | 18:31:59 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53091/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:59 INFO - PROCESS | 1928 | RSS: Main: 284000256 18:31:59 INFO - PROCESS | 1928 | 18:32:02 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53091/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:03 INFO - PROCESS | 1928 | RSS: Main: 325681152 18:32:03 INFO - PROCESS | 1928 | 18:32:06 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53091/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:06 INFO - PROCESS | 1928 | RSS: Main: 328921088 18:32:06 INFO - PROCESS | 1928 | 18:32:09 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53091/tests/kraken/driver.html?testName=json-parse-financial) 18:32:10 INFO - PROCESS | 1928 | RSS: Main: 324263936 18:32:10 INFO - PROCESS | 1928 | 18:32:12 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53091/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:12 INFO - PROCESS | 1928 | RSS: Main: 300417024 18:32:12 INFO - PROCESS | 1928 | 18:32:14 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:15 INFO - PROCESS | 1928 | RSS: Main: 304304128 18:32:15 INFO - PROCESS | 1928 | 18:32:17 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:18 INFO - PROCESS | 1928 | RSS: Main: 305573888 18:32:18 INFO - PROCESS | 1928 | 18:32:21 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:22 INFO - PROCESS | 1928 | RSS: Main: 322142208 18:32:22 INFO - PROCESS | 1928 | 18:32:25 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:25 INFO - PROCESS | 1928 | RSS: Main: 316178432 18:32:25 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53091/tests/kraken/driver.html?testName=ai-astar) 18:32:27 INFO - PROCESS | 1928 | RSS: Main: 360972288 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | __start_tp_report 18:32:27 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 18:32:27 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 18:32:27 INFO - PROCESS | 1928 | |i|pagename|runs| 18:32:27 INFO - PROCESS | 1928 | |0;ai-astar;113;113;113;112;111;114;112;112;113;113 18:32:27 INFO - PROCESS | 1928 | |1;audio-beat-detection;141;112;110;150;114;111;110;111;131;112 18:32:27 INFO - PROCESS | 1928 | |2;audio-dft;185;184;184;185;187;180;183;184;185;185 18:32:27 INFO - PROCESS | 1928 | |3;audio-fft;82;60;60;93;61;61;60;62;82;68 18:32:27 INFO - PROCESS | 1928 | |4;audio-oscillator;87;71;88;69;70;70;86;70;69;87 18:32:27 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;87;86;86;86;87;86;86;90;87;87 18:32:27 INFO - PROCESS | 1928 | |6;imaging-darkroom;99;96;94;92;94;93;94;103;122;94 18:32:27 INFO - PROCESS | 1928 | |7;imaging-desaturate;87;88;84;99;140;138;87;87;88;86 18:32:27 INFO - PROCESS | 1928 | |8;json-parse-financial;52;53;86;94;94;82;63;61;62;60 18:32:27 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;66;73;83;75;73;69;69;69;69;70 18:32:27 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;89;88;87;87;89;87;87;91;91;87 18:32:27 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;144;145;145;144;137;146;141;144;180;139 18:32:27 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;172;172;174;172;175;175;228;227;171;175 18:32:27 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;70;72;87;85;71;67;66;66;65;66 18:32:27 INFO - PROCESS | 1928 | __end_tp_report 18:32:27 INFO - PROCESS | 1928 | __start_cc_report 18:32:27 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,2108 18:32:27 INFO - PROCESS | 1928 | __end_cc_report 18:32:27 INFO - PROCESS | 1928 | __startTimestamp1487989947784__endTimestamp 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | ------- Summary: start ------- 18:32:27 INFO - PROCESS | 1928 | Number of tests: 14 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:0.84 (0.7%) stddev-sans-first:0.88 18:32:27 INFO - PROCESS | 1928 | Values: 113.0 113.0 113.0 112.0 111.0 114.0 112.0 112.0 113.0 113.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:113.00 stddev:14.86 (13.2%) stddev-sans-first:13.72 18:32:27 INFO - PROCESS | 1928 | Values: 141.0 112.0 110.0 150.0 114.0 111.0 110.0 111.0 131.0 112.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.81 (1.0%) stddev-sans-first:1.90 18:32:27 INFO - PROCESS | 1928 | Values: 185.0 184.0 184.0 185.0 187.0 180.0 183.0 184.0 185.0 185.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:68.90 Median:65.00 stddev:12.18 (18.7%) stddev-sans-first:11.96 18:32:27 INFO - PROCESS | 1928 | Values: 82.0 60.0 60.0 93.0 61.0 61.0 60.0 62.0 82.0 68.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:78.50 stddev:8.90 (11.3%) stddev-sans-first:8.62 18:32:27 INFO - PROCESS | 1928 | Values: 87.0 71.0 88.0 69.0 70.0 70.0 86.0 70.0 69.0 87.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 18:32:27 INFO - PROCESS | 1928 | Values: 87.0 86.0 86.0 86.0 87.0 86.0 86.0 90.0 87.0 87.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:94.00 stddev:9.01 (9.6%) stddev-sans-first:9.55 18:32:27 INFO - PROCESS | 1928 | Values: 99.0 96.0 94.0 92.0 94.0 93.0 94.0 103.0 122.0 94.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:98.40 Median:87.00 stddev:21.77 (25.0%) stddev-sans-first:22.70 18:32:27 INFO - PROCESS | 1928 | Values: 87.0 88.0 84.0 99.0 140.0 138.0 87.0 87.0 88.0 86.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:70.70 Median:72.50 stddev:16.51 (22.8%) stddev-sans-first:16.07 18:32:27 INFO - PROCESS | 1928 | Values: 52.0 53.0 86.0 94.0 94.0 82.0 63.0 61.0 62.0 60.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:4.79 (6.7%) stddev-sans-first:4.63 18:32:27 INFO - PROCESS | 1928 | Values: 66.0 73.0 83.0 75.0 73.0 69.0 69.0 69.0 69.0 70.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:88.50 stddev:1.64 (1.8%) stddev-sans-first:1.72 18:32:27 INFO - PROCESS | 1928 | Values: 89.0 88.0 87.0 87.0 89.0 87.0 87.0 91.0 91.0 87.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:144.50 stddev:12.12 (8.4%) stddev-sans-first:12.82 18:32:27 INFO - PROCESS | 1928 | Values: 144.0 145.0 145.0 144.0 137.0 146.0 141.0 144.0 180.0 139.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:175.00 stddev:22.92 (13.1%) stddev-sans-first:23.89 18:32:27 INFO - PROCESS | 1928 | Values: 172.0 172.0 174.0 172.0 175.0 175.0 228.0 227.0 171.0 175.0 18:32:27 INFO - PROCESS | 1928 | 18:32:27 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:70.50 stddev:8.02 (11.4%) stddev-sans-first:8.49 18:32:27 INFO - PROCESS | 1928 | Values: 70.0 72.0 87.0 85.0 71.0 67.0 66.0 66.0 65.0 66.0 18:32:27 INFO - PROCESS | 1928 | -------- Summary: end -------- 18:32:27 INFO - PROCESS | 1928 | 18:32:28 INFO - TEST-INFO | 1928: exit 0 18:32:28 INFO - TEST-OK | kraken | took 60816ms 18:32:28 INFO - SUITE-END | took 1180s 18:32:28 INFO - Completed test suite (00:19:40) 18:32:28 INFO - javascript benchmark 18:32:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7067.512153958852, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3185.569948057013, 3208.4866421372576, 3215.5980502437196, 3234.9191270218257, 3244.2376041630214, 5353.411124776271, 5387.811426600069, 5408.432114627966, 5499.230107784908, 5531.308586426697, 5382.0312343778105, 5407.405185429599, 5418.647787893794, 5428.75570599323, 5437.564994800417, 4914.926276105853, 4939.703617782927, 4958.380202474691, 4983.8504844854715, 5049.570786483153, 3807.6953843692577, 5052.545270925616, 5060.696358218519, 5061.240813877929, 5083.542481176693, 5061.797528098871, 5065.696058236499, 5071.847844564669, 5073.046876952363, 5074.8731281718065, 5061.848144555669, 5069.391672999227, 5072.467390923936, 5072.771725272371, 5081.364829396325, 4957.082939656175, 5005.824796132154, 5032.84901452957, 5033.84898453047, 5042.268871013719, 5282.524572788429, 5493.0, 5510.91733623996, 5515.227868098469, 5516.034693928546, 5970.761169553212, 6006.21919150512, 6009.278886533601, 6016.368281330485, 6033.9396606033815, 5648.181013752983, 5701.743421546041, 5716.8856622867725, 5743.54051675865, 5796.13058041293, 5340.305760251182, 5358.062339090644, 5385.919211211835, 5389.865253368658, 5397.379301380352, 4323.567643235714, 6179.536534759911, 6193.752249909953, 6212.316645169028, 6243.594166379171, 5887.293524777054, 5898.705064746746, 5900.881982360329, 5912.704364781744, 5912.852178695481, 5809.854753631109, 5886.1465087562065, 5905.527557795366, 6049.0, 6054.485368743706, 5684.602077854511, 5813.0, 5815.680137592389, 5817.854553636193, 5819.73811178498, 4892.730899800546, 5819.447152520504, 5865.912011319833, 5870.559708021916, 5881.676507792027, 4785.617150627942, 4789.904201915942, 4794.664373493822, 4797.112534181188, 4800.591949684316, 4300.0969796343115, 4306.655467562588, 4323.416338794285, 4363.629091527192, 4366.12677464502, 4356.6514678825615, 5426.457354264621, 5445.1015582429245, 5518.503334699896, 5554.2779438672715, 6093.086037094383, 6097.542684298695, 6132.478739307209, 6133.110698948627, 6141.109539116893, 5510.366307874566, 5522.91715624266, 5525.917111243334, 5531.3915469298345, 5537.1140617501005, 4705.858824235278, 4816.675414261106, 5264.315638966987, 5269.262303277545, 5285.180796976475, 6105.308121371101, 6116.153172518083, 6117.705611546597, 6119.959572021111, 6134.9079763803575, 5746.0, 5846.239988801429, 5897.144913987534, 5910.143029260819, 5967.6419414835245, 6017.759289628541, 6173.290071641819, 6173.660448675366, 6241.8751624966335, 6248.843778905381, 5975.40245975399, 5985.760569577168, 5990.4908082813445, 5993.921094203085, 5999.1601175836295, 5824.068149096124, 5894.970525147518, 6043.153958445909, 6055.303640081271, 6075.453209211192, 4849.587785038311, 5564.4713752193475, 5694.629849059798, 5718.656880587178, 5737.881113182889, 5683.60214784961, 5693.629914055573, 5697.3448053474385, 5699.658020518782, 5710.086386178192, 5566.610337276352, 5584.246126772996, 5616.634918730269, 5621.971890140523, 5632.5212356950115, 5568.693721845338, 5749.281339832521, 5775.220345253505, 5818.8254352369495, 5861.824145275648, 5712.685802280915, 5737.139429085671, 5741.13882917549, 5744.224529688606, 5747.252857128547], "value": 5423.957058096899, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.9586937175653, 482.99517004829966, 483.62760674280804, 485.63819954134175, 496.10205527994344, 27310.0, 27985.58021629677, 28008.719912800865, 28038.15885523435, 28041.29896752577, 28671.0, 32424.837875810586, 39268.41097383542, 41466.0, 43281.783591082, 9096.590653420579, 9296.302777291696, 9302.02328755481, 9305.720828375159, 9306.674266400678, 9186.0, 9287.860682089775, 9297.44215347078, 9299.395539289959, 9300.534973251344, 6818.1136452261135, 7430.331270185682, 7609.961950190269, 7634.312911837934, 7659.425543084263, 7304.269573042707, 11034.393108379076, 11287.943560282147, 11313.151513636429, 11427.0, 11043.006129448308, 11114.277571957797, 11121.944390278079, 11143.108551315876, 11147.996680298813, 30894.691053089406, 38211.044723881845, 38306.80846595749, 38377.04057398559, 38379.808100959315, 3692.981535092334, 3696.6379671987042, 3708.072981754561, 3715.7008420009906, 3742.8315725792404, 28921.42157156845, 29207.853960730274, 29559.81760729568, 29974.400511989857, 30609.234769130726, 3055.9694403055905, 3071.370369074357, 3088.722015018659, 3129.906102816919, 3194.217416732892, 367608.0, 432387.0, 433726.0, 433731.83134084445, 434222.0, 55246.723766381714, 55555.0, 55616.16575751367, 55646.0, 55853.44146558564, 47471.525284747404, 60060.89786857439, 60139.0, 60192.0, 60282.09576856351, 12895.548655797002, 13227.140235884637, 15160.31778569967, 15180.0, 15431.38274469031, 11835.526578936746, 11976.101792365607, 11983.34091624969, 11984.0, 12014.519419223308, 16400.343986240656, 16649.0, 16677.16614169311, 16679.332826686797, 16718.665626687398, 6133.570650054453, 7019.754308599277, 7042.295770422917, 7098.68055937484, 7102.92897071033, 685.9862802743917, 832.4713806732768, 852.2925971443686, 852.8018134521008, 862.0689655172433, 24454.0, 27983.860080699873, 28035.579466308023, 28662.856685716437, 29021.98423055183, 1008.7125169326706, 1049.3913530152493, 1059.6026490066224, 1062.3519653011656, 1078.665613659768, 11705.941470292593, 14030.71938561223, 14402.711945761235, 14927.253637318308, 15058.698826023417, 550373.0, 552098.0, 552215.0, 554878.0, 555209.0, 55288.0, 55352.44647553554, 55365.723171383885, 55397.1690424644, 55687.443125569036, 1314.5859054397833, 1355.9932200338935, 1385.161977003898, 1386.577093986344, 1391.0, 26942.0, 27457.31356716018, 28309.858450707616, 28375.43249135047, 28703.0, 48779.75610121927, 49255.76860578514, 49290.26064608962, 49330.013399731084, 49366.0, 511442.1841488276, 516729.0, 517751.0, 518149.18508148886, 518449.0, 21144.577108457437, 22634.660480092483, 23840.0, 43082.0, 43092.78453607712, 39579.80210098931, 45265.54734452745, 45766.542334576225, 46021.0, 46513.53486465092, 33911.491327630596, 34803.0, 35345.823270883484, 38567.80716096402, 38701.6129838698, 36315.09212269714, 36546.90359289225, 36711.0, 37987.05032374213, 37987.43018854775, 36508.63491365052, 36806.07984800402, 37373.252534949694, 37751.0, 37838.0, 29288.84916692051, 33016.0, 33115.0, 33267.33465330728, 33280.500792488616, 23851.0, 24195.948493085707, 24302.513949720553, 24766.752332476444, 24822.37944051413], "value": 18745.325069989216, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.8776416018553, 383.19337793943765, 392.26646171658996, 393.45506473534164, 422.9767362795047, 13371.465141394345, 13718.382672779724, 15141.0, 20485.283015094476, 21108.366748997516, 17539.561510962234, 20717.792822071773, 21921.780782192174, 22024.229151979685, 22048.0, 6040.395960403946, 6043.758249670019, 6077.7568897244055, 6196.752129914798, 6263.686815659199, 6013.308469526017, 6096.146539484475, 6125.264968203822, 6158.969205153968, 6172.907406388886, 3848.826802793881, 4055.35114381699, 5373.81191658292, 6732.249615100085, 6779.254282028973, 6579.51960808819, 6729.562578432435, 6858.554193977376, 6872.278410766848, 6887.345702158312, 5936.020556608154, 5939.0, 5941.881162376728, 5947.613405128653, 5950.702464876782, 25559.488810223876, 26110.477790444274, 26115.73884261171, 26141.73858261412, 26501.939922403275, 2326.482362641187, 2679.8660066996767, 2694.717054709247, 2696.9595456068173, 2740.7670348020442, 12387.442565084593, 14945.551633451014, 15151.545453636409, 15509.922450387789, 15868.365265389371, 1542.2674229740783, 1804.9548761281073, 1814.23802003159, 1826.5433641589602, 1827.4883032750852, 113208.43395783051, 115588.0, 115885.0, 116472.99999999916, 116652.0, 11211.439428028565, 11395.715107122389, 11398.031167350702, 11424.257423267461, 11631.300662675229, 14462.783058254134, 14471.421143154155, 14492.202928838804, 14516.854831451761, 14517.0, 8138.715144969898, 8440.746777596682, 8444.197801208875, 8586.0, 8647.135286471303, 8272.1727827218, 8275.668973241123, 8332.750017499484, 8469.788255293666, 8490.702825401082, 5939.020061689773, 6877.346652068046, 6958.617276049866, 6985.476089293324, 7021.789346319619, 1862.422648978821, 1884.24635107111, 1895.933642322512, 1899.838513726321, 1900.667383207933, 986.8865080515833, 992.6079198716467, 1013.4527355228241, 1013.7820368620781, 1026.3995562595817, 18431.631367372578, 18771.061446927873, 20514.589708205753, 21283.51015428905, 21288.254911078348, 983.4590974963884, 989.7179303898498, 1015.1219195396077, 1028.454918892973, 1035.8756949166147, 10704.732381690364, 11059.536023427845, 11123.72190695239, 11151.275167114112, 11257.718557035976, 628984.0, 713275.0, 718359.0, 730250.0, 738929.0, 126665.96673316204, 126738.0, 129370.05944910645, 129624.0, 132351.0, 1050.5167622893402, 1055.8257887448485, 1075.295681328731, 1078.7249251440833, 1080.9675709728722, 17700.822991769917, 18058.909705451915, 18440.90779546094, 18518.796278241873, 18541.907290463463, 49921.50078499169, 53251.733741331045, 53296.20612598878, 53379.46620533745, 54186.3744087678, 73342.63328683322, 74061.51876962323, 75049.62475187589, 75387.86918196121, 77854.610726946, 5664.0, 7122.821929451805, 7802.414818888493, 8454.957725211581, 12529.310887900889, 11467.598634047768, 13849.307534623431, 13853.030287879752, 14309.57071287863, 14528.782068268772, 11838.230515016496, 11879.703007424883, 12191.573294934635, 12237.20458170216, 12259.325737084546, 10024.398536087858, 11509.424528773361, 11756.412179390825, 11990.760184796429, 12158.0, 10278.537465814206, 11846.407679615812, 12252.142350035412, 12292.262464252173, 12309.199902006341, 12874.034447416669, 12904.548340808027, 12915.031372646901, 13313.46746130144, 13350.799738003743, 11810.586629467927, 12033.157678962389, 12077.75844483123, 12198.51205951752, 12222.572209972608], "value": 10129.110827031023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.6267370483674, 1447.0955652717053, 1448.1528305941024, 1463.1001933810705, 1470.1913947328967, 7944.443888927777, 8342.83314333713, 8349.582520873955, 8350.457220280678, 8350.624221910013, 7815.0, 8150.144234855344, 8153.143919888415, 8243.793905152374, 8264.752057438287, 2235.3629215673536, 2269.897854596547, 2270.20542810016, 2272.8409011369213, 2276.7381751098587, 2242.159190303636, 2273.227102785053, 2274.3177046885867, 2274.488240145962, 2280.1563421534056, 2084.603925254197, 2099.2862426774896, 2109.831213502916, 2111.989440052805, 2136.9893150534226, 2220.100859152046, 2222.888855557224, 2243.0354947372543, 2250.8311876609155, 2251.020805949417, 2189.715337006195, 2261.7738226177416, 2262.4796296851814, 2266.7733226677365, 2268.0814270220585, 8257.95871020647, 8325.66697332106, 8334.333253339719, 8341.332693384515, 8350.916490835074, 4959.355283813117, 5133.25567792668, 5144.897102057976, 5157.4068982067165, 5160.096983027993, 2896.000879696501, 3119.3449375631144, 3208.5508028875975, 3427.0746898337557, 3427.5715535558056, 1166.539217009285, 1169.4678921090883, 1214.2228973456995, 1224.7121926347302, 1229.9508019679201, 10512.894871051345, 10549.789004219949, 10611.045005949425, 10621.0, 10621.893781062168, 3680.0, 4065.89835254121, 4254.87235382939, 4319.3952846601505, 4380.496242932104, 8520.105388934073, 8526.019507756564, 8532.8720069199, 8536.274416674529, 8541.829163416822, 4954.851354459372, 4975.502449754996, 4979.502049795064, 4981.576565991932, 4982.231307885649, 4451.710638808467, 4462.486814016364, 4623.098495793288, 4628.143793398232, 4632.980744236263, 3460.532828068229, 3484.8257587120543, 3518.82405879705, 3559.839807208682, 3574.5353104096303, 1102.1513434655271, 1104.7735214281054, 1107.8725946516092, 1119.3339962722168, 1126.1047467263545, 731.7950973727364, 734.6657270941708, 758.518340853562, 767.877139657652, 795.2167115391335, 5226.320578324793, 5373.7581808818695, 5470.835874923759, 5536.0, 5590.580706447033, 545.6725964421315, 561.1834780394516, 566.8582854286428, 573.6328749600259, 577.9595428319977, 3017.7736669749856, 3340.799552026886, 3568.8750893719116, 3736.5889752127246, 3810.161764411825, 9264.90735092654, 9707.514624268759, 9716.95141524302, 9718.951405242928, 9735.123838854537, 8668.956655216683, 9670.032996700274, 9671.806563868684, 9687.17658998979, 9698.95150524257, 5846.7076646167525, 6610.636414997221, 6910.2053263874295, 7570.5836179010685, 7655.617219139021, 4566.70316429431, 5438.293021907127, 5496.093144631251, 5555.388907220284, 5610.691411972381, 8773.824523509622, 8956.552172391224, 8972.37193396456, 8984.191422771983, 8990.280777537911, 8741.300695944183, 8979.326550508607, 9161.404508706913, 9236.815263694823, 9245.260379169786, 6449.3873082057125, 6483.837904052436, 6485.513586480938, 6501.0, 6535.5425120241125, 7595.962020189863, 7659.4255430841795, 7670.8082297943, 7676.80807979805, 7677.385809135368, 6788.524803263724, 6935.618540980093, 6945.895811562729, 7019.08751862275, 7113.253108423748, 6812.352826481477, 6816.420604248694, 6877.690503927235, 6892.310768923067, 6906.205786334435, 6041.969790151021, 6262.248530176408, 6267.4045965633195, 6275.811725648238, 6277.340879207617, 4722.291656251589, 4773.522647735199, 4801.567858892577, 4842.280990248552, 4934.777934993006, 4674.976625116853, 4720.504347043511, 4836.709797412166, 4893.0916942393205, 5035.370578677665], "value": 4175.972115480645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.858856457417, 356.89293212036387, 365.87560229521955, 370.4850258141186, 370.76456450154177, 4282.507511636163, 5084.008618319429, 5191.480851914806, 5202.505761952613, 5279.392869819972, 4028.959710402895, 4282.635975942041, 4739.857804265877, 4956.801727930879, 5181.922271165936, 3763.322601931651, 4086.346184610463, 4089.3661482470266, 4091.4272001919753, 4126.236646220451, 3703.3889408247605, 3761.755485893422, 4117.773522456264, 4123.165191489788, 4141.716068018924, 3368.8652453901814, 3750.1749615084636, 4077.7961101944934, 4083.734557253769, 4093.2427500912277, 4030.9395359069636, 4056.0873803394297, 4067.3898915162667, 4072.409500622408, 4096.91806163875, 3148.7008734170217, 3587.318409502186, 3598.7660802048044, 3599.226166374242, 3599.7120230381506, 4053.8783836484954, 4634.976825115853, 4790.80836766529, 4848.806047758086, 4993.90012199754, 4700.059988002379, 4709.317149013408, 4719.5752382285755, 4732.053589282157, 4769.451513075971, 4535.841245556423, 4737.0, 4887.242477416006, 5421.322334708161, 5548.750306236229, 3826.827792749305, 3870.678712760403, 3976.16500534888, 3980.900477488086, 3994.0613955720382, 4955.481608058283, 5451.754671039813, 5715.514181294595, 5778.855528611776, 5806.390329015477, 3492.2666240089357, 4046.959530404717, 4051.8379264829077, 4058.6347228749555, 4064.7154699171365, 4070.7964601769795, 4097.955021469506, 4098.73358231714, 4105.753654780723, 4175.513446272396, 4412.977935110304, 4655.6973796703105, 4657.347971284023, 4689.554492323224, 4691.1555919934735, 4579.519150489217, 4781.737004464788, 4855.053264613366, 4891.486393928657, 5443.401225865152, 1178.8880056394628, 1197.8382918306093, 1278.367208231931, 1291.9599722223513, 1306.2496255018132, 850.7958090058341, 858.3991206155716, 916.5737931861717, 932.3473568502076, 948.3029972969862, 4518.0, 4814.15752243356, 4874.683145595525, 4888.804447822118, 4896.890474548687, 4588.564086411786, 4881.144530449656, 4994.850154495371, 5032.748362581856, 5250.291210686509, 4188.895277618084, 4420.58004489573, 4847.927281090787, 4855.514448555116, 4894.657373983858, 2215.5679642469563, 2496.3883848457244, 2777.0976880836693, 2789.302674331417, 2808.4944709951997, 5350.491703288181, 5577.860553486114, 5596.977632078632, 5604.0, 5610.747516361774, 5055.317532133115, 5750.5112065474905, 5772.740226689809, 5794.536437085023, 5822.7670893163795, 4932.556069953722, 5244.542139181742, 5524.475174858382, 5798.94201057984, 5863.70681465925, 4926.5073492650445, 5193.20895746115, 5293.0, 5583.664980101207, 5691.658500489983, 5333.493258168654, 5347.893042139213, 5561.63849349791, 5576.693281869536, 5595.944040559542, 4736.621070314435, 4870.464248932685, 5842.640481722941, 5855.10635427436, 5857.297124345106, 3122.7814053016073, 3340.1148695595202, 3641.471986561987, 3645.65366290202, 3840.9423858642704, 5078.314427552233, 5275.5996021809215, 5298.6820790751135, 5545.750441230235, 5567.55459563242, 4848.61211103118, 5295.523402893758, 5344.182977787571, 5473.059397233985, 5514.283143191359, 4527.773611319355, 4789.712617242976, 5193.636445448782, 5480.287562616834, 5485.501888451431, 4724.905501890012, 4827.372441582572, 5059.544640982329, 5067.442581315979, 5128.256392826457, 5065.0, 5160.896782064263, 5310.654807437504, 5347.1979203119845, 5535.228726807383, 4358.932061644918, 4837.588804951619, 4851.636127290397, 5408.567314614743, 5444.128939369682], "value": 3978.7571407082237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.0379506641368, 760.3992845651932, 800.5076877720202, 806.6168569929284, 824.7237175546192, 12278.0, 15150.015249008835, 15151.0, 15197.848021519781, 15212.467563635275, 13840.0, 14002.0, 14005.719885602308, 14008.719825603483, 14025.719485610307, 866.1295398124876, 879.1384443245623, 884.2395539835736, 902.9638814447447, 903.9683611073614, 896.5562046786849, 901.5672477210943, 902.2826852652157, 903.3631289940577, 910.5765818894213, 922.3128769067055, 939.2720641502856, 940.5485367023832, 942.3309450290302, 943.188857582482, 934.4766930518863, 935.8081593273431, 936.3679516326521, 936.5972631768371, 942.1238248428958, 694.4097517110466, 696.1611258433576, 696.6238231355062, 696.6273043921528, 696.6516741629185, 14874.553763387115, 15315.0, 15369.615759605987, 15515.99146055514, 15730.89883708141, 13607.931960340235, 13917.373718182705, 13931.651708707263, 13971.301434928315, 14168.929155354157, 11570.768584628344, 11616.303021818634, 11676.0, 11937.701557461047, 12128.696782580506, 3558.6619271169197, 3669.5229620149476, 3671.816409179557, 3678.9080272993124, 3771.6605505504635, 100372.0, 100388.9961100387, 103731.0, 103826.0, 106424.0, 10455.581776728846, 10536.420496872746, 10599.894001060045, 10857.992565055762, 11068.944655276671, 21784.673229901564, 22267.331980040624, 22305.884705764645, 22318.330450086523, 22385.216517422137, 7451.590162541004, 7499.512531685423, 7529.360004399688, 7619.847603047908, 7760.611969401507, 4048.7773172475795, 4132.628063474302, 4137.482814648169, 4147.0461793787, 4187.560306167869, 2803.89246247731, 3518.0149558123253, 3520.2783429396914, 3520.771149875301, 3525.488804123439, 640.4620119099978, 649.3506493506494, 652.2336254900473, 654.5189285874878, 665.8568407792347, 624.7063879976404, 632.6393955445352, 640.385230179024, 666.3103687683224, 689.741346994877, 9348.392354496937, 9519.904800952041, 9545.809083818423, 9748.561314740853, 9808.068233517804, 625.3996163682824, 640.466370558376, 671.2610758077461, 680.6664734280266, 686.7218776395567, 4455.9554404456185, 4724.196886529298, 4771.165046116916, 4796.760161991956, 4848.0788650156355, 94637.0, 97815.0, 98689.0, 98717.01282987221, 101486.0, 48310.46170895732, 57984.0, 74003.0, 89466.55266723756, 92075.0, 64991.02513462397, 66503.66748166324, 68140.29649258808, 73579.0, 75507.62246188734, 7284.635768211674, 10077.093061624195, 10145.0, 10153.543090560795, 10195.847062293924, 37824.43263350997, 45155.32267016063, 45172.0, 46774.766126169154, 46848.765756171, 48689.26966095441, 61912.38087619247, 62966.0, 64279.0, 64804.675976619816, 11946.867565424403, 12235.388230588613, 12401.317927514074, 12454.31501267439, 12610.991120710505, 13961.0, 14110.490626246674, 14178.432862685377, 14492.275386230436, 14956.252187390804, 12389.194702344319, 12701.174423662433, 12773.0, 12996.0, 13184.274864882524, 12891.097623166663, 12991.805122923353, 13013.024023198488, 13237.669058273621, 13416.12795168311, 12610.558630447891, 12612.783740146204, 12694.492220311084, 12901.677458063623, 12951.935240323737, 10767.676969690921, 10805.841589599802, 10849.837252441062, 11171.944140279247, 11421.1434142438, 7134.183211981773, 7142.785716428515, 8469.950817705745, 10001.449920254456, 10217.591296348062], "value": 7282.2172165117545, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 111.0, 114.0, 112.0, 112.0, 113.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 110.0, 150.0, 114.0, 111.0, 110.0, 111.0, 131.0, 112.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 185.0, 187.0, 180.0, 183.0, 184.0, 185.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 93.0, 61.0, 61.0, 60.0, 62.0, 82.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 69.0, 70.0, 70.0, 86.0, 70.0, 69.0, 87.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 87.0, 86.0, 86.0, 90.0, 87.0, 87.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 94.0, 92.0, 94.0, 93.0, 94.0, 103.0, 122.0, 94.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 84.0, 99.0, 140.0, 138.0, 87.0, 87.0, 88.0, 86.0], "value": 98.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 86.0, 94.0, 94.0, 82.0, 63.0, 61.0, 62.0, 60.0], "value": 70.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 83.0, 75.0, 73.0, 69.0, 69.0, 69.0, 69.0, 70.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 87.0, 87.0, 89.0, 87.0, 87.0, 91.0, 91.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 145.0, 144.0, 137.0, 146.0, 141.0, 144.0, 180.0, 139.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 174.0, 172.0, 175.0, 175.0, 228.0, 227.0, 171.0, 175.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 87.0, 85.0, 71.0, 67.0, 66.0, 66.0, 65.0, 66.0], "value": 71.5, "unit": "score"}]}]} 18:32:29 INFO - Return code: 0 18:32:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:29 ERROR - # TBPL SUCCESS # 18:32:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:32:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:32:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:32:29 INFO - 2017-02-24 18:32:29.111 screenresolution[1932:21688] starting screenresolution argv=screenresolution get 18:32:29 INFO - 2017-02-24 18:32:29.114 screenresolution[1932:21688] Display 0: 1600x1200x32@60 18:32:29 INFO - 2017-02-24 18:32:29.120 screenresolution[1933:21691] starting screenresolution argv=screenresolution list 18:32:29 INFO - Available Modes on Display 0 18:32:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:32:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:32:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:32:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:32:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:32:29 INFO - Intel Iris: 18:32:29 INFO - Chipset Model: Intel Iris 18:32:29 INFO - Type: GPU 18:32:29 INFO - Bus: Built-In 18:32:29 INFO - VRAM (Dynamic, Max): 1536 MB 18:32:29 INFO - Vendor: Intel (0x8086) 18:32:29 INFO - Device ID: 0x0a2e 18:32:29 INFO - Revision ID: 0x0009 18:32:29 INFO - Displays: 18:32:29 INFO - Display: 18:32:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:32:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:32:29 INFO - Display Serial Number: 1600x1200 60 18:32:29 INFO - Main Display: Yes 18:32:29 INFO - Mirror: Off 18:32:29 INFO - Online: Yes 18:32:29 INFO - Rotation: Supported 18:32:29 INFO - Return code: 0 18:32:29 INFO - Running post-action listener: _resource_record_post_action 18:32:29 INFO - [mozharness: 2017-02-25 02:32:29.358993Z] Finished run-tests step (success) 18:32:29 INFO - Running post-run listener: _resource_record_post_run 18:32:29 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 720896; Write bytes: 512387072; Read time: 7; Write time: 1687 18:32:29 INFO - TinderboxPrint: CPU usage
53.8% 18:32:29 INFO - TinderboxPrint: I/O read bytes / time
720,896 / 7 18:32:29 INFO - TinderboxPrint: I/O write bytes / time
512,387,072 / 1,687 18:32:29 INFO - TinderboxPrint: CPU idle
2,220.0 (46.2%) 18:32:29 INFO - TinderboxPrint: CPU system
962.0 (20.0%) 18:32:29 INFO - TinderboxPrint: CPU user
1,623.7 (33.8%) 18:32:29 INFO - TinderboxPrint: Swap in / out
210,755,584 / 0 18:32:29 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208105984; Write bytes: 204933120; Read time: 15714; Write time: 430 18:32:29 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 684032; Write bytes: 296488960; Read time: 7; Write time: 1219 18:32:29 INFO - Running post-run listener: _upload_blobber_files 18:32:29 INFO - Blob upload gear active. 18:32:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:29 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 18:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:32:30 INFO - (blobuploader) - INFO - Open directory for files ... 18:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:31 INFO - (blobuploader) - INFO - Done attempting. 18:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:32:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:32 INFO - (blobuploader) - INFO - Done attempting. 18:32:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:32:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:32:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:33 INFO - (blobuploader) - INFO - Done attempting. 18:32:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:32:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:32:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:35 INFO - (blobuploader) - INFO - Done attempting. 18:32:35 INFO - (blobuploader) - INFO - Iteration through files over. 18:32:35 INFO - Return code: 0 18:32:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:32:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:32:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75510feb17bb693a500970da9a0200654cb38321db09d96fa5c509df3dedb014a1d0d3a7a0c980194449a93799745b03d50bd59c24c765992f4f661b4c2a28ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ffe54edfc4c25eb5de5a2b0980e582077faef4d7da15d959bdedc01b972fc8b02bf41d4d0072f3bbe8083aeb36b8734c1caac6163104f17c7b210d78fe0e5ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b76ae9d05c7b1a2033f734e79589b8e06820df49c61ea10b2190e0f1daefcbf02b8a7d23349d5e09f8d6c4ef9e32c4bd7db1d1ba988821bb7cc5fc5e25878e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec8d2dec3977c674c8b6285118bcd27cb0204f4077c6b00499a43e1bc02eef0a7ec8de6434b85ff9652712dd61c8052938f4934202f4a3eec1984e0ba9874eb"} 18:32:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:35 INFO - Contents: 18:32:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75510feb17bb693a500970da9a0200654cb38321db09d96fa5c509df3dedb014a1d0d3a7a0c980194449a93799745b03d50bd59c24c765992f4f661b4c2a28ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ffe54edfc4c25eb5de5a2b0980e582077faef4d7da15d959bdedc01b972fc8b02bf41d4d0072f3bbe8083aeb36b8734c1caac6163104f17c7b210d78fe0e5ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b76ae9d05c7b1a2033f734e79589b8e06820df49c61ea10b2190e0f1daefcbf02b8a7d23349d5e09f8d6c4ef9e32c4bd7db1d1ba988821bb7cc5fc5e25878e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec8d2dec3977c674c8b6285118bcd27cb0204f4077c6b00499a43e1bc02eef0a7ec8de6434b85ff9652712dd61c8052938f4934202f4a3eec1984e0ba9874eb"} 18:32:35 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:35 INFO - Copying logs to upload dir... 18:32:35 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.402570 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-02-24 18:32:35.807394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 18:32:35.811108) ========= 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.39VOq46vRp/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.sm6vHZzIiC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/Firefox/sha512/75510feb17bb693a500970da9a0200654cb38321db09d96fa5c509df3dedb014a1d0d3a7a0c980194449a93799745b03d50bd59c24c765992f4f661b4c2a28ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ffe54edfc4c25eb5de5a2b0980e582077faef4d7da15d959bdedc01b972fc8b02bf41d4d0072f3bbe8083aeb36b8734c1caac6163104f17c7b210d78fe0e5ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b76ae9d05c7b1a2033f734e79589b8e06820df49c61ea10b2190e0f1daefcbf02b8a7d23349d5e09f8d6c4ef9e32c4bd7db1d1ba988821bb7cc5fc5e25878e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec8d2dec3977c674c8b6285118bcd27cb0204f4077c6b00499a43e1bc02eef0a7ec8de6434b85ff9652712dd61c8052938f4934202f4a3eec1984e0ba9874eb"} build_url:https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/JxGwwgPdQJeld8llBAu9Ng/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75510feb17bb693a500970da9a0200654cb38321db09d96fa5c509df3dedb014a1d0d3a7a0c980194449a93799745b03d50bd59c24c765992f4f661b4c2a28ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ffe54edfc4c25eb5de5a2b0980e582077faef4d7da15d959bdedc01b972fc8b02bf41d4d0072f3bbe8083aeb36b8734c1caac6163104f17c7b210d78fe0e5ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b76ae9d05c7b1a2033f734e79589b8e06820df49c61ea10b2190e0f1daefcbf02b8a7d23349d5e09f8d6c4ef9e32c4bd7db1d1ba988821bb7cc5fc5e25878e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec8d2dec3977c674c8b6285118bcd27cb0204f4077c6b00499a43e1bc02eef0a7ec8de6434b85ff9652712dd61c8052938f4934202f4a3eec1984e0ba9874eb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 18:32:35.847909) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:32:35.849845) ========= 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.39VOq46vRp/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.sm6vHZzIiC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005411 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:32:35.900444) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 18:32:35.900761) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 18:32:35.901094) ========= ========= Total master_lag: 0.20 =========