builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0236 starttime: 1481941322.96 results: success (0) buildid: 20161216165049 builduid: b354778d7e4b48d5931cdb6c56570d17 revision: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:02.957352) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:02.957831) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:02.958178) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:02.984508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:02.984889) ========= 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.6EsO61lbS0/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.w9FyembMKd/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-16 18:22:02-- 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% 138M=0s 2016-12-16 18:22:03 (138 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086668 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.095672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.096007) ========= 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.6EsO61lbS0/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.w9FyembMKd/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.024912 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.144627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.144999) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 --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 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6EsO61lbS0/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.w9FyembMKd/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-16 18:22:03,239 truncating revision to first 12 chars 2016-12-16 18:22:03,239 Setting DEBUG logging. 2016-12-16 18:22:03,239 attempt 1/10 2016-12-16 18:22:03,239 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34a1ab064cb5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 18:22:03,540 unpacking tar archive at: mozilla-central-34a1ab064cb5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.577724 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.746933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.747303) ========= script_repo_revision: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.747780) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.748075) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 18:22:03.768218) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-16 18:22:03.768681) ========= /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.6EsO61lbS0/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.w9FyembMKd/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:03 INFO - MultiFileLogger online at 20161216 18:22:03 in /builds/slave/test 18:22:03 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:22:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:03 INFO - {'append_to_log': False, 18:22:03 INFO - 'base_work_dir': '/builds/slave/test', 18:22:03 INFO - 'blob_upload_branch': 'Firefox', 18:22:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:03 INFO - 'branch': 'Firefox', 18:22:03 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:03 INFO - 'config_files': ('talos/mac_config.py',), 18:22:03 INFO - 'default_actions': ('clobber', 18:22:03 INFO - 'read-buildbot-config', 18:22:03 INFO - 'download-and-extract', 18:22:03 INFO - 'populate-webroot', 18:22:03 INFO - 'create-virtualenv', 18:22:03 INFO - 'install', 18:22:03 INFO - 'run-tests'), 18:22:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:03 INFO - 'download_minidump_stackwalk': True, 18:22:03 INFO - 'download_symbols': 'ondemand', 18:22:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:03 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:03 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:03 INFO - 'installer_path': 'installer.exe', 18:22:03 INFO - 'log_level': 'info', 18:22:03 INFO - 'log_name': 'talos', 18:22:03 INFO - 'log_to_console': True, 18:22:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:22:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:22:03 INFO - 'opt_config_files': (), 18:22:03 INFO - 'pip_index': False, 18:22:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:22:03 INFO - 'cmd': ('bash', 18:22:03 INFO - '-c', 18:22:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:22:03 INFO - 'enabled': True, 18:22:03 INFO - 'halt_on_failure': False, 18:22:03 INFO - 'name': 'check_screen_resolution'},), 18:22:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:22:03 INFO - 'cmd': ('bash', 18:22:03 INFO - '-c', 18:22:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:22:03 INFO - 'enabled': True, 18:22:03 INFO - 'halt_on_failure': False, 18:22:03 INFO - 'name': 'check_screen_resolution'},), 18:22:03 INFO - 'run_cmd_checks_enabled': True, 18:22:03 INFO - 'sps_profile': False, 18:22:03 INFO - 'sps_profile_interval': 0, 18:22:03 INFO - 'suite': 'dromaeojs', 18:22:03 INFO - 'system_bits': '32', 18:22:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:03 INFO - 'title': 't-yosemite-r7-0236', 18:22:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:03 INFO - 'use_talos_json': True, 18:22:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:03 INFO - 'work_dir': 'build'} 18:22:03 INFO - [mozharness: 2016-12-17 02:22:03.970267Z] Running clobber step. 18:22:03 INFO - Running pre-action listener: _resource_record_pre_action 18:22:03 INFO - Running main action method: clobber 18:22:03 INFO - rmtree: /builds/slave/test/build 18:22:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:06 INFO - Running post-action listener: _resource_record_post_action 18:22:06 INFO - [mozharness: 2016-12-17 02:22:06.388595Z] Finished clobber step (success) 18:22:06 INFO - [mozharness: 2016-12-17 02:22:06.388837Z] Running read-buildbot-config step. 18:22:06 INFO - Running pre-action listener: _resource_record_pre_action 18:22:06 INFO - Running main action method: read_buildbot_config 18:22:06 INFO - Using buildbot properties: 18:22:06 INFO - { 18:22:06 INFO - "project": "", 18:22:06 INFO - "product": "firefox", 18:22:06 INFO - "script_repo_revision": "production", 18:22:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:22:06 INFO - "repository": "", 18:22:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:22:06 INFO - "buildid": "20161216165049", 18:22:06 INFO - "pgo_build": "False", 18:22:06 INFO - "basedir": "/builds/slave/test", 18:22:06 INFO - "buildnumber": 45, 18:22:06 INFO - "slavename": "t-yosemite-r7-0236", 18:22:06 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:22:06 INFO - "platform": "yosemite_r7", 18:22:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:22:06 INFO - "branch": "mozilla-central", 18:22:06 INFO - "repo_path": "mozilla-central", 18:22:06 INFO - "revision": "34a1ab064cb5b868fa75cb74d052e978eb34d6c1", 18:22:06 INFO - "stage_platform": "macosx64", 18:22:06 INFO - "builduid": "b354778d7e4b48d5931cdb6c56570d17", 18:22:06 INFO - "slavebuilddir": "test" 18:22:06 INFO - } 18:22:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 18:22:06 INFO - Running post-action listener: _resource_record_post_action 18:22:06 INFO - [mozharness: 2016-12-17 02:22:06.394317Z] Finished read-buildbot-config step (success) 18:22:06 INFO - [mozharness: 2016-12-17 02:22:06.394508Z] Running download-and-extract step. 18:22:06 INFO - Running pre-action listener: _resource_record_pre_action 18:22:06 INFO - Running main action method: download_and_extract 18:22:06 INFO - mkdir: /builds/slave/test/build/tests 18:22:06 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:22:06 INFO - trying https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 18:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 18:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 18:22:07 INFO - Downloaded 1199 bytes. 18:22:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 18:22:07 INFO - Using the following test package requirements: 18:22:07 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 18:22:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 18:22:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 18:22:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'jsshell-mac.zip'], 18:22:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 18:22:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 18:22:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 18:22:07 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 18:22:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 18:22:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 18:22:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:22:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 18:22:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:22:07 INFO - Fetch https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 18:22:08 INFO - Expected file size: 16277914 18:22:08 INFO - Obtained file size: 16277914 18:22:15 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:22:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 18:22:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:22:15 INFO - Fetch https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 18:22:16 INFO - Expected file size: 16277914 18:22:16 INFO - Obtained file size: 16277914 18:22:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 18:22:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:22:22 INFO - Fetch https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 18:22:23 INFO - Expected file size: 14409734 18:22:23 INFO - Obtained file size: 14409734 18:22:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:23 INFO - trying https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:22:23 INFO - Downloading https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 18:22:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 18:22:25 INFO - Downloaded 69566110 bytes. 18:22:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:22:25 INFO - mkdir: /builds/slave/test/properties 18:22:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:25 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:25 INFO - Contents: 18:22:25 INFO - build_url:https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:22:26 INFO - Running post-action listener: _resource_record_post_action 18:22:26 INFO - Running post-action listener: set_extra_try_arguments 18:22:26 INFO - [mozharness: 2016-12-17 02:22:26.397190Z] Finished download-and-extract step (success) 18:22:26 INFO - [mozharness: 2016-12-17 02:22:26.397309Z] Running populate-webroot step. 18:22:26 INFO - Running pre-action listener: _resource_record_pre_action 18:22:26 INFO - Running main action method: populate_webroot 18:22:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:26 INFO - '--tppagecycles', 18:22:26 INFO - '7'], 18:22:26 INFO - 'tests': ['tsvgm']}}, 18:22:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['damp', 'tps']}, 18:22:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'tests': ['damp', 'tps']}, 18:22:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['dromaeo_dom']}, 18:22:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['basic_compositor_video']}, 18:22:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:22:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['a11yr', 18:22:26 INFO - 'ts_paint', 18:22:26 INFO - 'tpaint', 18:22:26 INFO - 'sessionrestore', 18:22:26 INFO - 'sessionrestore_no_auto_restore', 18:22:26 INFO - 'tabpaint']}, 18:22:26 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:26 INFO - 'ts_paint', 18:22:26 INFO - 'tpaint', 18:22:26 INFO - 'sessionrestore', 18:22:26 INFO - 'sessionrestore_no_auto_restore', 18:22:26 INFO - 'tabpaint']}, 18:22:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:26 INFO - 'ts_paint', 18:22:26 INFO - 'tpaint', 18:22:26 INFO - 'sessionrestore', 18:22:26 INFO - 'sessionrestore_no_auto_restore', 18:22:26 INFO - 'tabpaint']}, 18:22:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:26 INFO - 'ts_paint', 18:22:26 INFO - 'tpaint', 18:22:26 INFO - 'sessionrestore', 18:22:26 INFO - 'sessionrestore_no_auto_restore', 18:22:26 INFO - 'tabpaint']}, 18:22:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['a11yr', 18:22:26 INFO - 'ts_paint', 18:22:26 INFO - 'tpaint', 18:22:26 INFO - 'sessionrestore', 18:22:26 INFO - 'sessionrestore_no_auto_restore', 18:22:26 INFO - 'tabpaint']}, 18:22:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['a11yr', 18:22:26 INFO - 'ts_paint', 18:22:26 INFO - 'tpaint', 18:22:26 INFO - 'sessionrestore', 18:22:26 INFO - 'sessionrestore_no_auto_restore', 18:22:26 INFO - 'tabpaint']}, 18:22:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['tsvgx', 18:22:26 INFO - 'tsvgr_opacity', 18:22:26 INFO - 'tart', 18:22:26 INFO - 'tscrollx', 18:22:26 INFO - 'cart', 18:22:26 INFO - 'tsvg_static']}, 18:22:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:26 INFO - 'tsvgr_opacity', 18:22:26 INFO - 'tart', 18:22:26 INFO - 'tscrollx', 18:22:26 INFO - 'cart', 18:22:26 INFO - 'tsvg_static']}, 18:22:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'talos_options': ['--disable-e10s'], 18:22:26 INFO - 'tests': ['tp5o']}, 18:22:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:22:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'talos_options': ['--disable-e10s', 18:22:26 INFO - '--xperf_path', 18:22:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:26 INFO - 'tests': ['tp5n']}, 18:22:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:22:26 INFO - 'talos_options': ['--xperf_path', 18:22:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:26 INFO - 'tests': ['tp5n']}}, 18:22:26 INFO - 'talos.zip': {'path': '', 18:22:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:26 INFO - Running post-action listener: _resource_record_post_action 18:22:26 INFO - [mozharness: 2016-12-17 02:22:26.416214Z] Finished populate-webroot step (success) 18:22:26 INFO - [mozharness: 2016-12-17 02:22:26.416343Z] Running create-virtualenv step. 18:22:26 INFO - Running pre-action listener: _resource_record_pre_action 18:22:26 INFO - Running main action method: create_virtualenv 18:22:26 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:26 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:22:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:26 INFO - Using real prefix '/tools/python27' 18:22:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:27 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:30 INFO - Installing pip.................done. 18:22:30 INFO - Return code: 0 18:22:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:22:30 INFO - Reading from file tmpfile_stdout 18:22:30 INFO - Output received: 18:22:30 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:22:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:30 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:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:30 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:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:30 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:30 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:22:30 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:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:30 INFO - 'HOME': '/Users/cltbld', 18:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:30 INFO - 'LOGNAME': 'cltbld', 18:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:30 INFO - 'MOZ_NO_REMOTE': '1', 18:22:30 INFO - 'NO_EM_RESTART': '1', 18:22:30 INFO - 'PAGER': '/bin/cat', 18:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:30 INFO - 'PWD': '/builds/slave/test', 18:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:30 INFO - 'SHELL': '/bin/bash', 18:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:30 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:30 INFO - 'USER': 'cltbld', 18:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:30 INFO - 'XPC_FLAGS': '0x0', 18:22:30 INFO - 'XPC_SERVICE_NAME': '0', 18:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:30 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:22:30 INFO - Cleaning up... 18:22:30 INFO - Return code: 0 18:22:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:30 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:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:30 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:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:30 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:30 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:22:30 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:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:30 INFO - 'HOME': '/Users/cltbld', 18:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:30 INFO - 'LOGNAME': 'cltbld', 18:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:30 INFO - 'MOZ_NO_REMOTE': '1', 18:22:30 INFO - 'NO_EM_RESTART': '1', 18:22:30 INFO - 'PAGER': '/bin/cat', 18:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:30 INFO - 'PWD': '/builds/slave/test', 18:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:30 INFO - 'SHELL': '/bin/bash', 18:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:30 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:30 INFO - 'USER': 'cltbld', 18:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:30 INFO - 'XPC_FLAGS': '0x0', 18:22:30 INFO - 'XPC_SERVICE_NAME': '0', 18:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:30 INFO - Downloading/unpacking psutil>=3.1.1 18:22:30 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:22:30 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:22:30 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:22:30 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:22:30 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:22:30 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:22:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:34 INFO - Installing collected packages: psutil 18:22:34 INFO - Running setup.py install for psutil 18:22:34 INFO - building 'psutil._psutil_osx' extension 18:22:34 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:22:34 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:22:35 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:22:35 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:22:35 INFO - building 'psutil._psutil_posix' extension 18:22:35 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:22:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:22:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:22:35 INFO - ^ 18:22:35 INFO - 1 warning generated. 18:22:35 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:22:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:35 INFO - Successfully installed psutil 18:22:35 INFO - Cleaning up... 18:22:35 INFO - Return code: 0 18:22:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:22:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:35 INFO - 'HOME': '/Users/cltbld', 18:22:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:35 INFO - 'LOGNAME': 'cltbld', 18:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:35 INFO - 'MOZ_NO_REMOTE': '1', 18:22:35 INFO - 'NO_EM_RESTART': '1', 18:22:35 INFO - 'PAGER': '/bin/cat', 18:22:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:35 INFO - 'PWD': '/builds/slave/test', 18:22:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:35 INFO - 'SHELL': '/bin/bash', 18:22:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:35 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:35 INFO - 'USER': 'cltbld', 18:22:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:35 INFO - 'XPC_FLAGS': '0x0', 18:22:35 INFO - 'XPC_SERVICE_NAME': '0', 18:22:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:38 INFO - Installing collected packages: mozsystemmonitor 18:22:38 INFO - Running setup.py install for mozsystemmonitor 18:22:38 INFO - Successfully installed mozsystemmonitor 18:22:38 INFO - Cleaning up... 18:22:38 INFO - Return code: 0 18:22:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:38 INFO - 'HOME': '/Users/cltbld', 18:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:38 INFO - 'LOGNAME': 'cltbld', 18:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:38 INFO - 'MOZ_NO_REMOTE': '1', 18:22:38 INFO - 'NO_EM_RESTART': '1', 18:22:38 INFO - 'PAGER': '/bin/cat', 18:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:38 INFO - 'PWD': '/builds/slave/test', 18:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:38 INFO - 'SHELL': '/bin/bash', 18:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:38 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:38 INFO - 'USER': 'cltbld', 18:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:38 INFO - 'XPC_FLAGS': '0x0', 18:22:38 INFO - 'XPC_SERVICE_NAME': '0', 18:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:38 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:42 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:42 INFO - warning: no files found matching '*.txt' 18:22:42 INFO - no previously-included directories found matching 'build' 18:22:42 INFO - no previously-included directories found matching 'dist' 18:22:42 INFO - no previously-included directories found matching '.git*' 18:22:42 INFO - Installing collected packages: jsonschema, functools32 18:22:42 INFO - Running setup.py install for functools32 18:22:42 INFO - warning: no files found matching '*.txt' 18:22:42 INFO - no previously-included directories found matching 'build' 18:22:42 INFO - no previously-included directories found matching 'dist' 18:22:42 INFO - no previously-included directories found matching '.git*' 18:22:42 INFO - Successfully installed jsonschema functools32 18:22:42 INFO - Cleaning up... 18:22:42 INFO - Return code: 0 18:22:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:42 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:42 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:22:42 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:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:42 INFO - 'HOME': '/Users/cltbld', 18:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:42 INFO - 'LOGNAME': 'cltbld', 18:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:42 INFO - 'MOZ_NO_REMOTE': '1', 18:22:42 INFO - 'NO_EM_RESTART': '1', 18:22:42 INFO - 'PAGER': '/bin/cat', 18:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:42 INFO - 'PWD': '/builds/slave/test', 18:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:42 INFO - 'SHELL': '/bin/bash', 18:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:42 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:42 INFO - 'USER': 'cltbld', 18:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:42 INFO - 'XPC_FLAGS': '0x0', 18:22:42 INFO - 'XPC_SERVICE_NAME': '0', 18:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:42 INFO - Cleaning up... 18:22:42 INFO - Return code: 0 18:22:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:42 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:42 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:22:42 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:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:42 INFO - 'HOME': '/Users/cltbld', 18:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:42 INFO - 'LOGNAME': 'cltbld', 18:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:42 INFO - 'MOZ_NO_REMOTE': '1', 18:22:42 INFO - 'NO_EM_RESTART': '1', 18:22:42 INFO - 'PAGER': '/bin/cat', 18:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:42 INFO - 'PWD': '/builds/slave/test', 18:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:42 INFO - 'SHELL': '/bin/bash', 18:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:42 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:42 INFO - 'USER': 'cltbld', 18:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:42 INFO - 'XPC_FLAGS': '0x0', 18:22:42 INFO - 'XPC_SERVICE_NAME': '0', 18:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:42 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:42 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:22:42 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:22:42 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:22:42 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:22:42 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:22:42 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:22:45 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:45 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:22:45 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:22:45 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:22:45 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:22:45 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:22:45 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:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:46 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:22:46 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:22:46 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:22:46 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:22:46 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:22:46 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:22:46 INFO - Downloading docopt-0.6.1.tar.gz 18:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:46 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:46 INFO - Running setup.py install for blobuploader 18:22:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:47 INFO - Running setup.py install for requests 18:22:47 INFO - Running setup.py install for docopt 18:22:47 INFO - Successfully installed blobuploader requests docopt 18:22:47 INFO - Cleaning up... 18:22:47 INFO - Return code: 0 18:22:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22: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:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:47 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:47 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:22:47 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:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:47 INFO - 'HOME': '/Users/cltbld', 18:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:47 INFO - 'LOGNAME': 'cltbld', 18:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:47 INFO - 'MOZ_NO_REMOTE': '1', 18:22:47 INFO - 'NO_EM_RESTART': '1', 18:22:47 INFO - 'PAGER': '/bin/cat', 18:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:47 INFO - 'PWD': '/builds/slave/test', 18:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:47 INFO - 'SHELL': '/bin/bash', 18:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:47 INFO - 'USER': 'cltbld', 18:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:47 INFO - 'XPC_FLAGS': '0x0', 18:22:47 INFO - 'XPC_SERVICE_NAME': '0', 18:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-sCl5P2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tRtS9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tc9sWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Q0tPD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-R4cwPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-bddCQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ELVGmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-4cdz8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-4SwSfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:48 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-BnCsGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-xEa4JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-n2B3Mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-HukgAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Y4Ffyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8DWWVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-l6XRUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:49 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-NjV_Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:49 INFO - Running setup.py install for manifestparser 18:22:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:49 INFO - Running setup.py install for mozcrash 18:22:50 INFO - Running setup.py install for mozdebug 18:22:50 INFO - Running setup.py install for mozdevice 18:22:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Running setup.py install for mozfile 18:22:50 INFO - Running setup.py install for mozhttpd 18:22:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Running setup.py install for mozinfo 18:22:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Running setup.py install for mozInstall 18:22:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Running setup.py install for mozleak 18:22:51 INFO - Running setup.py install for mozlog 18:22:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for moznetwork 18:22:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozprocess 18:22:51 INFO - Running setup.py install for mozprofile 18:22:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozrunner 18:22:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozscreenshot 18:22:52 INFO - Running setup.py install for moztest 18:22:52 INFO - Running setup.py install for mozversion 18:22:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:52 INFO - Cleaning up... 18:22:52 INFO - Return code: 0 18:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22: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:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:52 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:52 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:22:52 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:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:52 INFO - 'HOME': '/Users/cltbld', 18:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:52 INFO - 'LOGNAME': 'cltbld', 18:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:52 INFO - 'MOZ_NO_REMOTE': '1', 18:22:52 INFO - 'NO_EM_RESTART': '1', 18:22:52 INFO - 'PAGER': '/bin/cat', 18:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:52 INFO - 'PWD': '/builds/slave/test', 18:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:52 INFO - 'SHELL': '/bin/bash', 18:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:52 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:52 INFO - 'USER': 'cltbld', 18:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:52 INFO - 'XPC_FLAGS': '0x0', 18:22:52 INFO - 'XPC_SERVICE_NAME': '0', 18:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-togU4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:52 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:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Bn8H7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:52 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:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-J2k0Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:52 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:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:52 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-jCVr9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2TZAzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-jZBssX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-LjoHE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1zBYKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TlE_ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-BB8X4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-WXDOAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:53 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:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-a9FTri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:53 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-777w5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:54 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:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:54 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-fwaMyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:54 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:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:54 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1F_psK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:54 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:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:54 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2D3ioZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:54 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-P7Xk_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:54 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:22:54 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:22:54 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:22:54 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:22:54 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:22:54 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:22:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:54 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:22:54 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:22:54 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:22:54 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:22:54 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:22:54 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:22:56 INFO - Downloading blessings-1.6.tar.gz 18:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:57 INFO - Installing collected packages: blessings 18:22:57 INFO - Running setup.py install for blessings 18:22:57 INFO - Successfully installed blessings 18:22:57 INFO - Cleaning up... 18:22:57 INFO - Return code: 0 18:22:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:57 INFO - Reading from file tmpfile_stdout 18:22:57 INFO - Current package versions: 18:22:57 INFO - blessings == 1.6 18:22:57 INFO - blobuploader == 1.2.4 18:22:57 INFO - docopt == 0.6.1 18:22:57 INFO - functools32 == 3.2.3-2 18:22:57 INFO - jsonschema == 2.5.1 18:22:57 INFO - manifestparser == 1.1 18:22:57 INFO - mozInstall == 1.12 18:22:57 INFO - mozcrash == 1.0 18:22:57 INFO - mozdebug == 0.1 18:22:57 INFO - mozdevice == 0.49 18:22:57 INFO - mozfile == 1.2 18:22:57 INFO - mozhttpd == 0.7 18:22:57 INFO - mozinfo == 0.9 18:22:57 INFO - mozleak == 0.1 18:22:57 INFO - mozlog == 3.4 18:22:57 INFO - moznetwork == 0.27 18:22:57 INFO - mozprocess == 0.23 18:22:57 INFO - mozprofile == 0.28 18:22:57 INFO - mozrunner == 6.13 18:22:57 INFO - mozscreenshot == 0.1 18:22:57 INFO - mozsystemmonitor == 0.3 18:22:57 INFO - moztest == 0.7 18:22:57 INFO - mozversion == 1.4 18:22:57 INFO - psutil == 3.1.1 18:22:57 INFO - requests == 1.2.3 18:22:57 INFO - wsgiref == 0.1.2 18:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:57 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:57 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:22:57 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:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:57 INFO - 'HOME': '/Users/cltbld', 18:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:57 INFO - 'LOGNAME': 'cltbld', 18:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:57 INFO - 'MOZ_NO_REMOTE': '1', 18:22:57 INFO - 'NO_EM_RESTART': '1', 18:22:57 INFO - 'PAGER': '/bin/cat', 18:22:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:57 INFO - 'PWD': '/builds/slave/test', 18:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:57 INFO - 'SHELL': '/bin/bash', 18:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:57 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:57 INFO - 'USER': 'cltbld', 18:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:57 INFO - 'XPC_FLAGS': '0x0', 18:22:57 INFO - 'XPC_SERVICE_NAME': '0', 18:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 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:22:57 INFO - Cleaning up... 18:22:57 INFO - Return code: 0 18:22:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:57 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 0x10c0f23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c068570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2db6ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, '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.6EsO61lbS0/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.w9FyembMKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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:22:57 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:22:57 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:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:57 INFO - 'HOME': '/Users/cltbld', 18:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:57 INFO - 'LOGNAME': 'cltbld', 18:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:57 INFO - 'MOZ_NO_REMOTE': '1', 18:22:57 INFO - 'NO_EM_RESTART': '1', 18:22:57 INFO - 'PAGER': '/bin/cat', 18:22:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:57 INFO - 'PWD': '/builds/slave/test', 18:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:57 INFO - 'SHELL': '/bin/bash', 18:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:22:57 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:57 INFO - 'USER': 'cltbld', 18:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:57 INFO - 'XPC_FLAGS': '0x0', 18:22:57 INFO - 'XPC_SERVICE_NAME': '0', 18:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:22:58 INFO - Cleaning up... 18:22:58 INFO - Return code: 0 18:22:58 INFO - Running post-action listener: _resource_record_post_action 18:22:58 INFO - Running post-action listener: _start_resource_monitoring 18:22:58 INFO - Starting resource monitoring. 18:22:58 INFO - [mozharness: 2016-12-17 02:22:58.148840Z] Finished create-virtualenv step (success) 18:22:58 INFO - [mozharness: 2016-12-17 02:22:58.149735Z] Running install step. 18:22:58 INFO - Running pre-action listener: _resource_record_pre_action 18:22:58 INFO - Running main action method: install 18:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:58 INFO - Reading from file tmpfile_stdout 18:22:58 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:58 INFO - Reading from file tmpfile_stdout 18:22:58 INFO - Output received: 18:22:58 INFO - Usage: mozinstall [options] installer 18:22:58 INFO - Options: 18:22:58 INFO - -h, --help show this help message and exit 18:22:58 INFO - -d DEST, --destination=DEST 18:22:58 INFO - Directory to install application into. [default: 18:22:58 INFO - "/builds/slave/test"] 18:22:58 INFO - --app=APP Application being installed. [default: firefox] 18:22:58 INFO - mkdir: /builds/slave/test/build/application 18:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:23:19 INFO - Reading from file tmpfile_stdout 18:23:19 INFO - Output received: 18:23:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:23:19 INFO - Running post-action listener: _resource_record_post_action 18:23:19 INFO - [mozharness: 2016-12-17 02:23:19.978375Z] Finished install step (success) 18:23:19 INFO - [mozharness: 2016-12-17 02:23:19.978530Z] Running run-tests step. 18:23:19 INFO - Running pre-action listener: _resource_record_pre_action 18:23:19 INFO - Running main action method: run_tests 18:23:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:23:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:23:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:23:19 INFO - 2016-12-16 18:23:19.990 screenresolution[1626:7354] starting screenresolution argv=screenresolution get 18:23:19 INFO - 2016-12-16 18:23:19.993 screenresolution[1626:7354] Display 0: 1600x1200x32@60 18:23:19 INFO - 2016-12-16 18:23:19.998 screenresolution[1627:7357] starting screenresolution argv=screenresolution list 18:23:20 INFO - Available Modes on Display 0 18:23:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:23:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:23:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:23:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:23:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:23:20 INFO - Intel Iris: 18:23:20 INFO - Chipset Model: Intel Iris 18:23:20 INFO - Type: GPU 18:23:20 INFO - Bus: Built-In 18:23:20 INFO - VRAM (Dynamic, Max): 1536 MB 18:23:20 INFO - Vendor: Intel (0x8086) 18:23:20 INFO - Device ID: 0x0a2e 18:23:20 INFO - Revision ID: 0x0009 18:23:20 INFO - Displays: 18:23:20 INFO - Display: 18:23:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:23:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:23:20 INFO - Display Serial Number: 1600x1200 60 18:23:20 INFO - Main Display: Yes 18:23:20 INFO - Mirror: Off 18:23:20 INFO - Online: Yes 18:23:20 INFO - Rotation: Supported 18:23:20 INFO - Return code: 0 18:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:20 INFO - Python 2.7.3 18:23:20 INFO - Return code: 0 18:23:20 INFO - grabbing minidump binary from tooltool 18:23:20 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:23:20 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 0x10bf07d30>, '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 0x10bd6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10beffb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:20 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:23:20 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:23:20 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:23:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:20 INFO - Return code: 0 18:23:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:23:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:23:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:20 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/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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:23:20 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/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --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:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6EsO61lbS0/Render', 18:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:20 INFO - 'HOME': '/Users/cltbld', 18:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:20 INFO - 'LOGNAME': 'cltbld', 18:23:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:20 INFO - 'MOZ_NO_REMOTE': '1', 18:23:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:20 INFO - 'NO_EM_RESTART': '1', 18:23:20 INFO - 'PAGER': '/bin/cat', 18:23:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:20 INFO - 'PWD': '/builds/slave/test', 18:23:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:20 INFO - 'SHELL': '/bin/bash', 18:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9FyembMKd/Listeners', 18:23:20 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 18:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:20 INFO - 'USER': 'cltbld', 18:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:20 INFO - 'XPC_FLAGS': '0x0', 18:23:20 INFO - 'XPC_SERVICE_NAME': '0', 18:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:20 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/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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:23:20 INFO - mozversion application_buildid: 20161216165049 18:23:20 INFO - mozversion application_changeset: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 18:23:20 INFO - mozversion application_display_name: Nightly 18:23:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:20 INFO - mozversion application_name: Firefox 18:23:20 INFO - mozversion application_remotingname: firefox 18:23:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:23:20 INFO - mozversion application_vendor: Mozilla 18:23:20 INFO - mozversion application_version: 53.0a1 18:23:20 INFO - mozversion platform_buildid: 20161216165049 18:23:20 INFO - mozversion platform_changeset: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 18:23:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:23:20 INFO - mozversion platform_version: 53.0a1 18:23:20 INFO - using testdate: 1481941400 18:23:20 INFO - actual date: 1481941400 18:23:20 INFO - starting webserver on 'localhost:49242' 18:23:20 INFO - SUITE-START | Running 2 tests 18:23:20 INFO - TEST-START | dromaeo_css 18:23:20 INFO - operating with platform_type : mac_ 18:23:20 INFO - Initialising browser for dromaeo_css test... 18:23:20 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpqqGIoA/profile http://localhost:49242/getInfo.html) 18:23:22 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 18:23:22 INFO - PROCESS | 1637 | colorDepth:24 18:23:22 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 18:23:22 INFO - PROCESS | 1637 | __metrics 18:23:22 INFO - PROCESS | 1637 | 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:23:22 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:23:23 INFO - TEST-INFO | 1637: exit 0 18:23:23 INFO - Browser initialized. 18:23:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:23 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpqqGIoA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:35 INFO - PROCESS | 1638 | RSS: Main: 236765184 18:23:35 INFO - PROCESS | 1638 | 18:24:26 INFO - PROCESS | 1638 | 1481941466487 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:26:39 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 18:26:41 INFO - PROCESS | 1638 | RSS: Main: 398725120 18:26:41 INFO - PROCESS | 1638 | 18:29:44 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 18:29:44 INFO - PROCESS | 1638 | RSS: Main: 378851328 18:29:44 INFO - PROCESS | 1638 | 18:32:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 18:32:47 INFO - PROCESS | 1638 | RSS: Main: 368144384 18:32:47 INFO - PROCESS | 1638 | 18:35:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 18:35:50 INFO - PROCESS | 1638 | RSS: Main: 343732224 18:35:50 INFO - PROCESS | 1638 | 18:38:54 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 18:38:55 INFO - PROCESS | 1638 | RSS: Main: 342904832 18:38:55 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 18:41:58 INFO - PROCESS | 1638 | RSS: Main: 404553728 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | __start_tp_report 18:41:58 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 18:41:58 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 18:41:58 INFO - PROCESS | 1638 | |i|pagename|runs| 18:41:58 INFO - PROCESS | 1638 | |0;dojo.html;3069.340091880246;3297.1757060734817;3306.5370848081275;3333.1500467380815;3367.12454761762;4704.553067458586;5108.2848401223855;5544.362398324194;5570.359408668004;5594.160875868622;4533.002739397336;5134.845954621358;5463.508284254416;5510.173473978905;5515.4760297771645;5051.141305977974;5056.924146137811;5117.616178786588;5147.922781158286;5164.122099243138;5040.092783299005;5090.541851233388;5158.045761534128;5174.379074511064;5181.818636347729;4354.8911277218;5160.92258616121;5161.354830646169;5186.988537235241;5194.298769666103;5023.497650234984;5060.392752869642;5162.638615296931;5163.535281824654;5203.4016088149965;4985.152524070881;5024.899502009976;5027.346444962168;5074.594032477393;5098.847034588931;5451.781928722847;5455.645383050129;5478.150886612541;5499.972500137514;5504.4495550445035;5931.288245410536;5931.703414829241;5936.495397891184;5941.940580594182;5946.464818166342;6037.426444487766;6041.275046994345;6121.849304493774;6122.47958923492;6179.0422484514975;5665.263515742927;5679.119736440858;5683.460071293221;5701.0308247597595;5742.253507044099;6259.217597800275;6280.434760871452;6284.591501552476;6298.307186209513;6305.495560355161;5764.048931926269;5816.651000939873;5826.213461182686;5937.970310148422;5995.400459954057;6065.575409721366;6095.146679464879;6121.204243448323;6151.477124444383;6177.907331390033;6084.209052823105;6086.300075491286;6091.162976261772;6100.762843515373;6137.478314343331;5851.970740146272;6100.328963814066;6117.654086234525;6125.490313351899;6134.877302453926;4750.572448479654;4802.687825291345;4808.206645903457;4810.2063159578975;4818.108649899709;4335.7615331157085;4352.847650332224;4367.67242456817;4383.539728328479;4389.582989615981;5455.727213639302;5571.1086226203615;5595.7481913313995;5604.60767746262;5609.326880774333;6091.51267898567;6099.725512351954;6103.084537319438;6104.38956104386;6109.266887973472;5558.77764889408;5592;5601.887962240732;5606.943930560724;5607.074832652648;5244.737763111829;5246.081935661244;5251.2123181523075;5251.6061295403;5262.657927234717;4904.141775189399;6010.248718910137;6028.0355143176885;6070.544709146831;6098.573099882966;5847.912281315868;5852.736626851887;5857.707114644336;5858.121281807763;5879.94120058811;6088.726007329769;6167.475764559883;6243.7814676486105;6244.313125556095;6246.219222597176;5980.073088671263;6102.398363691312;6107.114468401968;6107.8778424430375;6110.5891359570505;6104.400131021264;6142.9864072427545;6152.138700581832;6155.2613686356135;6164.291106522807;5143.4085080216255;5739.598228123991;5922.1412895130825;5926.644401335933;5944.702764861826;5823.417658234142;5844.503217226584;5891.49922256613;5892.528597712087;5893.263342082239;5721.056025755704;5755.510781583444;5804.593678442468;5826.94173058264;5849.94150058511;5909.556783241188;5919.348871624032;5942.970285148546;5950.523958083257;5967.015442452121;5641.26663533738;5675.7445914933105;5808.244928159313;5810.128480727755;5819.5344372450945 18:41:58 INFO - PROCESS | 1638 | |1;ext.html;445.8172149418738;449.25199542761305;489.6474538332399;499.1140725212747;511.86179731472504;29923.850380748092;31375.529367059513;31412;38866.80566597163;39567.60432395675;40895.59104408955;41329.38005929906;41356;41416;41431.17137657245;7795.259450352221;9401.858972115424;9410.388324758904;9419.340646154771;9437.716868493922;8413.831723365529;8622.223999840013;9354.81290374192;10999;11294.20940534163;11246.156538259622;11338.71653208668;11345.319280843176;11354.148438867118;11357.886421135765;9803.264755143322;11069.114470842394;11227.719307017309;11282.379469129235;11288;8382;8493.193146651058;10901.1279097672;11048.613298534594;11155.553777848876;30074.69925300741;39110.60889391127;39152.804235978925;39250.21499570021;39253;3397.7621566490357;3423.863045478178;3538.097785064792;3632;3679.742418030712;25511.362215944566;27447.725522744717;28583.28541786451;29427.705722942712;30052.69947300521;3137.921551961196;3165.9366812663848;3187.4103290891258;3189.2345836999184;3246.448103822332;374013;375465.12267438485;376709.1164544187;376848;379709;56085.43914560802;56366.390537277766;56502.43497564972;56517.43482565122;56611.15083273754;59000.40999589949;59136.40863591309;59220.40779592149;59225.44647321175;59242.518937026056;15137;15723.606909827116;15735.291911863993;15740.763888541682;15772.211389430482;12290.692732681575;12341.876581234252;12344.321062341658;12344.506219751289;12346.753064938652;13177.868221317856;13540.390682419315;13570.796438053438;14700.485483008257;15074;5144.9742751286;5910.822675319747;6069.3627169147485;6075.513958883279;6078.787242446581;842.7092653034694;944.15026476172;1040.7303090229907;1062.3307316390624;1077.922077922078;28742.40121580537;28956.42087158245;29194.7080529192;29626.851865740533;29782.55326170109;1067.2422579968154;1075.419273592267;1085.8751243606928;1132.5413207650915;1138.260130914912;14816.92591537057;14958.700825983637;15048.548543543711;15286.235688215767;15373.308201130976;607380;610950;612979;613799;616476;48120;48856.75571622119;48870.75564622154;53892.461075389525;55905.720471397384;1375.113052081422;1382.1914180204492;1397.0500059959325;1402.754517959353;1410.957671269843;23307.417314567272;29428.411431770815;29528.704712952596;29584.260393490335;30389.936352227567;47548.04903901833;48079.519204807504;48125.27812082751;48188.5181148198;48193;565704;635376;649378;674189;781525;19863.106160223113;20116;20166.899165504077;20183.59632807365;20187.0915808786;31998.840005799822;39352.40971385489;39495.407568885916;39672.80163599164;39962;33929.49105763366;34133.82933085319;34143.317133657685;34521.48217776786;34533.82733086318;30451;31315;32957.67042329642;33384.49923251202;33904.83047584746;33404.8308309208;33521.31265560738;33597.66402335945;33762.32475350529;34890.30219395547;29015.709842902146;30882.69117308798;30946.381072378877;30947.69052309448;30988.22529436782;23182.76817231806;23397;23519;23780.405489862893;23780.88109559441 18:41:58 INFO - PROCESS | 1638 | |2;jquery.html;358.7058611938212;372.58084654763394;376.36770226020286;386.9555001174865;402.52301023287407;14523.346449409775;14843.777343339845;16281.592960175987;19534.804651953476;20371.898140509278;19378.612427751435;19885.50286242841;20326.288579899705;20551.280705175323;20801;5239.030779305841;5599.132134519156;5791.913121303184;5792.304923409197;5803.042497987827;5599.832005039855;5880.55895807814;5887.1463637772495;5892.086726557369;5895.263092113486;5484.396716361197;5809.854753631151;5810.796622118227;5816.825495235129;5820.1560773688;5769.5672824538115;5870.442307980735;5880.029795083806;5888.411158884121;5896.941030589704;5479.808206712746;5640.971795141039;5699.401562835884;5701.885962280731;5733.971330143364;13002.739945201138;13020.739585208243;13095.607131786062;13208.207507549481;13238.338083095807;1799.4871461633372;2139.70044193813;2190.9561808763897;2244.6969659095976;2314.849534780234;8373.95813020937;9303.72088837336;10194.133498652624;10308.381497110197;11972.700682483008;1786.7409225662207;1793.7847458305087;1797;1836.348096425763;1845.7323688065155;107271;111351;111987.44006279999;114251;114457;11031.83452248217;11123.944380278208;11131.610393636183;11322.547098115983;11434;14174.64563385903;14351.64120896965;14463.566093017227;14476.597999470116;14505.709885802436;8142.715004974797;8304.543250121302;8338.04112527055;8444.873326900102;8446.662133514714;8149.7147599833725;8222.671093156327;8248.381371397169;8354.373421993374;8391.160883911682;4045.0494133878515;4138.896527586834;4392.494863090786;4485.820567177277;4602.792874320597;1570.1050401271393;1662.719705826531;1798.3975368251781;1889.6882014467458;1890.1588792987263;838.6938767349874;881.070470653454;885.4775682347446;885.9025507194203;934.514052692596;19760.011999399972;20934.162633494794;21017.264395746075;21061.789382105984;21221;875.2910142784365;916.422653728147;941.2799208605422;947.8578213268065;969.174855296816;10183.439910804977;11205.215634905479;11267.436628168723;11282.097432205404;11315.094792416587;469411;475960;479452;561031;565625;120264;122568;128316.07525887119;131419.34290328677;133971;883.9558022098869;1023.7031260934394;1089.6513115803025;1105.4693747001324;1107.2858006585707;19190.13644385978;19509.51226219299;19583.804161958768;19639.60720785605;19682.8031719681;37059.81470092632;54128;55370.44629553814;55528.722356389575;58112.54718632068;70993.93509097472;71607.92588111287;74826.62586687032;79251.60374198091;80158.79761803694;7978.960105199437;8064.15326390744;8205.876911846446;8298.294644955246;8366.24703776663;11194.66416007521;12838.743225135631;12982.870171298166;13194.604161875159;13199.538016169388;10820.729481763019;11058.894110588877;11079.169062320198;11212.04697600713;11218.55125794959;9552.522373881417;9595.664151754934;10837.512311946139;11210.439478025903;11239.381833999209;10535;10770.32703644093;11394.25937314072;11416.200865939305;11497.137714671266;6866.965665171642;7930.365570754441;7948.284654381134;8167.3466122711225;8206.876896846432;5607.5513958882375;8318.750437486886;8473.856029435947;9226.261899047926;10797.570546626946 18:41:58 INFO - PROCESS | 1638 | |3;mootools.html;1352.0197856553993;1485.8291296500902;1517.1579773225862;1729.437932671881;1907.1513176636386;7465.701371945122;7874.724384646539;7880.408969327309;7882.724104656339;7885.211478852112;7743.148253692089;8048;8277.255047045765;8333.874991875127;8453.492790432578;1881.576645254817;1986.2948653228113;2292.0487997481027;2300.9884950575306;2342.3792694935855;2238.8432809703245;2348.6829278047503;2378.357843382286;2380.631002194654;2382.8451150675237;2049.918003279867;2149.9355019349446;2150.2259186692854;2259.435141214696;2344.355302291875;2309.7574754650686;2321.883905804703;2326.8603883767028;2329.219711396684;2333.404981729665;2323.512062466884;2327.511222643247;2366.8934897929635;2368.988155059231;2371.8102551795814;8392.20274073962;8479.703210387606;8480.448770829877;8491.66033358665;8503.319734421231;4645.488996210414;5022.4224214215465;5177.378714554277;5187.662801917863;5188.195829646411;3169.9207519811957;3378.408778463759;3611.801350925698;3619.5113659656135;3620.402633565485;1053.8893416191343;1059.390850261103;1075.870895492546;1093.3931667924298;1095.0801326885376;10885.67342979704;10930.890691093067;11096.05683516902;11100.666979990614;11117.221794474472;4449.866504004885;4585.79363928624;4656.3946686930485;4664.183767840615;4708.788104535236;9274.675386361445;9300.906990930003;9307.627694892128;9313.34806563534;9316.487593182304;4990.176620857518;5071.594272458268;5084.313617661568;5097.311862898536;5227.242049902743;4501.257292546758;4634.21218392874;4652.488226295105;4778.713277203309;4819.783109759999;3769.566499852497;3800.8859734208017;3837;3876.6898648108086;3912.941305880414;1252.8974502437795;1257.9685507862378;1258.7356655102349;1273.6242808371367;1274.1908887856277;629.2543336146681;764.2472164917551;913.6573784830689;937.067617720359;941.5057095025167;5622.465865742748;5645.858853528694;5667;5726.112452569859;5845.444682755132;916.5234078279391;948.2366694810744;949.6438835436711;954.6420092465326;1005.939643621385;3750.456183853326;3763.7553559019125;3839.9040023999623;3878.301905657009;3963.5243770747693;9614.423134611945;10185.694429167137;10198.949005254926;10199.38803671782;10203.285769996177;10135.949320253352;10150.289479736348;10162.237832162617;10168.084872361524;10209.336393134423;7353.889691654629;7655.961720191475;7658.61706914652;7682;7767.456278060482;4760.6191504678845;5280.524752772269;5538.141588053858;5644.915326270191;5760.798372057126;8914.331425143011;9060.909390906007;9192.908070919206;9270.860937085814;9370.297227708086;7898.644560994654;9169.908300916906;9383.296252780932;9484.193843523377;9509.429434233969;7577.583232922242;7723.806904827425;7853.607319633881;7863;7863.764087077397;7650.196729343339;7732.188120247293;7732.381409487115;7776.105747839071;7830.569318687527;6967.303269672992;7022.473314501331;7093.361597456254;7104.822379440348;7164.176119746296;6848.452123830181;6865.862682746417;6876.415504682158;6973.546719463218;7013.579185248901;6482.70827812759;6606.042123892104;6607.669616519251;6609.272979972296;6609.834754131185;4732.171869922681;4862;4866.659333846596;4894.779734911866;4995.100881841304;4781.13939490895;4866.367372241586;4912.0175964807895;4921.950780492292;5010.649254552292 18:41:58 INFO - PROCESS | 1638 | |4;prototype.html;352.3921235868128;355.42421277530406;363.97452178347515;374.0834954361814;375.376874388515;5017.548420642146;5052.8484145475695;5168.198929165976;5278;5284.418713941468;4952.727599981999;5115.590752739781;5126.769295381708;5225.477452254772;5250.606204534656;3419.2135808763874;4096.631303182706;4148.522919864209;4166.958330416687;4173.749575025493;4049.9797501012454;4110.794460276989;4122.196171746511;4128.855490057848;4147.4815648044;3471.8784842530517;3641.854325826977;4083.979005248688;4111.424400583921;4140.846759684962;3434.9141271468166;4034.8789536313957;4064.7967601619803;4071.9796401017807;4123.649489793341;3049.6645369009393;3064.632244130696;3651.397519409294;3660.304542137012;3674.3386190485703;4965.975170124162;5058.392992840846;5090.440051594322;5109.642325037249;5153.304303918961;3594.19130695594;3913.6869050475893;4255;4666.929996050062;4698.1778188816825;4827.903441931142;5460.836174914759;5524.889502209933;5546.750396232179;5555.388907220203;3817.522809648794;3991.84032638697;3993.341098718708;4009.1981603679383;4075.6943229257627;4297.409958315443;4640.5127461616385;5173.921516697559;5739.995500787346;5743.569232307552;3254.6582608825734;3978.8607398740905;4058.5941405859767;4072.6131017553876;4109.260333140063;3464;3637;3960.5643379228263;4139.379093136054;4179.644730197906;3883.8640647577763;4053.351463765783;4501.864944051684;4678;4714.528547145258;4629.8148074077335;4899.975500122477;5147.790269286715;5475.5072043516275;5486.31421072366;1143.3425780176433;1185.9504338660174;1227.6808029912288;1273.9808902866464;1305.2690493323769;929.9584465398797;940.6237504997919;957.090763774417;961.6441916490803;962.6341990043878;4935.531124543163;4963;4965.90068198634;4990.575801056878;5008.424031236382;4121.670266378683;4977.402711674622;5113.7555828662125;5317.521423071942;5350.732463376893;4060.959390406058;4502.932456013163;4507.228054746308;4801.255805350106;4866.902661946741;2529.418233806235;2564.8201827159714;2743.9298673517346;2785.0670025541267;2831.150654803551;5005.171044151227;5141.614378921596;5182.740862956837;5560.916586251209;5590.720463976785;5043.571296439771;5321.760520776575;5457.69982650958;5481.479259470344;5580.748866301026;4852.708837469763;5128.8717782055;5174.7153906533995;5546.44699484137;5605.439456054362;4543.5910768031035;4651.930221046619;5007.349044624176;5195.92206116901;5287.259783630372;4458.955410445854;5291.391489978548;5363.946360536344;5406.459354064562;5431.83704488866;4643.512431194676;5128.025675121715;5187.896242075062;5509.796081567405;5660.528262651823;3447;3626.4379021250693;3665.780053196817;3915.9455624612847;3937.488126543531;4717.646176536705;5349.491798279158;5373.140297552372;5381.9860877908495;5404.675719456845;4300.698951073395;4744.620430365493;4772.546608072227;4875.658703890836;5055.014272216882;4235.915281694411;4492.550744925481;5385.353757549119;5433.891322173614;5467.863303417446;5402.783888644567;5456.144910730371;5457.426970168075;5460.126379779216;5464.398916119303;4872.561469467765;5116.8998665287145;5314.7874085036165;5390.946090539044;5581.302337207849;5402.9459705404015;5439.265699130567;5458.918116228339;5459.972700136474;5478.726063696879 18:41:58 INFO - PROCESS | 1638 | |5;yui.html;662.7382184037306;689.4346635758677;743.8400743840074;776.6039319946825;814.1939479914882;11270.380129092897;15639.139847308394;15647.84352156478;15669.216539173038;15703.84296157038;13991.580252592414;14151;14162.71674566508;14169;14169.929150354259;666.3869240298912;692.9930700692979;842.3050982939085;867.3061550759368;868.9832895512234;689.6689588997284;777.5412506621093;850.3664769746548;855.1149560205189;881.3566096749377;785.2971590426565;931.6459745296778;933.8179055084262;935.5556110847361;936.9812603747921;699.548791029788;924.6763632728558;926.2821313482071;926.379325851681;929.832630126577;538.8168022872243;545.0080852847818;545.342861851468;545.498141709628;651.387695566171;12563.183393079513;14604.70790584193;15156.772648410282;15166.469173578871;15175.392984280616;13949.58151255464;13949.860501394958;13974.650633734136;13995.211142804506;14066.859331406657;12679.619611411672;12754.80867786984;12784;12823.358832058453;12925.806112908314;4317.935230971538;4326.740395576276;4343.826246950118;4375.16871794358;4525.185466616008;105446;106985.46507267414;109229;110445.89554104436;110761.44619276932;8594.78513037179;11049.28179668335;11050.281731687415;11134.387608681438;11137.443127843575;17863.553411164823;22188.55622887533;22358;22392.440188995406;22638.207662731726;7707.730229442055;7741.458097933203;7823.413244006722;7832.725854595062;7858.685652573948;4208.305629571148;4286.485621725351;4317.136572685476;4375.606195442426;4481.282994720894;3303.339332133583;3363.6131844838155;3378.138574663445;3409.2499650076943;3414.487826825996;627.6359711367396;633.326705110358;636.4399328590811;639.5555089213045;651.2933467188059;533.375950138339;588.2088590845307;605.3915814606293;621.5307442880686;638.6838514935137;8321.916780832235;8646.52444115567;8876.245519130816;9074.818503629891;9270.397424167408;618.1778234947509;628.1519948070069;632.7152781248457;678.2064983968768;686.7939618114546;3441.7074548663145;4186.476690413699;4849.660523763373;4880.194767863334;4908.190148625508;93064;105197;105291;107733.46133269284;108617.45691271652;85243;85420.86447838854;86313;87994.56002719945;88648.5567572158;70264;82475;85548;86475;86866.58635220717;9459.668911588062;9918.30571859963;9935.20518358515;10012.44931528773;10058.14505767018;39198.6080139195;41096.79451602723;47131.70078094501;49601.503984961135;51739;43715;57944.71027644835;69158;70670.29329706637;72272;13440.663983400493;13659.79510307366;13780.310984450536;13829.446822127002;13937.024408291727;14091.506797262047;14106.576802695936;14349.784753228501;14379.281035947952;14828.481003164838;13421.194728315942;13470.73058538804;13632.65918352049;13919.860801391857;14060.929695351459;13484.460621575026;13513.52691253224;13583.52457663977;13643.522476713268;13645.795313070113;12726.17279876805;12839.935800320938;13074.673133171747;13098.803517947048;13349.199048057148;10829.350238985686;11164.497597607964;11456.656300311004;11519.777241876738;11800.586979455678;11003.504842281956;11335.376554289593;11362.091032717528;11512.769744605228;11571.826422603499 18:41:58 INFO - PROCESS | 1638 | __end_tp_report 18:41:58 INFO - PROCESS | 1638 | __start_cc_report 18:41:58 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1518 18:41:58 INFO - PROCESS | 1638 | __end_cc_report 18:41:58 INFO - PROCESS | 1638 | __startTimestamp1481942518705__endTimestamp 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | ------- Summary: start ------- 18:41:58 INFO - PROCESS | 1638 | Number of tests: 6 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5567.97 Median:5764.05 stddev:633.63 (11.0%) stddev-sans-first:604.50 18:41:58 INFO - PROCESS | 1638 | Values: 3069.3 3297.2 3306.5 3333.2 3367.1 4704.6 5108.3 5544.4 5570.4 5594.2 4533.0 5134.8 5463.5 5510.2 5515.5 5051.1 5056.9 5117.6 5147.9 5164.1 5040.1 5090.5 5158.0 5174.4 5181.8 4354.9 5160.9 5161.4 5187.0 5194.3 5023.5 5060.4 5162.6 5163.5 5203.4 4985.2 5024.9 5027.3 5074.6 5098.8 5451.8 5455.6 5478.2 5500.0 5504.4 5931.3 5931.7 5936.5 5941.9 5946.5 6037.4 6041.3 6121.8 6122.5 6179.0 5665.3 5679.1 5683.5 5701.0 5742.3 6259.2 6280.4 6284.6 6298.3 6305.5 5764.0 5816.7 5826.2 5938.0 5995.4 6065.6 6095.1 6121.2 6151.5 6177.9 6084.2 6086.3 6091.2 6100.8 6137.5 5852.0 6100.3 6117.7 6125.5 6134.9 4750.6 4802.7 4808.2 4810.2 4818.1 4335.8 4352.8 4367.7 4383.5 4389.6 5455.7 5571.1 5595.7 5604.6 5609.3 6091.5 6099.7 6103.1 6104.4 6109.3 5558.8 5592.0 5601.9 5606.9 5607.1 5244.7 5246.1 5251.2 5251.6 5262.7 4904.1 6010.2 6028.0 6070.5 6098.6 5847.9 5852.7 5857.7 5858.1 5879.9 6088.7 6167.5 6243.8 6244.3 6246.2 5980.1 6102.4 6107.1 6107.9 6110.6 6104.4 6143.0 6152.1 6155.3 6164.3 5143.4 5739.6 5922.1 5926.6 5944.7 5823.4 5844.5 5891.5 5892.5 5893.3 5721.1 5755.5 5804.6 5826.9 5849.9 5909.6 5919.3 5943.0 5950.5 5967.0 5641.3 5675.7 5808.2 5810.1 5819.5 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:66697.41 Median:33394.67 stddev:151828.11 (454.6%) stddev-sans-first:152172.54 18:41:58 INFO - PROCESS | 1638 | Values: 445.8 449.3 489.6 499.1 511.9 29923.9 31375.5 31412.0 38866.8 39567.6 40895.6 41329.4 41356.0 41416.0 41431.2 7795.3 9401.9 9410.4 9419.3 9437.7 8413.8 8622.2 9354.8 10999.0 11294.2 11246.2 11338.7 11345.3 11354.1 11357.9 9803.3 11069.1 11227.7 11282.4 11288.0 8382.0 8493.2 10901.1 11048.6 11155.6 30074.7 39110.6 39152.8 39250.2 39253.0 3397.8 3423.9 3538.1 3632.0 3679.7 25511.4 27447.7 28583.3 29427.7 30052.7 3137.9 3165.9 3187.4 3189.2 3246.4 374013.0 375465.1 376709.1 376848.0 379709.0 56085.4 56366.4 56502.4 56517.4 56611.2 59000.4 59136.4 59220.4 59225.4 59242.5 15137.0 15723.6 15735.3 15740.8 15772.2 12290.7 12341.9 12344.3 12344.5 12346.8 13177.9 13540.4 13570.8 14700.5 15074.0 5145.0 5910.8 6069.4 6075.5 6078.8 842.7 944.2 1040.7 1062.3 1077.9 28742.4 28956.4 29194.7 29626.9 29782.6 1067.2 1075.4 1085.9 1132.5 1138.3 14816.9 14958.7 15048.5 15286.2 15373.3 607380.0 610950.0 612979.0 613799.0 616476.0 48120.0 48856.8 48870.8 53892.5 55905.7 1375.1 1382.2 1397.1 1402.8 1411.0 23307.4 29428.4 29528.7 29584.3 30389.9 47548.0 48079.5 48125.3 48188.5 48193.0 565704.0 635376.0 649378.0 674189.0 781525.0 19863.1 20116.0 20166.9 20183.6 20187.1 31998.8 39352.4 39495.4 39672.8 39962.0 33929.5 34133.8 34143.3 34521.5 34533.8 30451.0 31315.0 32957.7 33384.5 33904.8 33404.8 33521.3 33597.7 33762.3 34890.3 29015.7 30882.7 30946.4 30947.7 30988.2 23182.8 23397.0 23519.0 23780.4 23780.9 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:31743.89 Median:1336.78 stddev:86285.10 (6454.7%) stddev-sans-first:86494.97 18:41:58 INFO - PROCESS | 1638 | Values: 358.7 372.6 376.4 387.0 402.5 14523.3 14843.8 16281.6 19534.8 20371.9 19378.6 19885.5 20326.3 20551.3 20801.0 5239.0 5599.1 5791.9 5792.3 5803.0 5599.8 5880.6 5887.1 5892.1 5895.3 5484.4 5809.9 5810.8 5816.8 5820.2 5769.6 5870.4 5880.0 5888.4 5896.9 5479.8 5641.0 5699.4 5701.9 5734.0 13002.7 13020.7 13095.6 13208.2 13238.3 1799.5 2139.7 2191.0 2244.7 2314.8 8374.0 9303.7 10194.1 10308.4 11972.7 1786.7 1793.8 1797.0 1836.3 1845.7 107271.0 111351.0 111987.4 114251.0 114457.0 11031.8 11123.9 11131.6 11322.5 11434.0 14174.6 14351.6 14463.6 14476.6 14505.7 8142.7 8304.5 8338.0 8444.9 8446.7 8149.7 8222.7 8248.4 8354.4 8391.2 4045.0 4138.9 4392.5 4485.8 4602.8 1570.1 1662.7 1798.4 1889.7 1890.2 838.7 881.1 885.5 885.9 934.5 19760.0 20934.2 21017.3 21061.8 21221.0 875.3 916.4 941.3 947.9 969.2 10183.4 11205.2 11267.4 11282.1 11315.1 469411.0 475960.0 479452.0 561031.0 565625.0 120264.0 122568.0 128316.1 131419.3 133971.0 884.0 1023.7 1089.7 1105.5 1107.3 19190.1 19509.5 19583.8 19639.6 19682.8 37059.8 54128.0 55370.4 55528.7 58112.5 70993.9 71607.9 74826.6 79251.6 80158.8 7979.0 8064.2 8205.9 8298.3 8366.2 11194.7 12838.7 12982.9 13194.6 13199.5 10820.7 11058.9 11079.2 11212.0 11218.6 9552.5 9595.7 10837.5 11210.4 11239.4 10535.0 10770.3 11394.3 11416.2 11497.1 6867.0 7930.4 7948.3 8167.3 8206.9 5607.6 8318.8 8473.9 9226.3 10797.6 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5431.50 Median:4992.64 stddev:2970.35 (59.5%) stddev-sans-first:2962.86 18:41:58 INFO - PROCESS | 1638 | Values: 1352.0 1485.8 1517.2 1729.4 1907.2 7465.7 7874.7 7880.4 7882.7 7885.2 7743.1 8048.0 8277.3 8333.9 8453.5 1881.6 1986.3 2292.0 2301.0 2342.4 2238.8 2348.7 2378.4 2380.6 2382.8 2049.9 2149.9 2150.2 2259.4 2344.4 2309.8 2321.9 2326.9 2329.2 2333.4 2323.5 2327.5 2366.9 2369.0 2371.8 8392.2 8479.7 8480.4 8491.7 8503.3 4645.5 5022.4 5177.4 5187.7 5188.2 3169.9 3378.4 3611.8 3619.5 3620.4 1053.9 1059.4 1075.9 1093.4 1095.1 10885.7 10930.9 11096.1 11100.7 11117.2 4449.9 4585.8 4656.4 4664.2 4708.8 9274.7 9300.9 9307.6 9313.3 9316.5 4990.2 5071.6 5084.3 5097.3 5227.2 4501.3 4634.2 4652.5 4778.7 4819.8 3769.6 3800.9 3837.0 3876.7 3912.9 1252.9 1258.0 1258.7 1273.6 1274.2 629.3 764.2 913.7 937.1 941.5 5622.5 5645.9 5667.0 5726.1 5845.4 916.5 948.2 949.6 954.6 1005.9 3750.5 3763.8 3839.9 3878.3 3963.5 9614.4 10185.7 10198.9 10199.4 10203.3 10135.9 10150.3 10162.2 10168.1 10209.3 7353.9 7656.0 7658.6 7682.0 7767.5 4760.6 5280.5 5538.1 5644.9 5760.8 8914.3 9060.9 9192.9 9270.9 9370.3 7898.6 9169.9 9383.3 9484.2 9509.4 7577.6 7723.8 7853.6 7863.0 7863.8 7650.2 7732.2 7732.4 7776.1 7830.6 6967.3 7022.5 7093.4 7104.8 7164.2 6848.5 6865.9 6876.4 6973.5 7013.6 6482.7 6606.0 6607.7 6609.3 6609.8 4732.2 4862.0 4866.7 4894.8 4995.1 4781.1 4866.4 4912.0 4922.0 5010.6 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4349.88 Median:4869.73 stddev:1285.17 (26.4%) stddev-sans-first:1253.26 18:41:58 INFO - PROCESS | 1638 | Values: 352.4 355.4 364.0 374.1 375.4 5017.5 5052.8 5168.2 5278.0 5284.4 4952.7 5115.6 5126.8 5225.5 5250.6 3419.2 4096.6 4148.5 4167.0 4173.7 4050.0 4110.8 4122.2 4128.9 4147.5 3471.9 3641.9 4084.0 4111.4 4140.8 3434.9 4034.9 4064.8 4072.0 4123.6 3049.7 3064.6 3651.4 3660.3 3674.3 4966.0 5058.4 5090.4 5109.6 5153.3 3594.2 3913.7 4255.0 4666.9 4698.2 4827.9 5460.8 5524.9 5546.8 5555.4 3817.5 3991.8 3993.3 4009.2 4075.7 4297.4 4640.5 5173.9 5740.0 5743.6 3254.7 3978.9 4058.6 4072.6 4109.3 3464.0 3637.0 3960.6 4139.4 4179.6 3883.9 4053.4 4501.9 4678.0 4714.5 4629.8 4900.0 5147.8 5475.5 5486.3 1143.3 1186.0 1227.7 1274.0 1305.3 930.0 940.6 957.1 961.6 962.6 4935.5 4963.0 4965.9 4990.6 5008.4 4121.7 4977.4 5113.8 5317.5 5350.7 4061.0 4502.9 4507.2 4801.3 4866.9 2529.4 2564.8 2743.9 2785.1 2831.2 5005.2 5141.6 5182.7 5560.9 5590.7 5043.6 5321.8 5457.7 5481.5 5580.7 4852.7 5128.9 5174.7 5546.4 5605.4 4543.6 4651.9 5007.3 5195.9 5287.3 4459.0 5291.4 5363.9 5406.5 5431.8 4643.5 5128.0 5187.9 5509.8 5660.5 3447.0 3626.4 3665.8 3915.9 3937.5 4717.6 5349.5 5373.1 5382.0 5404.7 4300.7 4744.6 4772.5 4875.7 5055.0 4235.9 4492.6 5385.4 5433.9 5467.9 5402.8 5456.1 5457.4 5460.1 5464.4 4872.6 5116.9 5314.8 5390.9 5581.3 5402.9 5439.3 5458.9 5460.0 5478.7 18:41:58 INFO - PROCESS | 1638 | 18:41:58 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20364.49 Median:4301.81 stddev:29565.03 (687.3%) stddev-sans-first:29610.96 18:41:58 INFO - PROCESS | 1638 | Values: 662.7 689.4 743.8 776.6 814.2 11270.4 15639.1 15647.8 15669.2 15703.8 13991.6 14151.0 14162.7 14169.0 14169.9 666.4 693.0 842.3 867.3 869.0 689.7 777.5 850.4 855.1 881.4 785.3 931.6 933.8 935.6 937.0 699.5 924.7 926.3 926.4 929.8 538.8 545.0 545.3 545.5 651.4 12563.2 14604.7 15156.8 15166.5 15175.4 13949.6 13949.9 13974.7 13995.2 14066.9 12679.6 12754.8 12784.0 12823.4 12925.8 4317.9 4326.7 4343.8 4375.2 4525.2 105446.0 106985.5 109229.0 110445.9 110761.4 8594.8 11049.3 11050.3 11134.4 11137.4 17863.6 22188.6 22358.0 22392.4 22638.2 7707.7 7741.5 7823.4 7832.7 7858.7 4208.3 4286.5 4317.1 4375.6 4481.3 3303.3 3363.6 3378.1 3409.2 3414.5 627.6 633.3 636.4 639.6 651.3 533.4 588.2 605.4 621.5 638.7 8321.9 8646.5 8876.2 9074.8 9270.4 618.2 628.2 632.7 678.2 686.8 3441.7 4186.5 4849.7 4880.2 4908.2 93064.0 105197.0 105291.0 107733.5 108617.5 85243.0 85420.9 86313.0 87994.6 88648.6 70264.0 82475.0 85548.0 86475.0 86866.6 9459.7 9918.3 9935.2 10012.4 10058.1 39198.6 41096.8 47131.7 49601.5 51739.0 43715.0 57944.7 69158.0 70670.3 72272.0 13440.7 13659.8 13780.3 13829.4 13937.0 14091.5 14106.6 14349.8 14379.3 14828.5 13421.2 13470.7 13632.7 13919.9 14060.9 13484.5 13513.5 13583.5 13643.5 13645.8 12726.2 12839.9 13074.7 13098.8 13349.2 10829.4 11164.5 11456.7 11519.8 11800.6 11003.5 11335.4 11362.1 11512.8 11571.8 18:41:58 INFO - PROCESS | 1638 | -------- Summary: end -------- 18:41:58 INFO - PROCESS | 1638 | 18:41:59 INFO - TEST-INFO | 1638: exit 0 18:41:59 INFO - TEST-OK | dromaeo_css | took 1118779ms 18:41:59 INFO - TEST-START | kraken 18:41:59 INFO - operating with platform_type : mac_ 18:41:59 INFO - Initialising browser for kraken test... 18:41:59 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpd39lbV/profile http://localhost:49242/getInfo.html) 18:42:01 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 18:42:01 INFO - PROCESS | 1649 | colorDepth:24 18:42:01 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 18:42:01 INFO - PROCESS | 1649 | __metrics 18:42:01 INFO - PROCESS | 1649 | 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:42:01 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:02 INFO - TEST-INFO | 1649: exit 0 18:42:02 INFO - Browser initialized. 18:42:02 INFO - Running cycle 1/1 for kraken test... 18:42:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpd39lbV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:14 INFO - PROCESS | 1650 | RSS: Main: 236957696 18:42:14 INFO - PROCESS | 1650 | 18:42:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:18 INFO - PROCESS | 1650 | RSS: Main: 289587200 18:42:18 INFO - PROCESS | 1650 | 18:42:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 18:42:21 INFO - PROCESS | 1650 | RSS: Main: 291622912 18:42:21 INFO - PROCESS | 1650 | 18:42:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 18:42:25 INFO - PROCESS | 1650 | RSS: Main: 380489728 18:42:25 INFO - PROCESS | 1650 | 18:42:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 18:42:28 INFO - PROCESS | 1650 | RSS: Main: 284815360 18:42:28 INFO - PROCESS | 1650 | 18:42:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:30 INFO - PROCESS | 1650 | RSS: Main: 288165888 18:42:30 INFO - PROCESS | 1650 | 18:42:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:34 INFO - PROCESS | 1650 | RSS: Main: 330559488 18:42:34 INFO - PROCESS | 1650 | 18:42:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:38 INFO - PROCESS | 1650 | RSS: Main: 332304384 18:42:38 INFO - PROCESS | 1650 | 18:42:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 18:42:41 INFO - PROCESS | 1650 | RSS: Main: 329150464 18:42:41 INFO - PROCESS | 1650 | 18:42:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:43 INFO - PROCESS | 1650 | RSS: Main: 303468544 18:42:43 INFO - PROCESS | 1650 | 18:42:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:46 INFO - PROCESS | 1650 | RSS: Main: 307310592 18:42:46 INFO - PROCESS | 1650 | 18:42:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:49 INFO - PROCESS | 1650 | RSS: Main: 312348672 18:42:49 INFO - PROCESS | 1650 | 18:42:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:52 INFO - PROCESS | 1650 | RSS: Main: 329830400 18:42:52 INFO - PROCESS | 1650 | 18:42:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:56 INFO - PROCESS | 1650 | RSS: Main: 324448256 18:42:56 INFO - PROCESS | 1650 | 18:42:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 18:42:58 INFO - PROCESS | 1650 | RSS: Main: 368590848 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | __start_tp_report 18:42:58 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 18:42:58 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 18:42:58 INFO - PROCESS | 1650 | |i|pagename|runs| 18:42:58 INFO - PROCESS | 1650 | |0;ai-astar;114;115;114;113;115;115;114;114;114;113 18:42:58 INFO - PROCESS | 1650 | |1;audio-beat-detection;140;111;109;146;111;110;110;109;132;109 18:42:58 INFO - PROCESS | 1650 | |2;audio-dft;184;186;187;195;265;169;235;252;181;183 18:42:58 INFO - PROCESS | 1650 | |3;audio-fft;79;60;58;87;61;62;58;60;73;61 18:42:58 INFO - PROCESS | 1650 | |4;audio-oscillator;89;70;87;68;70;69;83;70;93;117 18:42:58 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;110;107;107;108;107;107;106;109;106;116 18:42:58 INFO - PROCESS | 1650 | |6;imaging-darkroom;93;93;93;93;92;94;92;91;91;92 18:42:58 INFO - PROCESS | 1650 | |7;imaging-desaturate;84;84;84;85;85;87;87;84;84;84 18:42:58 INFO - PROCESS | 1650 | |8;json-parse-financial;58;57;59;54;55;54;55;54;54;56 18:42:58 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;64;67;76;75;74;72;70;71;71;71 18:42:58 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;89;89;86;90;88;87;86;90;88;89 18:42:58 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;138;143;145;148;132;139;141;138;185;152 18:42:58 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;172;174;173;173;173;173;165;163;172;170 18:42:58 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;67;67;68;66;67;67;66;67;66 18:42:58 INFO - PROCESS | 1650 | __end_tp_report 18:42:58 INFO - PROCESS | 1650 | __start_cc_report 18:42:58 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2027 18:42:58 INFO - PROCESS | 1650 | __end_cc_report 18:42:58 INFO - PROCESS | 1650 | __startTimestamp1481942578250__endTimestamp 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | ------- Summary: start ------- 18:42:58 INFO - PROCESS | 1650 | Number of tests: 14 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 18:42:58 INFO - PROCESS | 1650 | Values: 114.0 115.0 114.0 113.0 115.0 115.0 114.0 114.0 114.0 113.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:118.70 Median:111.00 stddev:14.64 (13.2%) stddev-sans-first:13.34 18:42:58 INFO - PROCESS | 1650 | Values: 140.0 111.0 109.0 146.0 111.0 110.0 110.0 109.0 132.0 109.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:203.70 Median:191.00 stddev:33.79 (17.7%) stddev-sans-first:35.08 18:42:58 INFO - PROCESS | 1650 | Values: 184.0 186.0 187.0 195.0 265.0 169.0 235.0 252.0 181.0 183.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:65.90 Median:61.50 stddev:10.14 (16.5%) stddev-sans-first:9.58 18:42:58 INFO - PROCESS | 1650 | Values: 79.0 60.0 58.0 87.0 61.0 62.0 58.0 60.0 73.0 61.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:76.50 stddev:15.69 (20.5%) stddev-sans-first:16.41 18:42:58 INFO - PROCESS | 1650 | Values: 89.0 70.0 87.0 68.0 70.0 69.0 83.0 70.0 93.0 117.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:108.30 Median:107.50 stddev:2.98 (2.8%) stddev-sans-first:3.10 18:42:58 INFO - PROCESS | 1650 | Values: 110.0 107.0 107.0 108.0 107.0 107.0 106.0 109.0 106.0 116.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:93.00 stddev:0.97 (1.0%) stddev-sans-first:1.00 18:42:58 INFO - PROCESS | 1650 | Values: 93.0 93.0 93.0 93.0 92.0 94.0 92.0 91.0 91.0 92.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.50 stddev:1.23 (1.5%) stddev-sans-first:1.27 18:42:58 INFO - PROCESS | 1650 | Values: 84.0 84.0 84.0 85.0 85.0 87.0 87.0 84.0 84.0 84.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:1.84 (3.3%) stddev-sans-first:1.73 18:42:58 INFO - PROCESS | 1650 | Values: 58.0 57.0 59.0 54.0 55.0 54.0 55.0 54.0 54.0 56.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:3.60 (5.0%) stddev-sans-first:2.76 18:42:58 INFO - PROCESS | 1650 | Values: 64.0 67.0 76.0 75.0 74.0 72.0 70.0 71.0 71.0 71.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:89.00 stddev:1.48 (1.7%) stddev-sans-first:1.54 18:42:58 INFO - PROCESS | 1650 | Values: 89.0 89.0 86.0 90.0 88.0 87.0 86.0 90.0 88.0 89.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:144.00 stddev:14.79 (10.3%) stddev-sans-first:15.39 18:42:58 INFO - PROCESS | 1650 | Values: 138.0 143.0 145.0 148.0 132.0 139.0 141.0 138.0 185.0 152.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:173.00 stddev:3.77 (2.2%) stddev-sans-first:3.97 18:42:58 INFO - PROCESS | 1650 | Values: 172.0 174.0 173.0 173.0 173.0 173.0 165.0 163.0 172.0 170.0 18:42:58 INFO - PROCESS | 1650 | 18:42:58 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 18:42:58 INFO - PROCESS | 1650 | Values: 68.0 67.0 67.0 68.0 66.0 67.0 67.0 66.0 67.0 66.0 18:42:58 INFO - PROCESS | 1650 | -------- Summary: end -------- 18:42:58 INFO - PROCESS | 1650 | 18:42:59 INFO - TEST-INFO | 1650: exit 0 18:42:59 INFO - TEST-OK | kraken | took 59819ms 18:42:59 INFO - SUITE-END | took 1178s 18:42:59 INFO - Completed test suite (00:19:38) 18:42:59 INFO - javascript benchmark 18:42:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3069.340091880246, 3297.1757060734817, 3306.5370848081275, 3333.1500467380815, 3367.12454761762, 4704.553067458586, 5108.2848401223855, 5544.362398324194, 5570.359408668004, 5594.160875868622, 4533.002739397336, 5134.845954621358, 5463.508284254416, 5510.173473978905, 5515.4760297771645, 5051.141305977974, 5056.924146137811, 5117.616178786588, 5147.922781158286, 5164.122099243138, 5040.092783299005, 5090.541851233388, 5158.045761534128, 5174.379074511064, 5181.818636347729, 4354.8911277218, 5160.92258616121, 5161.354830646169, 5186.988537235241, 5194.298769666103, 5023.497650234984, 5060.392752869642, 5162.638615296931, 5163.535281824654, 5203.4016088149965, 4985.152524070881, 5024.899502009976, 5027.346444962168, 5074.594032477393, 5098.847034588931, 5451.781928722847, 5455.645383050129, 5478.150886612541, 5499.972500137514, 5504.4495550445035, 5931.288245410536, 5931.703414829241, 5936.495397891184, 5941.940580594182, 5946.464818166342, 6037.426444487766, 6041.275046994345, 6121.849304493774, 6122.47958923492, 6179.0422484514975, 5665.263515742927, 5679.119736440858, 5683.460071293221, 5701.0308247597595, 5742.253507044099, 6259.217597800275, 6280.434760871452, 6284.591501552476, 6298.307186209513, 6305.495560355161, 5764.048931926269, 5816.651000939873, 5826.213461182686, 5937.970310148422, 5995.400459954057, 6065.575409721366, 6095.146679464879, 6121.204243448323, 6151.477124444383, 6177.907331390033, 6084.209052823105, 6086.300075491286, 6091.162976261772, 6100.762843515373, 6137.478314343331, 5851.970740146272, 6100.328963814066, 6117.654086234525, 6125.490313351899, 6134.877302453926, 4750.572448479654, 4802.687825291345, 4808.206645903457, 4810.2063159578975, 4818.108649899709, 4335.7615331157085, 4352.847650332224, 4367.67242456817, 4383.539728328479, 4389.582989615981, 5455.727213639302, 5571.1086226203615, 5595.7481913313995, 5604.60767746262, 5609.326880774333, 6091.51267898567, 6099.725512351954, 6103.084537319438, 6104.38956104386, 6109.266887973472, 5558.77764889408, 5592.0, 5601.887962240732, 5606.943930560724, 5607.074832652648, 5244.737763111829, 5246.081935661244, 5251.2123181523075, 5251.6061295403, 5262.657927234717, 4904.141775189399, 6010.248718910137, 6028.0355143176885, 6070.544709146831, 6098.573099882966, 5847.912281315868, 5852.736626851887, 5857.707114644336, 5858.121281807763, 5879.94120058811, 6088.726007329769, 6167.475764559883, 6243.7814676486105, 6244.313125556095, 6246.219222597176, 5980.073088671263, 6102.398363691312, 6107.114468401968, 6107.8778424430375, 6110.5891359570505, 6104.400131021264, 6142.9864072427545, 6152.138700581832, 6155.2613686356135, 6164.291106522807, 5143.4085080216255, 5739.598228123991, 5922.1412895130825, 5926.644401335933, 5944.702764861826, 5823.417658234142, 5844.503217226584, 5891.49922256613, 5892.528597712087, 5893.263342082239, 5721.056025755704, 5755.510781583444, 5804.593678442468, 5826.94173058264, 5849.94150058511, 5909.556783241188, 5919.348871624032, 5942.970285148546, 5950.523958083257, 5967.015442452121, 5641.26663533738, 5675.7445914933105, 5808.244928159313, 5810.128480727755, 5819.5344372450945], "value": 5528.059520176792, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.8172149418738, 449.25199542761305, 489.6474538332399, 499.1140725212747, 511.86179731472504, 29923.850380748092, 31375.529367059513, 31412.0, 38866.80566597163, 39567.60432395675, 40895.59104408955, 41329.38005929906, 41356.0, 41416.0, 41431.17137657245, 7795.259450352221, 9401.858972115424, 9410.388324758904, 9419.340646154771, 9437.716868493922, 8413.831723365529, 8622.223999840013, 9354.81290374192, 10999.0, 11294.20940534163, 11246.156538259622, 11338.71653208668, 11345.319280843176, 11354.148438867118, 11357.886421135765, 9803.264755143322, 11069.114470842394, 11227.719307017309, 11282.379469129235, 11288.0, 8382.0, 8493.193146651058, 10901.1279097672, 11048.613298534594, 11155.553777848876, 30074.69925300741, 39110.60889391127, 39152.804235978925, 39250.21499570021, 39253.0, 3397.7621566490357, 3423.863045478178, 3538.097785064792, 3632.0, 3679.742418030712, 25511.362215944566, 27447.725522744717, 28583.28541786451, 29427.705722942712, 30052.69947300521, 3137.921551961196, 3165.9366812663848, 3187.4103290891258, 3189.2345836999184, 3246.448103822332, 374013.0, 375465.12267438485, 376709.1164544187, 376848.0, 379709.0, 56085.43914560802, 56366.390537277766, 56502.43497564972, 56517.43482565122, 56611.15083273754, 59000.40999589949, 59136.40863591309, 59220.40779592149, 59225.44647321175, 59242.518937026056, 15137.0, 15723.606909827116, 15735.291911863993, 15740.763888541682, 15772.211389430482, 12290.692732681575, 12341.876581234252, 12344.321062341658, 12344.506219751289, 12346.753064938652, 13177.868221317856, 13540.390682419315, 13570.796438053438, 14700.485483008257, 15074.0, 5144.9742751286, 5910.822675319747, 6069.3627169147485, 6075.513958883279, 6078.787242446581, 842.7092653034694, 944.15026476172, 1040.7303090229907, 1062.3307316390624, 1077.922077922078, 28742.40121580537, 28956.42087158245, 29194.7080529192, 29626.851865740533, 29782.55326170109, 1067.2422579968154, 1075.419273592267, 1085.8751243606928, 1132.5413207650915, 1138.260130914912, 14816.92591537057, 14958.700825983637, 15048.548543543711, 15286.235688215767, 15373.308201130976, 607380.0, 610950.0, 612979.0, 613799.0, 616476.0, 48120.0, 48856.75571622119, 48870.75564622154, 53892.461075389525, 55905.720471397384, 1375.113052081422, 1382.1914180204492, 1397.0500059959325, 1402.754517959353, 1410.957671269843, 23307.417314567272, 29428.411431770815, 29528.704712952596, 29584.260393490335, 30389.936352227567, 47548.04903901833, 48079.519204807504, 48125.27812082751, 48188.5181148198, 48193.0, 565704.0, 635376.0, 649378.0, 674189.0, 781525.0, 19863.106160223113, 20116.0, 20166.899165504077, 20183.59632807365, 20187.0915808786, 31998.840005799822, 39352.40971385489, 39495.407568885916, 39672.80163599164, 39962.0, 33929.49105763366, 34133.82933085319, 34143.317133657685, 34521.48217776786, 34533.82733086318, 30451.0, 31315.0, 32957.67042329642, 33384.49923251202, 33904.83047584746, 33404.8308309208, 33521.31265560738, 33597.66402335945, 33762.32475350529, 34890.30219395547, 29015.709842902146, 30882.69117308798, 30946.381072378877, 30947.69052309448, 30988.22529436782, 23182.76817231806, 23397.0, 23519.0, 23780.405489862893, 23780.88109559441], "value": 18764.4741260987, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.7058611938212, 372.58084654763394, 376.36770226020286, 386.9555001174865, 402.52301023287407, 14523.346449409775, 14843.777343339845, 16281.592960175987, 19534.804651953476, 20371.898140509278, 19378.612427751435, 19885.50286242841, 20326.288579899705, 20551.280705175323, 20801.0, 5239.030779305841, 5599.132134519156, 5791.913121303184, 5792.304923409197, 5803.042497987827, 5599.832005039855, 5880.55895807814, 5887.1463637772495, 5892.086726557369, 5895.263092113486, 5484.396716361197, 5809.854753631151, 5810.796622118227, 5816.825495235129, 5820.1560773688, 5769.5672824538115, 5870.442307980735, 5880.029795083806, 5888.411158884121, 5896.941030589704, 5479.808206712746, 5640.971795141039, 5699.401562835884, 5701.885962280731, 5733.971330143364, 13002.739945201138, 13020.739585208243, 13095.607131786062, 13208.207507549481, 13238.338083095807, 1799.4871461633372, 2139.70044193813, 2190.9561808763897, 2244.6969659095976, 2314.849534780234, 8373.95813020937, 9303.72088837336, 10194.133498652624, 10308.381497110197, 11972.700682483008, 1786.7409225662207, 1793.7847458305087, 1797.0, 1836.348096425763, 1845.7323688065155, 107271.0, 111351.0, 111987.44006279999, 114251.0, 114457.0, 11031.83452248217, 11123.944380278208, 11131.610393636183, 11322.547098115983, 11434.0, 14174.64563385903, 14351.64120896965, 14463.566093017227, 14476.597999470116, 14505.709885802436, 8142.715004974797, 8304.543250121302, 8338.04112527055, 8444.873326900102, 8446.662133514714, 8149.7147599833725, 8222.671093156327, 8248.381371397169, 8354.373421993374, 8391.160883911682, 4045.0494133878515, 4138.896527586834, 4392.494863090786, 4485.820567177277, 4602.792874320597, 1570.1050401271393, 1662.719705826531, 1798.3975368251781, 1889.6882014467458, 1890.1588792987263, 838.6938767349874, 881.070470653454, 885.4775682347446, 885.9025507194203, 934.514052692596, 19760.011999399972, 20934.162633494794, 21017.264395746075, 21061.789382105984, 21221.0, 875.2910142784365, 916.422653728147, 941.2799208605422, 947.8578213268065, 969.174855296816, 10183.439910804977, 11205.215634905479, 11267.436628168723, 11282.097432205404, 11315.094792416587, 469411.0, 475960.0, 479452.0, 561031.0, 565625.0, 120264.0, 122568.0, 128316.07525887119, 131419.34290328677, 133971.0, 883.9558022098869, 1023.7031260934394, 1089.6513115803025, 1105.4693747001324, 1107.2858006585707, 19190.13644385978, 19509.51226219299, 19583.804161958768, 19639.60720785605, 19682.8031719681, 37059.81470092632, 54128.0, 55370.44629553814, 55528.722356389575, 58112.54718632068, 70993.93509097472, 71607.92588111287, 74826.62586687032, 79251.60374198091, 80158.79761803694, 7978.960105199437, 8064.15326390744, 8205.876911846446, 8298.294644955246, 8366.24703776663, 11194.66416007521, 12838.743225135631, 12982.870171298166, 13194.604161875159, 13199.538016169388, 10820.729481763019, 11058.894110588877, 11079.169062320198, 11212.04697600713, 11218.55125794959, 9552.522373881417, 9595.664151754934, 10837.512311946139, 11210.439478025903, 11239.381833999209, 10535.0, 10770.32703644093, 11394.25937314072, 11416.200865939305, 11497.137714671266, 6866.965665171642, 7930.365570754441, 7948.284654381134, 8167.3466122711225, 8206.876896846432, 5607.5513958882375, 8318.750437486886, 8473.856029435947, 9226.261899047926, 10797.570546626946], "value": 9110.373084648287, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.0197856553993, 1485.8291296500902, 1517.1579773225862, 1729.437932671881, 1907.1513176636386, 7465.701371945122, 7874.724384646539, 7880.408969327309, 7882.724104656339, 7885.211478852112, 7743.148253692089, 8048.0, 8277.255047045765, 8333.874991875127, 8453.492790432578, 1881.576645254817, 1986.2948653228113, 2292.0487997481027, 2300.9884950575306, 2342.3792694935855, 2238.8432809703245, 2348.6829278047503, 2378.357843382286, 2380.631002194654, 2382.8451150675237, 2049.918003279867, 2149.9355019349446, 2150.2259186692854, 2259.435141214696, 2344.355302291875, 2309.7574754650686, 2321.883905804703, 2326.8603883767028, 2329.219711396684, 2333.404981729665, 2323.512062466884, 2327.511222643247, 2366.8934897929635, 2368.988155059231, 2371.8102551795814, 8392.20274073962, 8479.703210387606, 8480.448770829877, 8491.66033358665, 8503.319734421231, 4645.488996210414, 5022.4224214215465, 5177.378714554277, 5187.662801917863, 5188.195829646411, 3169.9207519811957, 3378.408778463759, 3611.801350925698, 3619.5113659656135, 3620.402633565485, 1053.8893416191343, 1059.390850261103, 1075.870895492546, 1093.3931667924298, 1095.0801326885376, 10885.67342979704, 10930.890691093067, 11096.05683516902, 11100.666979990614, 11117.221794474472, 4449.866504004885, 4585.79363928624, 4656.3946686930485, 4664.183767840615, 4708.788104535236, 9274.675386361445, 9300.906990930003, 9307.627694892128, 9313.34806563534, 9316.487593182304, 4990.176620857518, 5071.594272458268, 5084.313617661568, 5097.311862898536, 5227.242049902743, 4501.257292546758, 4634.21218392874, 4652.488226295105, 4778.713277203309, 4819.783109759999, 3769.566499852497, 3800.8859734208017, 3837.0, 3876.6898648108086, 3912.941305880414, 1252.8974502437795, 1257.9685507862378, 1258.7356655102349, 1273.6242808371367, 1274.1908887856277, 629.2543336146681, 764.2472164917551, 913.6573784830689, 937.067617720359, 941.5057095025167, 5622.465865742748, 5645.858853528694, 5667.0, 5726.112452569859, 5845.444682755132, 916.5234078279391, 948.2366694810744, 949.6438835436711, 954.6420092465326, 1005.939643621385, 3750.456183853326, 3763.7553559019125, 3839.9040023999623, 3878.301905657009, 3963.5243770747693, 9614.423134611945, 10185.694429167137, 10198.949005254926, 10199.38803671782, 10203.285769996177, 10135.949320253352, 10150.289479736348, 10162.237832162617, 10168.084872361524, 10209.336393134423, 7353.889691654629, 7655.961720191475, 7658.61706914652, 7682.0, 7767.456278060482, 4760.6191504678845, 5280.524752772269, 5538.141588053858, 5644.915326270191, 5760.798372057126, 8914.331425143011, 9060.909390906007, 9192.908070919206, 9270.860937085814, 9370.297227708086, 7898.644560994654, 9169.908300916906, 9383.296252780932, 9484.193843523377, 9509.429434233969, 7577.583232922242, 7723.806904827425, 7853.607319633881, 7863.0, 7863.764087077397, 7650.196729343339, 7732.188120247293, 7732.381409487115, 7776.105747839071, 7830.569318687527, 6967.303269672992, 7022.473314501331, 7093.361597456254, 7104.822379440348, 7164.176119746296, 6848.452123830181, 6865.862682746417, 6876.415504682158, 6973.546719463218, 7013.579185248901, 6482.70827812759, 6606.042123892104, 6607.669616519251, 6609.272979972296, 6609.834754131185, 4732.171869922681, 4862.0, 4866.659333846596, 4894.779734911866, 4995.100881841304, 4781.13939490895, 4866.367372241586, 4912.0175964807895, 4921.950780492292, 5010.649254552292], "value": 4406.549925808798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.3921235868128, 355.42421277530406, 363.97452178347515, 374.0834954361814, 375.376874388515, 5017.548420642146, 5052.8484145475695, 5168.198929165976, 5278.0, 5284.418713941468, 4952.727599981999, 5115.590752739781, 5126.769295381708, 5225.477452254772, 5250.606204534656, 3419.2135808763874, 4096.631303182706, 4148.522919864209, 4166.958330416687, 4173.749575025493, 4049.9797501012454, 4110.794460276989, 4122.196171746511, 4128.855490057848, 4147.4815648044, 3471.8784842530517, 3641.854325826977, 4083.979005248688, 4111.424400583921, 4140.846759684962, 3434.9141271468166, 4034.8789536313957, 4064.7967601619803, 4071.9796401017807, 4123.649489793341, 3049.6645369009393, 3064.632244130696, 3651.397519409294, 3660.304542137012, 3674.3386190485703, 4965.975170124162, 5058.392992840846, 5090.440051594322, 5109.642325037249, 5153.304303918961, 3594.19130695594, 3913.6869050475893, 4255.0, 4666.929996050062, 4698.1778188816825, 4827.903441931142, 5460.836174914759, 5524.889502209933, 5546.750396232179, 5555.388907220203, 3817.522809648794, 3991.84032638697, 3993.341098718708, 4009.1981603679383, 4075.6943229257627, 4297.409958315443, 4640.5127461616385, 5173.921516697559, 5739.995500787346, 5743.569232307552, 3254.6582608825734, 3978.8607398740905, 4058.5941405859767, 4072.6131017553876, 4109.260333140063, 3464.0, 3637.0, 3960.5643379228263, 4139.379093136054, 4179.644730197906, 3883.8640647577763, 4053.351463765783, 4501.864944051684, 4678.0, 4714.528547145258, 4629.8148074077335, 4899.975500122477, 5147.790269286715, 5475.5072043516275, 5486.31421072366, 1143.3425780176433, 1185.9504338660174, 1227.6808029912288, 1273.9808902866464, 1305.2690493323769, 929.9584465398797, 940.6237504997919, 957.090763774417, 961.6441916490803, 962.6341990043878, 4935.531124543163, 4963.0, 4965.90068198634, 4990.575801056878, 5008.424031236382, 4121.670266378683, 4977.402711674622, 5113.7555828662125, 5317.521423071942, 5350.732463376893, 4060.959390406058, 4502.932456013163, 4507.228054746308, 4801.255805350106, 4866.902661946741, 2529.418233806235, 2564.8201827159714, 2743.9298673517346, 2785.0670025541267, 2831.150654803551, 5005.171044151227, 5141.614378921596, 5182.740862956837, 5560.916586251209, 5590.720463976785, 5043.571296439771, 5321.760520776575, 5457.69982650958, 5481.479259470344, 5580.748866301026, 4852.708837469763, 5128.8717782055, 5174.7153906533995, 5546.44699484137, 5605.439456054362, 4543.5910768031035, 4651.930221046619, 5007.349044624176, 5195.92206116901, 5287.259783630372, 4458.955410445854, 5291.391489978548, 5363.946360536344, 5406.459354064562, 5431.83704488866, 4643.512431194676, 5128.025675121715, 5187.896242075062, 5509.796081567405, 5660.528262651823, 3447.0, 3626.4379021250693, 3665.780053196817, 3915.9455624612847, 3937.488126543531, 4717.646176536705, 5349.491798279158, 5373.140297552372, 5381.9860877908495, 5404.675719456845, 4300.698951073395, 4744.620430365493, 4772.546608072227, 4875.658703890836, 5055.014272216882, 4235.915281694411, 4492.550744925481, 5385.353757549119, 5433.891322173614, 5467.863303417446, 5402.783888644567, 5456.144910730371, 5457.426970168075, 5460.126379779216, 5464.398916119303, 4872.561469467765, 5116.8998665287145, 5314.7874085036165, 5390.946090539044, 5581.302337207849, 5402.9459705404015, 5439.265699130567, 5458.918116228339, 5459.972700136474, 5478.726063696879], "value": 3963.7711333662683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.7382184037306, 689.4346635758677, 743.8400743840074, 776.6039319946825, 814.1939479914882, 11270.380129092897, 15639.139847308394, 15647.84352156478, 15669.216539173038, 15703.84296157038, 13991.580252592414, 14151.0, 14162.71674566508, 14169.0, 14169.929150354259, 666.3869240298912, 692.9930700692979, 842.3050982939085, 867.3061550759368, 868.9832895512234, 689.6689588997284, 777.5412506621093, 850.3664769746548, 855.1149560205189, 881.3566096749377, 785.2971590426565, 931.6459745296778, 933.8179055084262, 935.5556110847361, 936.9812603747921, 699.548791029788, 924.6763632728558, 926.2821313482071, 926.379325851681, 929.832630126577, 538.8168022872243, 545.0080852847818, 545.342861851468, 545.498141709628, 651.387695566171, 12563.183393079513, 14604.70790584193, 15156.772648410282, 15166.469173578871, 15175.392984280616, 13949.58151255464, 13949.860501394958, 13974.650633734136, 13995.211142804506, 14066.859331406657, 12679.619611411672, 12754.80867786984, 12784.0, 12823.358832058453, 12925.806112908314, 4317.935230971538, 4326.740395576276, 4343.826246950118, 4375.16871794358, 4525.185466616008, 105446.0, 106985.46507267414, 109229.0, 110445.89554104436, 110761.44619276932, 8594.78513037179, 11049.28179668335, 11050.281731687415, 11134.387608681438, 11137.443127843575, 17863.553411164823, 22188.55622887533, 22358.0, 22392.440188995406, 22638.207662731726, 7707.730229442055, 7741.458097933203, 7823.413244006722, 7832.725854595062, 7858.685652573948, 4208.305629571148, 4286.485621725351, 4317.136572685476, 4375.606195442426, 4481.282994720894, 3303.339332133583, 3363.6131844838155, 3378.138574663445, 3409.2499650076943, 3414.487826825996, 627.6359711367396, 633.326705110358, 636.4399328590811, 639.5555089213045, 651.2933467188059, 533.375950138339, 588.2088590845307, 605.3915814606293, 621.5307442880686, 638.6838514935137, 8321.916780832235, 8646.52444115567, 8876.245519130816, 9074.818503629891, 9270.397424167408, 618.1778234947509, 628.1519948070069, 632.7152781248457, 678.2064983968768, 686.7939618114546, 3441.7074548663145, 4186.476690413699, 4849.660523763373, 4880.194767863334, 4908.190148625508, 93064.0, 105197.0, 105291.0, 107733.46133269284, 108617.45691271652, 85243.0, 85420.86447838854, 86313.0, 87994.56002719945, 88648.5567572158, 70264.0, 82475.0, 85548.0, 86475.0, 86866.58635220717, 9459.668911588062, 9918.30571859963, 9935.20518358515, 10012.44931528773, 10058.14505767018, 39198.6080139195, 41096.79451602723, 47131.70078094501, 49601.503984961135, 51739.0, 43715.0, 57944.71027644835, 69158.0, 70670.29329706637, 72272.0, 13440.663983400493, 13659.79510307366, 13780.310984450536, 13829.446822127002, 13937.024408291727, 14091.506797262047, 14106.576802695936, 14349.784753228501, 14379.281035947952, 14828.481003164838, 13421.194728315942, 13470.73058538804, 13632.65918352049, 13919.860801391857, 14060.929695351459, 13484.460621575026, 13513.52691253224, 13583.52457663977, 13643.522476713268, 13645.795313070113, 12726.17279876805, 12839.935800320938, 13074.673133171747, 13098.803517947048, 13349.199048057148, 10829.350238985686, 11164.497597607964, 11456.656300311004, 11519.777241876738, 11800.586979455678, 11003.504842281956, 11335.376554289593, 11362.091032717528, 11512.769744605228, 11571.826422603499], "value": 7383.472920196866, "unit": "score"}], "name": "dromaeo_css", "value": 7041.414510927345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 113.0, 115.0, 115.0, 114.0, 114.0, 114.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 109.0, 146.0, 111.0, 110.0, 110.0, 109.0, 132.0, 109.0], "value": 118.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 187.0, 195.0, 265.0, 169.0, 235.0, 252.0, 181.0, 183.0], "value": 203.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 58.0, 87.0, 61.0, 62.0, 58.0, 60.0, 73.0, 61.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 87.0, 68.0, 70.0, 69.0, 83.0, 70.0, 93.0, 117.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 107.0, 107.0, 108.0, 107.0, 107.0, 106.0, 109.0, 106.0, 116.0], "value": 108.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 92.0, 94.0, 92.0, 91.0, 91.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 85.0, 87.0, 87.0, 84.0, 84.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 54.0, 55.0, 54.0, 55.0, 54.0, 54.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 76.0, 75.0, 74.0, 72.0, 70.0, 71.0, 71.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 86.0, 90.0, 88.0, 87.0, 86.0, 90.0, 88.0, 89.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 145.0, 148.0, 132.0, 139.0, 141.0, 138.0, 185.0, 152.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 173.0, 173.0, 173.0, 173.0, 165.0, 163.0, 172.0, 170.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 66.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1468.1999999999998, "alertThreshold": 2.0}]} 18:42:59 INFO - Return code: 0 18:42:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:59 INFO - # TBPL SUCCESS # 18:42:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:42:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:42:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:42:59 INFO - 2016-12-16 18:42:59.595 screenresolution[1654:9748] starting screenresolution argv=screenresolution get 18:42:59 INFO - 2016-12-16 18:42:59.599 screenresolution[1654:9748] Display 0: 1600x1200x32@60 18:42:59 INFO - 2016-12-16 18:42:59.605 screenresolution[1655:9751] starting screenresolution argv=screenresolution list 18:42:59 INFO - Available Modes on Display 0 18:42:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:42:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:42:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:42:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:42:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:42:59 INFO - Intel Iris: 18:42:59 INFO - Chipset Model: Intel Iris 18:42:59 INFO - Type: GPU 18:42:59 INFO - Bus: Built-In 18:42:59 INFO - VRAM (Dynamic, Max): 1536 MB 18:42:59 INFO - Vendor: Intel (0x8086) 18:42:59 INFO - Device ID: 0x0a2e 18:42:59 INFO - Revision ID: 0x0009 18:42:59 INFO - Displays: 18:42:59 INFO - Display: 18:42:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:42:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:42:59 INFO - Display Serial Number: 1600x1200 60 18:42:59 INFO - Main Display: Yes 18:42:59 INFO - Mirror: Off 18:42:59 INFO - Online: Yes 18:42:59 INFO - Rotation: Supported 18:42:59 INFO - Return code: 0 18:42:59 INFO - Running post-action listener: _resource_record_post_action 18:42:59 INFO - [mozharness: 2016-12-17 02:42:59.841428Z] Finished run-tests step (success) 18:42:59 INFO - Running post-run listener: _resource_record_post_run 18:42:59 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 41976832; Write bytes: 550338560; Read time: 343; Write time: 1672 18:42:59 INFO - TinderboxPrint: CPU usage
53.7% 18:42:59 INFO - TinderboxPrint: I/O read bytes / time
41,976,832 / 343 18:42:59 INFO - TinderboxPrint: I/O write bytes / time
550,338,560 / 1,672 18:42:59 INFO - TinderboxPrint: CPU idle
2,223.8 (46.3%) 18:42:59 INFO - TinderboxPrint: CPU system
959.2 (20.0%) 18:42:59 INFO - TinderboxPrint: CPU user
1,622.8 (33.8%) 18:42:59 INFO - TinderboxPrint: Swap in / out
291,356,672 / 0 18:42:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230787584; Write bytes: 223100928; Read time: 16507; Write time: 501 18:42:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 41047040; Write bytes: 300109824; Read time: 331; Write time: 1112 18:43:00 INFO - Running post-run listener: _upload_blobber_files 18:43:00 INFO - Blob upload gear active. 18:43:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:43:00 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:43:00 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:43:00 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:43:00 INFO - (blobuploader) - INFO - Open directory for files ... 18:43:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:43:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:01 INFO - (blobuploader) - INFO - Done attempting. 18:43:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:43:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:43:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:02 INFO - (blobuploader) - INFO - Done attempting. 18:43:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:43:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:43:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:05 INFO - (blobuploader) - INFO - Done attempting. 18:43:05 INFO - (blobuploader) - INFO - Iteration through files over. 18:43:05 INFO - Return code: 0 18:43:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:43:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:43:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d268aac5ac19f9c2bf77d1363a359048221e0b325325c11851e37306d4c05bb178b842d24a7d3dae720b98520bc301f0f0cc54ea0e7fba5e761d90bd61e9afc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bacb282d59e758ec7b14146973dc88c6d24d69ba8f25e2a4e6b6d72004ea342517937f3ffdbc742a832644a79ee812e9a953281d4c7dbe5131bc068145140a2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94cda31f1eb93f8466fd2c5594e790a0d4e3c6b62e35c46eefb09b84b4e20d97e4340156731943ee45d74178c4d0d585c0ec7f8c78f989ed9c6bb52beec6584e"} 18:43:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:43:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:43:05 INFO - Contents: 18:43:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d268aac5ac19f9c2bf77d1363a359048221e0b325325c11851e37306d4c05bb178b842d24a7d3dae720b98520bc301f0f0cc54ea0e7fba5e761d90bd61e9afc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bacb282d59e758ec7b14146973dc88c6d24d69ba8f25e2a4e6b6d72004ea342517937f3ffdbc742a832644a79ee812e9a953281d4c7dbe5131bc068145140a2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94cda31f1eb93f8466fd2c5594e790a0d4e3c6b62e35c46eefb09b84b4e20d97e4340156731943ee45d74178c4d0d585c0ec7f8c78f989ed9c6bb52beec6584e"} 18:43:05 INFO - Running post-run listener: copy_logs_to_upload_dir 18:43:05 INFO - Copying logs to upload dir... 18:43:05 INFO - mkdir: /builds/slave/test/build/upload/logs 18:43:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.424355 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-16 18:43:05.250016) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 18:43:05.254067) ========= 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.6EsO61lbS0/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.w9FyembMKd/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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/3d268aac5ac19f9c2bf77d1363a359048221e0b325325c11851e37306d4c05bb178b842d24a7d3dae720b98520bc301f0f0cc54ea0e7fba5e761d90bd61e9afc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bacb282d59e758ec7b14146973dc88c6d24d69ba8f25e2a4e6b6d72004ea342517937f3ffdbc742a832644a79ee812e9a953281d4c7dbe5131bc068145140a2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94cda31f1eb93f8466fd2c5594e790a0d4e3c6b62e35c46eefb09b84b4e20d97e4340156731943ee45d74178c4d0d585c0ec7f8c78f989ed9c6bb52beec6584e"} build_url:https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/fmMkSrdRQAmAyS1064MAEg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d268aac5ac19f9c2bf77d1363a359048221e0b325325c11851e37306d4c05bb178b842d24a7d3dae720b98520bc301f0f0cc54ea0e7fba5e761d90bd61e9afc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bacb282d59e758ec7b14146973dc88c6d24d69ba8f25e2a4e6b6d72004ea342517937f3ffdbc742a832644a79ee812e9a953281d4c7dbe5131bc068145140a2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94cda31f1eb93f8466fd2c5594e790a0d4e3c6b62e35c46eefb09b84b4e20d97e4340156731943ee45d74178c4d0d585c0ec7f8c78f989ed9c6bb52beec6584e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 18:43:05.289998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:43:05.290445) ========= 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.6EsO61lbS0/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.w9FyembMKd/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 18:43:05.340394) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 18:43:05.340863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 18:43:05.341285) ========= ========= Total master_lag: 0.20 =========