builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1486174230.98 results: success (0) buildid: 20170203170012 builduid: b41a63a5d0f943aea3aafe3cb0682850 revision: 94079d43835ff229dce0f9b226cedd64c6aaef60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:30.982152) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:30.982653) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:30.982943) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:31.009683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:31.010039) ========= 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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-03 18:10:31-- 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% 8.59M=0.001s 2017-02-03 18:10:31 (8.59 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.453352 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:31.485454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:31.485766) ========= 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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.028349 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:31.535356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 18:10:31.535677) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 94079d43835ff229dce0f9b226cedd64c6aaef60 --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 94079d43835ff229dce0f9b226cedd64c6aaef60 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SgFBF2cCNW/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.iaZDIuzCZz/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-03 18:10:31,640 truncating revision to first 12 chars 2017-02-03 18:10:31,640 Setting DEBUG logging. 2017-02-03 18:10:31,640 attempt 1/10 2017-02-03 18:10:31,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/94079d43835f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-03 18:10:32,800 unpacking tar archive at: mozilla-central-94079d43835f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.433295 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 18:10:32.992187) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:32.992491) ========= script_repo_revision: 94079d43835ff229dce0f9b226cedd64c6aaef60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:32.994946) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:32.995289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 18:10:33.017289) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-03 18:10:33.017661) ========= /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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:33 INFO - MultiFileLogger online at 20170203 18:10:33 in /builds/slave/test 18:10:33 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:10:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:10:33 INFO - {'append_to_log': False, 18:10:33 INFO - 'base_work_dir': '/builds/slave/test', 18:10:33 INFO - 'blob_upload_branch': 'Firefox', 18:10:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:10:33 INFO - 'branch': 'Firefox', 18:10:33 INFO - 'buildbot_json_path': 'buildprops.json', 18:10:33 INFO - 'config_files': ('talos/mac_config.py',), 18:10:33 INFO - 'default_actions': ('clobber', 18:10:33 INFO - 'read-buildbot-config', 18:10:33 INFO - 'download-and-extract', 18:10:33 INFO - 'populate-webroot', 18:10:33 INFO - 'create-virtualenv', 18:10:33 INFO - 'install', 18:10:33 INFO - 'run-tests'), 18:10:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:10:33 INFO - 'download_minidump_stackwalk': True, 18:10:33 INFO - 'download_symbols': 'ondemand', 18:10:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:10:33 INFO - 'tooltool.py': '/tools/tooltool.py', 18:10:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:10:33 INFO - '/tools/misc-python/virtualenv.py')}, 18:10:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:10:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:10:33 INFO - 'installer_path': 'installer.exe', 18:10:33 INFO - 'log_level': 'info', 18:10:33 INFO - 'log_name': 'talos', 18:10:33 INFO - 'log_to_console': True, 18:10:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:10:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:10:33 INFO - 'opt_config_files': (), 18:10:33 INFO - 'pip_index': False, 18:10:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:10:33 INFO - 'cmd': ('bash', 18:10:33 INFO - '-c', 18:10:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:10:33 INFO - 'enabled': True, 18:10:33 INFO - 'halt_on_failure': False, 18:10:33 INFO - 'name': 'check_screen_resolution'},), 18:10:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:10:33 INFO - 'cmd': ('bash', 18:10:33 INFO - '-c', 18:10:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:10:33 INFO - 'enabled': True, 18:10:33 INFO - 'halt_on_failure': False, 18:10:33 INFO - 'name': 'check_screen_resolution'},), 18:10:33 INFO - 'run_cmd_checks_enabled': True, 18:10:33 INFO - 'sps_profile': False, 18:10:33 INFO - 'sps_profile_interval': 0, 18:10:33 INFO - 'suite': 'dromaeojs', 18:10:33 INFO - 'system_bits': '32', 18:10:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:10:33 INFO - 'title': 't-yosemite-r7-0029', 18:10:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:10:33 INFO - 'use_talos_json': True, 18:10:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:10:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:10:33 INFO - 'work_dir': 'build'} 18:10:33 INFO - [mozharness: 2017-02-04 02:10:33.235742Z] Running clobber step. 18:10:33 INFO - Running pre-action listener: _resource_record_pre_action 18:10:33 INFO - Running main action method: clobber 18:10:33 INFO - rmtree: /builds/slave/test/build 18:10:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:10:35 INFO - Running post-action listener: _resource_record_post_action 18:10:35 INFO - [mozharness: 2017-02-04 02:10:35.742893Z] Finished clobber step (success) 18:10:35 INFO - [mozharness: 2017-02-04 02:10:35.743091Z] Running read-buildbot-config step. 18:10:35 INFO - Running pre-action listener: _resource_record_pre_action 18:10:35 INFO - Running main action method: read_buildbot_config 18:10:35 INFO - Using buildbot properties: 18:10:35 INFO - { 18:10:35 INFO - "project": "", 18:10:35 INFO - "product": "firefox", 18:10:35 INFO - "script_repo_revision": "production", 18:10:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:10:35 INFO - "repository": "", 18:10:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:10:35 INFO - "buildid": "20170203170012", 18:10:35 INFO - "pgo_build": "False", 18:10:35 INFO - "basedir": "/builds/slave/test", 18:10:35 INFO - "buildnumber": 59, 18:10:35 INFO - "slavename": "t-yosemite-r7-0029", 18:10:35 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:10:35 INFO - "platform": "yosemite_r7", 18:10:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:10:35 INFO - "branch": "mozilla-central", 18:10:35 INFO - "repo_path": "mozilla-central", 18:10:35 INFO - "revision": "94079d43835ff229dce0f9b226cedd64c6aaef60", 18:10:35 INFO - "stage_platform": "macosx64", 18:10:35 INFO - "builduid": "b41a63a5d0f943aea3aafe3cb0682850", 18:10:35 INFO - "slavebuilddir": "test" 18:10:35 INFO - } 18:10:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:10:35 INFO - Running post-action listener: _resource_record_post_action 18:10:35 INFO - [mozharness: 2017-02-04 02:10:35.746525Z] Finished read-buildbot-config step (success) 18:10:35 INFO - [mozharness: 2017-02-04 02:10:35.746657Z] Running download-and-extract step. 18:10:35 INFO - Running pre-action listener: _resource_record_pre_action 18:10:35 INFO - Running main action method: download_and_extract 18:10:35 INFO - mkdir: /builds/slave/test/build/tests 18:10: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:10:35 INFO - trying https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:10:35 INFO - Downloading https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:10:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 18:10:37 INFO - Downloaded 1199 bytes. 18:10:37 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:10:37 INFO - Using the following test package requirements: 18:10:37 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:10:37 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:10:37 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:10:37 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'jsshell-mac.zip'], 18:10:37 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:10:37 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:10:37 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:10:37 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:10:37 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:10:37 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:10:37 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:10:37 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:10:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:10:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:10:37 INFO - Fetch https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:10:38 INFO - Expected file size: 34849825 18:10:38 INFO - Obtained file size: 34849825 18:10:45 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:10:46 INFO - Expected file size: 34849825 18:10:46 INFO - Obtained file size: 34849825 18:10:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:10:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:10:53 INFO - Fetch https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:10:54 INFO - Expected file size: 14403454 18:10:54 INFO - Obtained file size: 14403454 18:10:55 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:10:55 INFO - trying https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:10:55 INFO - Downloading https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:10:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:10:57 INFO - Downloaded 70288639 bytes. 18:10:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:10:57 INFO - mkdir: /builds/slave/test/properties 18:10:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:10:57 INFO - Writing to file /builds/slave/test/properties/build_url 18:10:57 INFO - Contents: 18:10:57 INFO - build_url:https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:10:58 INFO - Running post-action listener: _resource_record_post_action 18:10:58 INFO - Running post-action listener: set_extra_try_arguments 18:10:58 INFO - [mozharness: 2017-02-04 02:10:58.548365Z] Finished download-and-extract step (success) 18:10:58 INFO - [mozharness: 2017-02-04 02:10:58.548495Z] Running populate-webroot step. 18:10:58 INFO - Running pre-action listener: _resource_record_pre_action 18:10:58 INFO - Running main action method: populate_webroot 18:10:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:10:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:10:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:10:58 INFO - '--tppagecycles', 18:10:58 INFO - '7'], 18:10:58 INFO - 'tests': ['tsvgm']}}, 18:10:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:10:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:10:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:10:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:10:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:10:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:10:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['damp', 'tps']}, 18:10:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'tests': ['damp', 'tps']}, 18:10:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['dromaeo_dom']}, 18:10:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:10:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['basic_compositor_video']}, 18:10:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:10:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['a11yr', 18:10:58 INFO - 'ts_paint', 18:10:58 INFO - 'tpaint', 18:10:58 INFO - 'sessionrestore', 18:10:58 INFO - 'sessionrestore_no_auto_restore', 18:10:58 INFO - 'tabpaint']}, 18:10:58 INFO - 'other-e10s': {'tests': ['a11yr', 18:10:58 INFO - 'ts_paint', 18:10:58 INFO - 'tpaint', 18:10:58 INFO - 'sessionrestore', 18:10:58 INFO - 'sessionrestore_no_auto_restore', 18:10:58 INFO - 'tabpaint']}, 18:10:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:10:58 INFO - 'ts_paint', 18:10:58 INFO - 'tpaint', 18:10:58 INFO - 'sessionrestore', 18:10:58 INFO - 'sessionrestore_no_auto_restore', 18:10:58 INFO - 'tabpaint']}, 18:10:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:10:58 INFO - 'ts_paint', 18:10:58 INFO - 'tpaint', 18:10:58 INFO - 'sessionrestore', 18:10:58 INFO - 'sessionrestore_no_auto_restore', 18:10:58 INFO - 'tabpaint']}, 18:10:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['a11yr', 18:10:58 INFO - 'ts_paint', 18:10:58 INFO - 'tpaint', 18:10:58 INFO - 'sessionrestore', 18:10:58 INFO - 'sessionrestore_no_auto_restore', 18:10:58 INFO - 'tabpaint']}, 18:10:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['a11yr', 18:10:58 INFO - 'ts_paint', 18:10:58 INFO - 'tpaint', 18:10:58 INFO - 'sessionrestore', 18:10:58 INFO - 'sessionrestore_no_auto_restore', 18:10:58 INFO - 'tabpaint']}, 18:10:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['tsvgx', 18:10:58 INFO - 'tsvgr_opacity', 18:10:58 INFO - 'tart', 18:10:58 INFO - 'tscrollx', 18:10:58 INFO - 'cart', 18:10:58 INFO - 'tsvg_static']}, 18:10:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:10:58 INFO - 'tsvgr_opacity', 18:10:58 INFO - 'tart', 18:10:58 INFO - 'tscrollx', 18:10:58 INFO - 'cart', 18:10:58 INFO - 'tsvg_static']}, 18:10:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'talos_options': ['--disable-e10s'], 18:10:58 INFO - 'tests': ['tp5o']}, 18:10:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:10:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'talos_options': ['--disable-e10s', 18:10:58 INFO - '--xperf_path', 18:10:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:10:58 INFO - 'tests': ['tp5n']}, 18:10:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:10:58 INFO - 'talos_options': ['--xperf_path', 18:10:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:10:58 INFO - 'tests': ['tp5n']}}, 18:10:58 INFO - 'talos.zip': {'path': '', 18:10:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:10:58 INFO - Running post-action listener: _resource_record_post_action 18:10:58 INFO - [mozharness: 2017-02-04 02:10:58.566633Z] Finished populate-webroot step (success) 18:10:58 INFO - [mozharness: 2017-02-04 02:10:58.566735Z] Running create-virtualenv step. 18:10:58 INFO - Running pre-action listener: _resource_record_pre_action 18:10:58 INFO - Running main action method: create_virtualenv 18:10:58 INFO - Creating virtualenv /builds/slave/test/build/venv 18:10:58 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:10:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:10:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:10:58 INFO - Using real prefix '/tools/python27' 18:10:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:10:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:11:02 INFO - Installing pip.................done. 18:11:02 INFO - Return code: 0 18:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:11:02 INFO - Reading from file tmpfile_stdout 18:11:02 INFO - Output received: 18:11:02 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:11:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:02 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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:02 INFO - 'HOME': '/Users/cltbld', 18:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:02 INFO - 'LOGNAME': 'cltbld', 18:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:02 INFO - 'MOZ_NO_REMOTE': '1', 18:11:02 INFO - 'NO_EM_RESTART': '1', 18:11:02 INFO - 'PAGER': '/bin/cat', 18:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:02 INFO - 'PWD': '/builds/slave/test', 18:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:02 INFO - 'SHELL': '/bin/bash', 18:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:02 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:02 INFO - 'USER': 'cltbld', 18:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:02 INFO - 'XPC_FLAGS': '0x0', 18:11:02 INFO - 'XPC_SERVICE_NAME': '0', 18:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:11:02 INFO - Cleaning up... 18:11:03 INFO - Return code: 0 18:11:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:03 INFO - 'HOME': '/Users/cltbld', 18:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:03 INFO - 'LOGNAME': 'cltbld', 18:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:03 INFO - 'MOZ_NO_REMOTE': '1', 18:11:03 INFO - 'NO_EM_RESTART': '1', 18:11:03 INFO - 'PAGER': '/bin/cat', 18:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:03 INFO - 'PWD': '/builds/slave/test', 18:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:03 INFO - 'SHELL': '/bin/bash', 18:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:03 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:03 INFO - 'USER': 'cltbld', 18:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:03 INFO - 'XPC_FLAGS': '0x0', 18:11:03 INFO - 'XPC_SERVICE_NAME': '0', 18:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:03 INFO - Downloading/unpacking psutil>=3.1.1 18:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:11:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:07 INFO - Installing collected packages: psutil 18:11:07 INFO - Running setup.py install for psutil 18:11:07 INFO - building 'psutil._psutil_osx' extension 18:11:07 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:11:07 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:11:07 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:11:07 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:11:08 INFO - building 'psutil._psutil_posix' extension 18:11:08 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:11:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:11:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:11:08 INFO - ^ 18:11:08 INFO - 1 warning generated. 18:11:08 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:11:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:08 INFO - Successfully installed psutil 18:11:08 INFO - Cleaning up... 18:11:08 INFO - Return code: 0 18:11:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:08 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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:08 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:11:08 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:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:08 INFO - 'HOME': '/Users/cltbld', 18:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:08 INFO - 'LOGNAME': 'cltbld', 18:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:08 INFO - 'MOZ_NO_REMOTE': '1', 18:11:08 INFO - 'NO_EM_RESTART': '1', 18:11:08 INFO - 'PAGER': '/bin/cat', 18:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:08 INFO - 'PWD': '/builds/slave/test', 18:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:08 INFO - 'SHELL': '/bin/bash', 18:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:08 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:08 INFO - 'USER': 'cltbld', 18:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:08 INFO - 'XPC_FLAGS': '0x0', 18:11:08 INFO - 'XPC_SERVICE_NAME': '0', 18:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:11:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:11:11 INFO - Installing collected packages: mozsystemmonitor 18:11:11 INFO - Running setup.py install for mozsystemmonitor 18:11:11 INFO - Successfully installed mozsystemmonitor 18:11:11 INFO - Cleaning up... 18:11:11 INFO - Return code: 0 18:11:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:11 INFO - 'HOME': '/Users/cltbld', 18:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:11 INFO - 'LOGNAME': 'cltbld', 18:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:11 INFO - 'MOZ_NO_REMOTE': '1', 18:11:11 INFO - 'NO_EM_RESTART': '1', 18:11:11 INFO - 'PAGER': '/bin/cat', 18:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:11 INFO - 'PWD': '/builds/slave/test', 18:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:11 INFO - 'SHELL': '/bin/bash', 18:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:11 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:11 INFO - 'USER': 'cltbld', 18:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:11 INFO - 'XPC_FLAGS': '0x0', 18:11:11 INFO - 'XPC_SERVICE_NAME': '0', 18:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:11 INFO - Downloading/unpacking jsonschema==2.5.1 18:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:11:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:14 INFO - Downloading functools32-3.2.3-2.tar.gz 18:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:11:14 INFO - warning: no files found matching '*.txt' 18:11:14 INFO - no previously-included directories found matching 'build' 18:11:14 INFO - no previously-included directories found matching 'dist' 18:11:14 INFO - no previously-included directories found matching '.git*' 18:11:14 INFO - Installing collected packages: jsonschema, functools32 18:11:15 INFO - Running setup.py install for functools32 18:11:15 INFO - warning: no files found matching '*.txt' 18:11:15 INFO - no previously-included directories found matching 'build' 18:11:15 INFO - no previously-included directories found matching 'dist' 18:11:15 INFO - no previously-included directories found matching '.git*' 18:11:15 INFO - Successfully installed jsonschema functools32 18:11:15 INFO - Cleaning up... 18:11:15 INFO - Return code: 0 18:11:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:15 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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:15 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:11:15 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:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:15 INFO - 'HOME': '/Users/cltbld', 18:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:15 INFO - 'LOGNAME': 'cltbld', 18:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:15 INFO - 'MOZ_NO_REMOTE': '1', 18:11:15 INFO - 'NO_EM_RESTART': '1', 18:11:15 INFO - 'PAGER': '/bin/cat', 18:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:15 INFO - 'PWD': '/builds/slave/test', 18:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:15 INFO - 'SHELL': '/bin/bash', 18:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:15 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:15 INFO - 'USER': 'cltbld', 18:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:15 INFO - 'XPC_FLAGS': '0x0', 18:11:15 INFO - 'XPC_SERVICE_NAME': '0', 18:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:11:15 INFO - Cleaning up... 18:11:15 INFO - Return code: 0 18:11:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:15 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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:15 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:11:15 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:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:15 INFO - 'HOME': '/Users/cltbld', 18:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:15 INFO - 'LOGNAME': 'cltbld', 18:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:15 INFO - 'MOZ_NO_REMOTE': '1', 18:11:15 INFO - 'NO_EM_RESTART': '1', 18:11:15 INFO - 'PAGER': '/bin/cat', 18:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:15 INFO - 'PWD': '/builds/slave/test', 18:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:15 INFO - 'SHELL': '/bin/bash', 18:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:15 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:15 INFO - 'USER': 'cltbld', 18:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:15 INFO - 'XPC_FLAGS': '0x0', 18:11:15 INFO - 'XPC_SERVICE_NAME': '0', 18:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:15 INFO - Downloading/unpacking blobuploader==1.2.4 18:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:18 INFO - Downloading blobuploader-1.2.4.tar.gz 18:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:11:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:11:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:19 INFO - Downloading docopt-0.6.1.tar.gz 18:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:11:19 INFO - Installing collected packages: blobuploader, requests, docopt 18:11:19 INFO - Running setup.py install for blobuploader 18:11:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:11:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:11:19 INFO - Running setup.py install for requests 18:11:20 INFO - Running setup.py install for docopt 18:11:20 INFO - Successfully installed blobuploader requests docopt 18:11:20 INFO - Cleaning up... 18:11:20 INFO - Return code: 0 18:11:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11: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:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:20 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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:20 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:11:20 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:11:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:20 INFO - 'HOME': '/Users/cltbld', 18:11:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:20 INFO - 'LOGNAME': 'cltbld', 18:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:20 INFO - 'MOZ_NO_REMOTE': '1', 18:11:20 INFO - 'NO_EM_RESTART': '1', 18:11:20 INFO - 'PAGER': '/bin/cat', 18:11:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:20 INFO - 'PWD': '/builds/slave/test', 18:11:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:20 INFO - 'SHELL': '/bin/bash', 18:11:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:20 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:20 INFO - 'USER': 'cltbld', 18:11:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:20 INFO - 'XPC_FLAGS': '0x0', 18:11:20 INFO - 'XPC_SERVICE_NAME': '0', 18:11:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-K3muMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OelC26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5R3EY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-naH5OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iXpW_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-P7y2H3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-li1BuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tcmceJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vfQAJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-a7M9oE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-leszRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sMjnvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Cdz1b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sDN83I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-C3gyG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-dWzqe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9XS7EH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:11:22 INFO - Running setup.py install for manifestparser 18:11:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:11:22 INFO - Running setup.py install for mozcrash 18:11:22 INFO - Running setup.py install for mozdebug 18:11:23 INFO - Running setup.py install for mozdevice 18:11:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Running setup.py install for mozfile 18:11:23 INFO - Running setup.py install for mozhttpd 18:11:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Running setup.py install for mozinfo 18:11:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Running setup.py install for mozInstall 18:11:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:11:23 INFO - Running setup.py install for mozleak 18:11:23 INFO - Running setup.py install for mozlog 18:11:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:11:24 INFO - Running setup.py install for moznetwork 18:11:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:11:24 INFO - Running setup.py install for mozprocess 18:11:24 INFO - Running setup.py install for mozprofile 18:11:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:11:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:11:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:11:24 INFO - Running setup.py install for mozrunner 18:11:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:11:24 INFO - Running setup.py install for mozscreenshot 18:11:24 INFO - Running setup.py install for moztest 18:11:25 INFO - Running setup.py install for mozversion 18:11:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:11:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:11:25 INFO - Cleaning up... 18:11:25 INFO - Return code: 0 18:11:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:11:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:25 INFO - 'HOME': '/Users/cltbld', 18:11:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:25 INFO - 'LOGNAME': 'cltbld', 18:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:25 INFO - 'MOZ_NO_REMOTE': '1', 18:11:25 INFO - 'NO_EM_RESTART': '1', 18:11:25 INFO - 'PAGER': '/bin/cat', 18:11:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:25 INFO - 'PWD': '/builds/slave/test', 18:11:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:25 INFO - 'SHELL': '/bin/bash', 18:11:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:25 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:25 INFO - 'USER': 'cltbld', 18:11:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:25 INFO - 'XPC_FLAGS': '0x0', 18:11:25 INFO - 'XPC_SERVICE_NAME': '0', 18:11:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-m1ZFCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-osxjpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1rcAPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-R5DBvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-YYML2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0frHLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-aNNEpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-R_e1EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pWJUhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:26 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:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KhLZ9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:26 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:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sTACbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:26 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:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5vFuY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tQGhfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:26 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:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zzMlKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:26 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:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Svc5bF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tbHVZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_mVSu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:11:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:29 INFO - Downloading blessings-1.6.tar.gz 18:11:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:11:29 INFO - Installing collected packages: blessings 18:11:29 INFO - Running setup.py install for blessings 18:11:30 INFO - Successfully installed blessings 18:11:30 INFO - Cleaning up... 18:11:30 INFO - Return code: 0 18:11:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:11:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:30 INFO - Reading from file tmpfile_stdout 18:11:30 INFO - Current package versions: 18:11:30 INFO - blessings == 1.6 18:11:30 INFO - blobuploader == 1.2.4 18:11:30 INFO - docopt == 0.6.1 18:11:30 INFO - functools32 == 3.2.3-2 18:11:30 INFO - jsonschema == 2.5.1 18:11:30 INFO - manifestparser == 1.1 18:11:30 INFO - mozInstall == 1.12 18:11:30 INFO - mozcrash == 1.0 18:11:30 INFO - mozdebug == 0.1 18:11:30 INFO - mozdevice == 0.49 18:11:30 INFO - mozfile == 1.2 18:11:30 INFO - mozhttpd == 0.7 18:11:30 INFO - mozinfo == 0.9 18:11:30 INFO - mozleak == 0.1 18:11:30 INFO - mozlog == 3.4 18:11:30 INFO - moznetwork == 0.27 18:11:30 INFO - mozprocess == 0.24 18:11:30 INFO - mozprofile == 0.28 18:11:30 INFO - mozrunner == 6.13 18:11:30 INFO - mozscreenshot == 0.1 18:11:30 INFO - mozsystemmonitor == 0.3 18:11:30 INFO - moztest == 0.8 18:11:30 INFO - mozversion == 1.4 18:11:30 INFO - psutil == 3.1.1 18:11:30 INFO - requests == 1.2.3 18:11:30 INFO - wsgiref == 0.1.2 18:11:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11: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:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:30 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 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:30 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:11:30 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:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:30 INFO - 'HOME': '/Users/cltbld', 18:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:30 INFO - 'LOGNAME': 'cltbld', 18:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:30 INFO - 'MOZ_NO_REMOTE': '1', 18:11:30 INFO - 'NO_EM_RESTART': '1', 18:11:30 INFO - 'PAGER': '/bin/cat', 18:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:30 INFO - 'PWD': '/builds/slave/test', 18:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:30 INFO - 'SHELL': '/bin/bash', 18:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:30 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:30 INFO - 'USER': 'cltbld', 18:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:30 INFO - 'XPC_FLAGS': '0x0', 18:11:30 INFO - 'XPC_SERVICE_NAME': '0', 18:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 INFO - Cleaning up... 18:11:30 INFO - Return code: 0 18:11:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:11: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:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa641c502f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, '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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11: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', 'jsonschema'] in /builds/slave/test/build 18:11: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 jsonschema 18:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:30 INFO - 'HOME': '/Users/cltbld', 18:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:30 INFO - 'LOGNAME': 'cltbld', 18:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:30 INFO - 'MOZ_NO_REMOTE': '1', 18:11:30 INFO - 'NO_EM_RESTART': '1', 18:11:30 INFO - 'PAGER': '/bin/cat', 18:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:30 INFO - 'PWD': '/builds/slave/test', 18:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:30 INFO - 'SHELL': '/bin/bash', 18:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:30 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:30 INFO - 'USER': 'cltbld', 18:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:30 INFO - 'XPC_FLAGS': '0x0', 18:11:30 INFO - 'XPC_SERVICE_NAME': '0', 18:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:11:30 INFO - Cleaning up... 18:11:31 INFO - Return code: 0 18:11:31 INFO - Running post-action listener: _resource_record_post_action 18:11:31 INFO - Running post-action listener: _start_resource_monitoring 18:11:31 INFO - Starting resource monitoring. 18:11:31 INFO - [mozharness: 2017-02-04 02:11:31.033169Z] Finished create-virtualenv step (success) 18:11:31 INFO - [mozharness: 2017-02-04 02:11:31.034098Z] Running install step. 18:11:31 INFO - Running pre-action listener: _resource_record_pre_action 18:11:31 INFO - Running main action method: install 18:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:31 INFO - Reading from file tmpfile_stdout 18:11:31 INFO - Detecting whether we're running mozinstall >=1.0... 18:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:11:31 INFO - Reading from file tmpfile_stdout 18:11:31 INFO - Output received: 18:11:31 INFO - Usage: mozinstall [options] installer 18:11:31 INFO - Options: 18:11:31 INFO - -h, --help show this help message and exit 18:11:31 INFO - -d DEST, --destination=DEST 18:11:31 INFO - Directory to install application into. [default: 18:11:31 INFO - "/builds/slave/test"] 18:11:31 INFO - --app=APP Application being installed. [default: firefox] 18:11:31 INFO - mkdir: /builds/slave/test/build/application 18:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:11:53 INFO - Reading from file tmpfile_stdout 18:11:53 INFO - Output received: 18:11:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:11:53 INFO - Running post-action listener: _resource_record_post_action 18:11:53 INFO - [mozharness: 2017-02-04 02:11:53.265726Z] Finished install step (success) 18:11:53 INFO - [mozharness: 2017-02-04 02:11:53.265841Z] Running run-tests step. 18:11:53 INFO - Running pre-action listener: _resource_record_pre_action 18:11:53 INFO - Running main action method: run_tests 18:11:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:11:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:11:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:11:53 INFO - 2017-02-03 18:11:53.280 screenresolution[1627:7928] starting screenresolution argv=screenresolution get 18:11:53 INFO - 2017-02-03 18:11:53.283 screenresolution[1627:7928] Display 0: 1600x1200x32@60 18:11:53 INFO - 2017-02-03 18:11:53.288 screenresolution[1628:7931] starting screenresolution argv=screenresolution list 18:11:53 INFO - Available Modes on Display 0 18:11:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:11:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:11:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:11:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:11:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:11:53 INFO - Intel Iris: 18:11:53 INFO - Chipset Model: Intel Iris 18:11:53 INFO - Type: GPU 18:11:53 INFO - Bus: Built-In 18:11:53 INFO - VRAM (Dynamic, Max): 1536 MB 18:11:53 INFO - Vendor: Intel (0x8086) 18:11:53 INFO - Device ID: 0x0a2e 18:11:53 INFO - Revision ID: 0x0009 18:11:53 INFO - Displays: 18:11:53 INFO - Display: 18:11:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:11:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:11:53 INFO - Display Serial Number: 1600x1200 60 18:11:53 INFO - Main Display: Yes 18:11:53 INFO - Mirror: Off 18:11:53 INFO - Online: Yes 18:11:53 INFO - Rotation: Supported 18:11:53 INFO - Return code: 0 18:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:11:53 INFO - Python 2.7.3 18:11:53 INFO - Return code: 0 18:11:53 INFO - grabbing minidump binary from tooltool 18:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:53 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 0x101eafd30>, '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 0x101dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ea4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:11:53 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:11:53 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:11:53 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:11:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:11:53 INFO - Return code: 0 18:11:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:11:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:11:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:11:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:11:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:11:53 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/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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:11:53 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/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SgFBF2cCNW/Render', 18:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:53 INFO - 'HOME': '/Users/cltbld', 18:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:53 INFO - 'LOGNAME': 'cltbld', 18:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:53 INFO - 'MOZ_NO_REMOTE': '1', 18:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:11:53 INFO - 'NO_EM_RESTART': '1', 18:11:53 INFO - 'PAGER': '/bin/cat', 18:11:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:53 INFO - 'PWD': '/builds/slave/test', 18:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:53 INFO - 'SHELL': '/bin/bash', 18:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iaZDIuzCZz/Listeners', 18:11:53 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:53 INFO - 'USER': 'cltbld', 18:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:53 INFO - 'XPC_FLAGS': '0x0', 18:11:53 INFO - 'XPC_SERVICE_NAME': '0', 18:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:53 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/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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:11:53 INFO - mozversion application_buildid: 20170203170012 18:11:53 INFO - mozversion application_changeset: 94079d43835ff229dce0f9b226cedd64c6aaef60 18:11:53 INFO - mozversion application_display_name: Nightly 18:11:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:11:53 INFO - mozversion application_name: Firefox 18:11:53 INFO - mozversion application_remotingname: firefox 18:11:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:11:53 INFO - mozversion application_vendor: Mozilla 18:11:53 INFO - mozversion application_version: 54.0a1 18:11:53 INFO - mozversion platform_buildid: 20170203170012 18:11:53 INFO - mozversion platform_changeset: 94079d43835ff229dce0f9b226cedd64c6aaef60 18:11:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:11:53 INFO - mozversion platform_version: 54.0a1 18:11:53 INFO - using testdate: 1486174313 18:11:53 INFO - actual date: 1486174313 18:11:53 INFO - starting webserver on 'localhost:49240' 18:11:53 INFO - SUITE-START | Running 2 tests 18:11:53 INFO - TEST-START | dromaeo_css 18:11:53 INFO - operating with platform_type : mac_ 18:11:53 INFO - Initialising browser for dromaeo_css test... 18:11:53 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmppDQRcs/profile http://localhost:49240/getInfo.html) 18:11:56 INFO - PROCESS | 1638 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:11:56 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 18:11:56 INFO - PROCESS | 1638 | colorDepth:24 18:11:56 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 18:11:56 INFO - PROCESS | 1638 | __metrics 18:11:56 INFO - PROCESS | 1638 | 1486174316592 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:11:56 INFO - PROCESS | 1638 | 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:11:56 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:11:57 INFO - TEST-INFO | 1638: exit 0 18:11:57 INFO - Browser initialized. 18:11:57 INFO - Running cycle 1/1 for dromaeo_css test... 18:11:57 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmppDQRcs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:09 INFO - PROCESS | 1641 | RSS: Main: 236564480 18:12:09 INFO - PROCESS | 1641 | 18:15:12 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 18:15:14 INFO - PROCESS | 1641 | RSS: Main: 356397056 18:15:14 INFO - PROCESS | 1641 | 18:16:59 INFO - PROCESS | 1641 | ************************* 18:16:59 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 18:16:59 INFO - PROCESS | 1641 | 18:16:59 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:16:59 INFO - PROCESS | 1641 | Full stack: 18:16:59 INFO - PROCESS | 1641 | ************************* 18:18:17 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 18:18:18 INFO - PROCESS | 1641 | RSS: Main: 368349184 18:18:18 INFO - PROCESS | 1641 | 18:21:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 18:21:21 INFO - PROCESS | 1641 | RSS: Main: 364822528 18:21:21 INFO - PROCESS | 1641 | 18:24:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 18:24:24 INFO - PROCESS | 1641 | RSS: Main: 351055872 18:24:24 INFO - PROCESS | 1641 | 18:27:28 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 18:27:28 INFO - PROCESS | 1641 | RSS: Main: 339120128 18:27:28 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 18:30:32 INFO - PROCESS | 1641 | RSS: Main: 389599232 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | __start_tp_report 18:30:32 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 18:30:32 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 18:30:32 INFO - PROCESS | 1641 | |i|pagename|runs| 18:30:32 INFO - PROCESS | 1641 | |0;dojo.html;2769.8615069246534;3305.8181800001007;3341.315030418764;3344.4147274227003;3388.322335532894;5566.4990150886415;5654.095344744842;5698.601097923141;5761.539076873851;5774.191613174154;5664.490195882369;5685.488306052454;5714.485696287334;5787.710614469281;5806.158107074471;5125.154349532323;5174.586033117361;5187.351581052369;5221.347331583552;5224.503672151159;5332.92000619991;5357.116075847481;5358.490943360394;5363.329583802025;5419.83740487786;5101.464346243647;5115.437301896788;5130.025295193901;5191.247269145991;5286.867828304304;5185.766640501187;5251.212318152269;5275.208718692188;5322.441143679896;5347.6256662033675;4366.6506679465565;5169.586433085344;5206.531412172884;5255.86860328491;5280.9438112377675;5579.721013949286;5604.915926261109;5745.253117094792;5766.481016708474;5789.594728369016;5183.3002544656365;6163.722632481549;6197.876042479125;6227.532935029845;6264.248290205204;6178.907316390258;6236.532260080512;6267.05994100884;6283.183186185766;6311.495080393557;5848.122781582797;5901.203337549418;5914;5915.1718759373725;5969.313528944183;6134.846828498841;6226.844328891723;6251.843703907348;6253.749850005949;6282.089097080898;6163.075538669235;6229.906551401733;6241.812745617548;6245.531585131133;6248.875022499616;6275.874482510324;6292.937070629235;6306.369363063749;6309.716062777186;6320.905186422207;6112.777056696867;6120.081987701791;6129.35641757609;6245.656488893154;6258.718357673915;6168.876622467616;6209.379062093845;6223.502119830403;6270.216222972129;6342.8414289642205;5022.4977502249485;5025.170846810309;5028.069807085628;5033.672811267256;5059.8988020239385;4497.752623605733;4499.775011249424;4502.077074199781;4505.986153115588;4509.616682581952;5586.497215250647;5624.184493248456;5624.959382514248;5702.201691763156;5714.685692286964;6249.781257656051;6252.3122456530655;6284.43440090385;6297.559170857996;6319.778807741707;5766.538676905838;5783.595148339576;5797.884042319214;5798.217240672539;5833.854153646193;5309.256704061435;5330.120530112488;5365.704886231295;5480.123180291134;5480.671159730429;6202.56582039262;6237.75048998035;6306.779262725782;6316.905246421399;6465.127207826883;6146.524834039709;6174.536909731698;6178.845528861814;6310.842228944313;6337.493000560036;6179.6910154493;6237.563370564017;6301.0863424804065;6350.333215012357;6365.458935990493;6291.1192433058495;6301.873962520815;6303.086052522451;6304.527160463075;6311.053341998737;6130.172426722335;6132.84504737327;6136.6722581676495;6230.158928544589;6239.563230573816;5107.361579802525;6197.194364732556;6199.411055949678;6210.285817131088;6211.782587609412;6178.104174894705;6191.5665903388135;6199.132121503083;6200.81397558074;6205.100260462117;5732.713364331683;5819.679917604572;6075.7569697213385;6076.361981992004;6077.696115194134;6037.547183961133;6088.817335479943;6141.232345956755;6148.077788331786;6216.53375996793;6053.02751013817;6053.606515576474;6074.817755467343;6078.179445774941;6123.724432400463 18:30:32 INFO - PROCESS | 1641 | |1;ext.html;450.59896691944164;494.69081823860085;517.9404368497621;530.3927003580899;532.5340327213688;39119.60880391195;41362.965925851866;41455.58544414555;41503;41641;40928.79535602325;41491.170176596526;41570;41574;41607.37588936168;6677.565958212725;8155.551444670541;9162.083791620835;9450.338476306626;9480.24158067353;9574.712758617252;11068.944655276713;11180.496877640526;11247.66257012291;11381.430928453585;11189.44052797361;11260.268082574647;11263.380514071723;11271.718207044807;11283.492242849092;7995.760127196135;8232.50604963704;11129.888701112966;11178.217524773268;11307.64308283003;11217.60738374153;11236.58413441106;11244.943775281154;11251.0436612888;11253.324800511913;39836.60163398358;40142.19715605672;40255.39616905749;40276.79861600703;40286.79856600698;3937.625925537098;3949.4273330367223;3952.150287688161;3958.1489979654225;4081.877483691979;28768.71231287702;29502;29942.95199668023;29952.550711739343;29980.70019299801;3109.4713898637287;3143.4184675834813;3190.760692948015;3194.393065317582;3283.2448536836423;437526;439594;440576;441473;444940;48630;49727.75136124296;55309.170362444595;55456.72271638616;55732.442675572725;53432.198517022276;62403;63182.68408657928;63490.682546587894;65899.67050164814;14877.702445951021;14985.850141498664;15013.399464021317;15047.548573542812;15051.021683590532;11944.98467630261;12054.216475929037;12060.914517693274;12061.457234424472;12064.336461494702;15179.924100379427;15564.688706225812;15617.3753049879;15645.765313520078;15801.36568589231;6621.238557565942;6771;6793.864122717617;6803.2856550062525;6849.965750171217;631.4821846086257;748.8015675845905;804.9415019249785;863.4042510667745;872.7381785464336;26878.5968210477;28928.566071508943;29358.559621605695;29592.704072959426;30202.9428969985;1000.2748007694363;1016.3698506925606;1016.4307987527009;1048.8793788714243;1056.6460235820932;13671.521496747766;14188.716225675635;14293.927955403386;14577.97954143221;14580.343884525222;587235;591457;591736;593253;593297;49762.50237497579;50156.24765628519;50165.49834501608;50212.497875021516;50261.246081308076;1364.8430430500619;1391.3877893726728;1415.4691990503354;1417.8156839610785;1418.9077709949233;23390.18134365289;26424.0751573694;26953.730462695905;27568.44863102767;27619.03333383322;44545;44786.3282050776;44806.55193448154;44952.775236124915;44968.32547511724;415453;500618;501072;501321;501636;21484.785152148277;21981.34055978323;23688.170914017926;23840.880795595913;24463;40715.77852664425;40886.15421436915;41283.96790080272;41516.79241603773;41766.79116604398;27663.58504622392;28225.71774282313;33455.33089338248;33840.32319353648;34902.65097348994;26895.596566051132;30968.225794355323;34497.44761485789;34501;34668.13329666779;32703.528341225177;33199.50200747039;33489.497657534666;34615;35636.109097272776;29766;31427.057188284387;31532.52701209438;31551.684483154873;31634.683653164095;23494.76505234926;23890;23984.86796705484;24027.879860601286;24213.152539661027 18:30:32 INFO - PROCESS | 1641 | |2;jquery.html;344.27186500551335;401.5101576077185;402.6557293514044;416.46068341497755;435.1167130724628;12776.233425994447;19802.90098549509;19898.60202795945;20693.379198624047;20716.48208794779;20005.999700014978;20063;20265.696014559795;20321.085551150198;20619.38141855743;5326.201069839516;5751.597388182829;5813.06990881459;5823.650580965155;5844.094165404369;4767.90464190716;5660.405657405975;5666.971665141689;5760.798372056979;5766.942330576703;5714.628549144313;5771.913421298663;5773.422657734214;5773.913391299134;5787.102999035156;5594.412586678381;5766.711664416784;5767.394423585526;5775.191473193756;5865.032269675498;5801.7389217485315;5805.7387417566315;5813.128030795372;5818.970905145489;5832.737526811304;13445.26051067191;18214.27142914282;24692.753072469226;25153;25470.617940730903;2573.266619013591;2595.8572278524675;2597.59737240728;2598.9350266243305;2667.8932842686268;9712.75718107046;9976.501174941297;10005.64980225696;10522.21083418739;10535.473226338729;1836.3249587748496;1858.7397764313007;1861.255497800877;1878.3613571385658;1892;120238;121234.39382803117;134899;140395.59604404029;141017;9501.762455938657;11489.597864074718;11742.765144697058;11781.234219775859;11812.70468238301;13940.721185576233;14498.492552760814;15676.843231567538;15701.921490392704;15733.370665173494;7740.458167928191;10337.689869303933;10435.32170408921;10484.26610137283;10809.945950270197;8345.582720863933;8444.746657600283;8456.154384561618;8650.740477785677;8840.33697472692;10438.154633405316;10535.209859260585;10572.471376431147;10680.092192163753;10692.679219623424;3995.880123596297;4084.2648323301496;4133.297339452301;4162.62536371728;4180.435641188462;986.8519722041751;1001.4992503748126;1028.4343611013912;1046.7644779924456;1048.7640280936728;20617.278395256093;21061.473463163544;21168.470788230417;21267.893660531598;21449.67825482619;1017.2319898476603;1045.268312181476;1055.7044027672264;1075.4031512510633;1086.3590481615884;10327.74180645475;10657.786844263072;10694.946525267324;10710.94644526788;10712.303700259457;695647;701836;709547.1156682252;725045;727962;125995;145458;149053.25473372563;153421;158573.2071339636;894.3605322194662;950.7385468995943;959.194276807485;969.767255858603;993.4138858073627;17894.373696920546;18434.89390636566;18472.445826625222;18479.53801154982;18532.444026679223;47027.29459058048;51475.485245148564;55553.44446555483;57350.42649573451;57412;84402;89780.5510972441;89902.55048724715;90409.54795225982;92601;8184.631691574013;8334.87497687523;8353.498790072616;8552.786180345542;8660.437071590326;10720.981506756845;11465.656030319104;11650.242734222249;12154;12765.680857978625;9132.680356187502;9651;10727.624533141303;11066.28069175501;11091.611793587186;9077.592973089182;9381.437113773196;10387.480625968823;10829.592153020058;10992.340459572451;10441.425721585385;10870.510827012646;11131.276467029616;11330.263532870336;11340.659780206606;7000.579965202104;8137.575924213121;8233.711820086268;8401.067754416641;8428.283595894418;9166.266698664223;10977.582391410031;11049.447527623748;11058.949399807005;11186.04918581897 18:30:32 INFO - PROCESS | 1641 | |3;mootools.html;1402.388225492919;1439.4098419647946;1461.1379286221133;1461.8903582231333;1464.7070585882823;8467.153284671538;8836.646534138627;8838.42550234236;8844.734657960264;8868.689595864145;8347.874781878276;8663.436876603033;8670.653173873037;8761.123887611237;8763.956180219106;2296.6210575255104;2328.3247858121126;2330.0796185506715;2331.428799944016;2334.8599084054927;2315.918942837001;2352.4706940938236;2361.5631108244943;2362.8818559072065;2363.0429675981254;2308.3536609749212;2309.064828744353;2329.94175145621;2335.182686059874;2337;1893.4698284480367;2291.3584196424863;2298.321995011467;2317.5480781247666;2324.6629238760456;2265.4676151104477;2340.8829558522007;2351.294611616508;2353.2822489140804;2354.799842013431;8600.8709869352;8683.869741953877;8686.391952563323;8693.347998900108;8704.608292626846;5100.9489905100845;5256.369235691704;5260.92108618371;5261.76322065508;5266.183741520071;3339.131825725329;3397.59228892532;3528.347255757693;3550.5029295898594;3588.0312315674623;1078.282941843673;1175.8295047218105;1215.744693614342;1249.2504497301636;1263.974720505594;9597.760055998586;10623.468826558641;10701.785964280749;11291.20961532693;11292.266002709797;4152.896177595584;4270.893227669271;4270.957290427118;4272.6154646082;4460.130274596422;8992.640294388282;9044.773880653036;9091.363604547618;9114.088591140833;9144.54277286133;5212.765425555859;5214.191800270964;5220.660657057279;5225.947740522623;5227.477252274818;4195.160967806451;4630.3517507548295;4695.85912422621;4854.2233242681705;4873.878153046131;3157.715805577463;3597.8021208833243;3667.211549516867;3727.1800203955086;3748.0629842539365;1150.6663067710438;1155.6764106050318;1157.4444266751839;1161.355447726503;1165.8251262310553;846.4709556527171;880.9075047120089;881.5063564403954;886.0394038647237;913.1507697840939;5425.647332923348;5516.696581688046;5534.5056834654415;5710.543156547466;5763.337216220105;909.9636014559476;959.9568019439142;994.8756405449319;1003.5784970312341;1033.6485594897772;3359.0258824940984;3457.0665920201404;3604.873829415958;3653;3797.4493698413576;10018.298719089593;10169.13562347194;10406.635767748092;10408.219383546264;10420.32267902584;10334.276600637882;10339.689809305582;10359.223058270509;10370.377777333384;10372.11836993864;7506.174320824705;7893.013373328334;7902.604869756489;8009.159038301011;8009.559474228857;5534.225208470737;5565.499105080562;5684.118961560965;5740.68426236561;5810.244668193108;8913.153250441197;9322.673706420243;9556.713298601053;9570.95214523923;9659.661911833053;9077.45535267886;9353.719388418358;9473.100055494717;9775.187676806194;9812.116909478182;6824.556403833735;7164.785056448315;7166.227279723219;7237.095363079615;7331.120265568166;8035;8100.797480063045;8109.594520273845;8116.350691944748;8125.431219814793;7123.252058533986;7285.526440781332;7328.230535793665;7354.558726476428;7388.704451822082;7051.259617740064;7116.074910261633;7201.4238860889955;7225.385842203262;7327.1207455103595;6396.360363963752;6522.934770652422;6527.738890444329;6528.804135875931;6562.8359291018105;4878.292647566012;5030.346055012825;5035.748212589429;5115.56517696;5128.051310507568;4402.7138236015835;4524.0951809637545;4543.54564543543;4618.145643056046;4682.086993036325 18:30:32 INFO - PROCESS | 1641 | |4;prototype.html;325.7247625756238;359.5792922280931;360.4485137739259;371.1019333213622;377.5997442710725;4565;5064.392272927244;5082.08522465956;5088.287639730441;5134.460881607433;4154.605312495316;4604.539546045402;5006.824761133361;5031.572316353106;5303.2575439438415;3461.774984626004;3849.7497662651976;3975.3639417693175;4115.691323150776;4159.708820382574;3627.727920405967;3676.522052133219;4084.6323830855217;4090.9181816363653;4140.482439695038;4116.259073366793;4122.484689413824;4141.4409054777525;4144.8549300774475;4145.917081658365;3262.124278539849;3863.1694185750057;3954.149857780591;4007.398890166484;4103.589641035873;3045.462041668965;3217.1957010747315;3221.6617255188103;3366.9158271043175;3677.4667673187237;4559.586528176276;4746.267612321512;4768.709590382625;5187.273781670569;5214.165733482625;4493.438320209973;4632.698874573142;4658.673892827504;4709.811607535695;4772.403449568804;4847.830325938575;5310.92033619496;5465.682111132201;5489.725513724298;5603.439656034405;3340.4822252550894;4021.833668236208;4025;4074.979625101885;4089.85685501009;5114.744262786883;5279.366476022863;5470.7264636768;5573.136163894603;5713.285839270091;3637.7453578249797;3736.8415791104303;3759.9248015039548;3870.41943708446;4102.733322334038;4144.274751918402;4166.687498437629;4170.833166673299;4181.849991252369;4200.600942910419;4571.81712731487;4641.651876109306;4669.649776266793;4697.248440249544;4740.265258884879;5263.973680131651;5499.340079190442;5520.503154716095;5521.723913804293;5543.501084902378;1126.6957921361186;1233.2785320587377;1264.968375790594;1332.4942814619612;1344.6907211341447;931.8119397767765;962.9903700962901;963.5182408795603;976.3263348289657;976.4092723902079;4290.849820256276;4448.955510444919;4587.357769912215;4676.954434414207;4738.573528382462;4559.52125026874;4745.2819352679935;4754.405699287589;4864.70811751289;5396.408059622421;4159.563245859202;4217.755762050203;4413.521470307483;4773.498782627844;4791.47293797682;2462.4336402627496;2713.470873179711;2759.9300524606547;2860.4982384248437;2862.912093404493;4748.1482221933475;5335.359756829205;5714.0571805652435;5724.542036637059;5735.365420855036;4623.722576645412;5039.44566097729;5386.434424385461;5537.667739935536;5607.915881261784;5048.671836330626;5684.49360919365;5705.4579814917515;5709.972959599366;5736.6844823534275;5060.684222102206;5371.919421208763;5379.946200538101;5380.031594312904;5413.969761339497;4722.952770472251;4885.389326334208;5138.486151384832;5330.680159190461;5337.185356978646;4810.807567697253;4938.48145944685;5176;5243.868903277296;5664.631798933056;3309.718673912744;3324.3351329734633;3434.3131373724855;3457.6715212054814;3691.963080369162;4315.78421078951;5385.698732482926;5405.864853378697;5426.891462170655;5628.187899346;4213.535081977077;4922.92615610759;5469.3983661797965;5487.640314664733;5487.914592455001;5542.695151766691;5565.666060036411;5585.882823435248;5587.385387607279;5613.46672066153;4293.227219100592;4688.906221875475;5198.402183748917;5227.497576623748;5437.02133615953;4955.225073958594;5569.248151499658;5569.291476236727;5572.832815015556;5623.228682964853;4449.822007119679;5092.796288148433;5404.270423492778;5417.187421886749;5479.561635069264 18:30:32 INFO - PROCESS | 1641 | |5;yui.html;713.1121753416996;744.497464211657;758.0145810446418;822.3585603229479;837.2883049408003;14641.267936603168;14797.92601036996;15233.847661523381;15261.160636165021;15280.083195008294;13094.083414161012;13198.406071726771;13236.338183090831;13310.334483275821;13414.59756207313;874.0603850860318;881.088073843572;890.7194233816359;895.2971917045121;907.8138981508807;888.6489836514573;898.4833720610675;902.1429641840245;904.3624244907357;905.0677801864091;950.6910254167424;951.7715748220412;952.0764858087643;953.31361419778;955.9044095590455;923.7275003873873;947.4457442396207;948.2603569216022;949.9002604726473;951.3293128344535;703.6657587645878;703.714995426853;705.6930235347589;706.0186340985982;706.4489698035541;14514.637134071627;14563.708725825423;14877.628059298497;15258.08451492914;15454.304556294994;13861.09902856311;14020.166332545963;14167.387316234397;14233.501827436094;14329.140251584939;11698.590549330733;11718.355490448022;11794.632762758549;11849.111316651286;12024.398780060961;3654.634536546324;3747.9063023424633;3758.891127117507;3787.7159213058853;3800.239952009609;102322.48838755833;102635.4868225654;110781.9999999992;111612.44193778979;111703.44148279288;9805.901940980642;10236.994104920554;10307.793844123076;10422.478876056319;10676.306040107369;21100.683489747666;21334.893325533274;21478.140874365163;21488.677669834964;21510;6707.8261304271555;7058.647067646597;7094.11323584552;7370.484066115428;7370.705171793068;4132.504099508078;4206.810693518799;4251.44731184944;4382.802773875183;4392.934105988413;3475.1485885957595;3511.438169892805;3596.1009747563107;3599.7120230381506;3601.747877648592;654.5745265577318;658.7858945842524;665.6871270502855;669.8091044052421;703.5989485993038;667.4560233409755;687.3504538211442;708.8086216721559;716.6774951271949;740.3484933258698;9985.101340879355;10251.179905607534;10306.742331441774;10584.259101862795;10597.364158150536;592.9762809487659;681.1825809029185;692.5982929900746;705.1573369823114;722.3282347416953;4593;4898.21628539432;4905.484924082992;4984.152694042022;5018.447970723218;86499.56750216208;87443;95698;107180.46409767901;112746;76331;93490.53254733683;95736.52131739436;96149.5577566337;98321.50839245757;69938.65030674916;71225.93161102588;76859.84710229351;78370.6081469589;79282;9511.857322140035;9530.904690953003;9597.28020398459;9628.422294662343;9665.081817227352;53716.19425708539;54046.45953540572;54908;55118;56364;56494;64284.67857660682;64414;64936;74268;12907.353947799187;13430.328483575977;13555.661108472366;13645.590632281048;13904.652383690489;14448.422063117358;14800.555983320517;14977.925110374379;15020.849791502382;15473.994190377589;12290.262584244974;13657.521986730417;13700.862991369959;13961.790573141208;14008;13554.728905422033;13652.24912629815;14392.928035359757;14434.711305774035;14441.638959026108;13288.734225315633;13946.79079813824;14106.365213565208;14108.50620228328;14162;11237.943810280896;11354.545818167511;11375.374354410256;11532.94233528827;11602.245854019462;9633.807323853624;10839.480643429653;11179.664610061385;11190.496427660612;11395.715107122389 18:30:32 INFO - PROCESS | 1641 | __end_tp_report 18:30:32 INFO - PROCESS | 1641 | __start_cc_report 18:30:32 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1892 18:30:32 INFO - PROCESS | 1641 | __end_cc_report 18:30:32 INFO - PROCESS | 1641 | __startTimestamp1486175432373__endTimestamp 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | ------- Summary: start ------- 18:30:32 INFO - PROCESS | 1641 | Number of tests: 6 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5758.82 Median:6074.82 stddev:662.11 (10.9%) stddev-sans-first:621.24 18:30:32 INFO - PROCESS | 1641 | Values: 2769.9 3305.8 3341.3 3344.4 3388.3 5566.5 5654.1 5698.6 5761.5 5774.2 5664.5 5685.5 5714.5 5787.7 5806.2 5125.2 5174.6 5187.4 5221.3 5224.5 5332.9 5357.1 5358.5 5363.3 5419.8 5101.5 5115.4 5130.0 5191.2 5286.9 5185.8 5251.2 5275.2 5322.4 5347.6 4366.7 5169.6 5206.5 5255.9 5280.9 5579.7 5604.9 5745.3 5766.5 5789.6 5183.3 6163.7 6197.9 6227.5 6264.2 6178.9 6236.5 6267.1 6283.2 6311.5 5848.1 5901.2 5914.0 5915.2 5969.3 6134.8 6226.8 6251.8 6253.7 6282.1 6163.1 6229.9 6241.8 6245.5 6248.9 6275.9 6292.9 6306.4 6309.7 6320.9 6112.8 6120.1 6129.4 6245.7 6258.7 6168.9 6209.4 6223.5 6270.2 6342.8 5022.5 5025.2 5028.1 5033.7 5059.9 4497.8 4499.8 4502.1 4506.0 4509.6 5586.5 5624.2 5625.0 5702.2 5714.7 6249.8 6252.3 6284.4 6297.6 6319.8 5766.5 5783.6 5797.9 5798.2 5833.9 5309.3 5330.1 5365.7 5480.1 5480.7 6202.6 6237.8 6306.8 6316.9 6465.1 6146.5 6174.5 6178.8 6310.8 6337.5 6179.7 6237.6 6301.1 6350.3 6365.5 6291.1 6301.9 6303.1 6304.5 6311.1 6130.2 6132.8 6136.7 6230.2 6239.6 5107.4 6197.2 6199.4 6210.3 6211.8 6178.1 6191.6 6199.1 6200.8 6205.1 5732.7 5819.7 6075.8 6076.4 6077.7 6037.5 6088.8 6141.2 6148.1 6216.5 6053.0 6053.6 6074.8 6078.2 6123.7 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:63254.49 Median:34168.89 stddev:136037.03 (398.1%) stddev-sans-first:136336.93 18:30:32 INFO - PROCESS | 1641 | Values: 450.6 494.7 517.9 530.4 532.5 39119.6 41363.0 41455.6 41503.0 41641.0 40928.8 41491.2 41570.0 41574.0 41607.4 6677.6 8155.6 9162.1 9450.3 9480.2 9574.7 11068.9 11180.5 11247.7 11381.4 11189.4 11260.3 11263.4 11271.7 11283.5 7995.8 8232.5 11129.9 11178.2 11307.6 11217.6 11236.6 11244.9 11251.0 11253.3 39836.6 40142.2 40255.4 40276.8 40286.8 3937.6 3949.4 3952.2 3958.1 4081.9 28768.7 29502.0 29943.0 29952.6 29980.7 3109.5 3143.4 3190.8 3194.4 3283.2 437526.0 439594.0 440576.0 441473.0 444940.0 48630.0 49727.8 55309.2 55456.7 55732.4 53432.2 62403.0 63182.7 63490.7 65899.7 14877.7 14985.9 15013.4 15047.5 15051.0 11945.0 12054.2 12060.9 12061.5 12064.3 15179.9 15564.7 15617.4 15645.8 15801.4 6621.2 6771.0 6793.9 6803.3 6850.0 631.5 748.8 804.9 863.4 872.7 26878.6 28928.6 29358.6 29592.7 30202.9 1000.3 1016.4 1016.4 1048.9 1056.6 13671.5 14188.7 14293.9 14578.0 14580.3 587235.0 591457.0 591736.0 593253.0 593297.0 49762.5 50156.2 50165.5 50212.5 50261.2 1364.8 1391.4 1415.5 1417.8 1418.9 23390.2 26424.1 26953.7 27568.4 27619.0 44545.0 44786.3 44806.6 44952.8 44968.3 415453.0 500618.0 501072.0 501321.0 501636.0 21484.8 21981.3 23688.2 23840.9 24463.0 40715.8 40886.2 41284.0 41516.8 41766.8 27663.6 28225.7 33455.3 33840.3 34902.7 26895.6 30968.2 34497.4 34501.0 34668.1 32703.5 33199.5 33489.5 34615.0 35636.1 29766.0 31427.1 31532.5 31551.7 31634.7 23494.8 23890.0 23984.9 24027.9 24213.2 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:39454.48 Median:21358.79 stddev:118742.35 (555.9%) stddev-sans-first:119039.13 18:30:32 INFO - PROCESS | 1641 | Values: 344.3 401.5 402.7 416.5 435.1 12776.2 19802.9 19898.6 20693.4 20716.5 20006.0 20063.0 20265.7 20321.1 20619.4 5326.2 5751.6 5813.1 5823.7 5844.1 4767.9 5660.4 5667.0 5760.8 5766.9 5714.6 5771.9 5773.4 5773.9 5787.1 5594.4 5766.7 5767.4 5775.2 5865.0 5801.7 5805.7 5813.1 5819.0 5832.7 13445.3 18214.3 24692.8 25153.0 25470.6 2573.3 2595.9 2597.6 2598.9 2667.9 9712.8 9976.5 10005.6 10522.2 10535.5 1836.3 1858.7 1861.3 1878.4 1892.0 120238.0 121234.4 134899.0 140395.6 141017.0 9501.8 11489.6 11742.8 11781.2 11812.7 13940.7 14498.5 15676.8 15701.9 15733.4 7740.5 10337.7 10435.3 10484.3 10809.9 8345.6 8444.7 8456.2 8650.7 8840.3 10438.2 10535.2 10572.5 10680.1 10692.7 3995.9 4084.3 4133.3 4162.6 4180.4 986.9 1001.5 1028.4 1046.8 1048.8 20617.3 21061.5 21168.5 21267.9 21449.7 1017.2 1045.3 1055.7 1075.4 1086.4 10327.7 10657.8 10694.9 10710.9 10712.3 695647.0 701836.0 709547.1 725045.0 727962.0 125995.0 145458.0 149053.3 153421.0 158573.2 894.4 950.7 959.2 969.8 993.4 17894.4 18434.9 18472.4 18479.5 18532.4 47027.3 51475.5 55553.4 57350.4 57412.0 84402.0 89780.6 89902.6 90409.5 92601.0 8184.6 8334.9 8353.5 8552.8 8660.4 10721.0 11465.7 11650.2 12154.0 12765.7 9132.7 9651.0 10727.6 11066.3 11091.6 9077.6 9381.4 10387.5 10829.6 10992.3 10441.4 10870.5 11131.3 11330.3 11340.7 7000.6 8137.6 8233.7 8401.1 8428.3 9166.3 10977.6 11049.4 11058.9 11186.0 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5488.63 Median:5121.81 stddev:3048.06 (59.5%) stddev-sans-first:3041.14 18:30:32 INFO - PROCESS | 1641 | Values: 1402.4 1439.4 1461.1 1461.9 1464.7 8467.2 8836.6 8838.4 8844.7 8868.7 8347.9 8663.4 8670.7 8761.1 8764.0 2296.6 2328.3 2330.1 2331.4 2334.9 2315.9 2352.5 2361.6 2362.9 2363.0 2308.4 2309.1 2329.9 2335.2 2337.0 1893.5 2291.4 2298.3 2317.5 2324.7 2265.5 2340.9 2351.3 2353.3 2354.8 8600.9 8683.9 8686.4 8693.3 8704.6 5100.9 5256.4 5260.9 5261.8 5266.2 3339.1 3397.6 3528.3 3550.5 3588.0 1078.3 1175.8 1215.7 1249.3 1264.0 9597.8 10623.5 10701.8 11291.2 11292.3 4152.9 4270.9 4271.0 4272.6 4460.1 8992.6 9044.8 9091.4 9114.1 9144.5 5212.8 5214.2 5220.7 5225.9 5227.5 4195.2 4630.4 4695.9 4854.2 4873.9 3157.7 3597.8 3667.2 3727.2 3748.1 1150.7 1155.7 1157.4 1161.4 1165.8 846.5 880.9 881.5 886.0 913.2 5425.6 5516.7 5534.5 5710.5 5763.3 910.0 960.0 994.9 1003.6 1033.6 3359.0 3457.1 3604.9 3653.0 3797.4 10018.3 10169.1 10406.6 10408.2 10420.3 10334.3 10339.7 10359.2 10370.4 10372.1 7506.2 7893.0 7902.6 8009.2 8009.6 5534.2 5565.5 5684.1 5740.7 5810.2 8913.2 9322.7 9556.7 9571.0 9659.7 9077.5 9353.7 9473.1 9775.2 9812.1 6824.6 7164.8 7166.2 7237.1 7331.1 8035.0 8100.8 8109.6 8116.4 8125.4 7123.3 7285.5 7328.2 7354.6 7388.7 7051.3 7116.1 7201.4 7225.4 7327.1 6396.4 6522.9 6527.7 6528.8 6562.8 4878.3 5030.3 5035.7 5115.6 5128.1 4402.7 4524.1 4543.5 4618.1 4682.1 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4369.04 Median:4747.21 stddev:1301.78 (27.4%) stddev-sans-first:1269.56 18:30:32 INFO - PROCESS | 1641 | Values: 325.7 359.6 360.4 371.1 377.6 4565.0 5064.4 5082.1 5088.3 5134.5 4154.6 4604.5 5006.8 5031.6 5303.3 3461.8 3849.7 3975.4 4115.7 4159.7 3627.7 3676.5 4084.6 4090.9 4140.5 4116.3 4122.5 4141.4 4144.9 4145.9 3262.1 3863.2 3954.1 4007.4 4103.6 3045.5 3217.2 3221.7 3366.9 3677.5 4559.6 4746.3 4768.7 5187.3 5214.2 4493.4 4632.7 4658.7 4709.8 4772.4 4847.8 5310.9 5465.7 5489.7 5603.4 3340.5 4021.8 4025.0 4075.0 4089.9 5114.7 5279.4 5470.7 5573.1 5713.3 3637.7 3736.8 3759.9 3870.4 4102.7 4144.3 4166.7 4170.8 4181.8 4200.6 4571.8 4641.7 4669.6 4697.2 4740.3 5264.0 5499.3 5520.5 5521.7 5543.5 1126.7 1233.3 1265.0 1332.5 1344.7 931.8 963.0 963.5 976.3 976.4 4290.8 4449.0 4587.4 4677.0 4738.6 4559.5 4745.3 4754.4 4864.7 5396.4 4159.6 4217.8 4413.5 4773.5 4791.5 2462.4 2713.5 2759.9 2860.5 2862.9 4748.1 5335.4 5714.1 5724.5 5735.4 4623.7 5039.4 5386.4 5537.7 5607.9 5048.7 5684.5 5705.5 5710.0 5736.7 5060.7 5371.9 5379.9 5380.0 5414.0 4723.0 4885.4 5138.5 5330.7 5337.2 4810.8 4938.5 5176.0 5243.9 5664.6 3309.7 3324.3 3434.3 3457.7 3692.0 4315.8 5385.7 5405.9 5426.9 5628.2 4213.5 4922.9 5469.4 5487.6 5487.9 5542.7 5565.7 5585.9 5587.4 5613.5 4293.2 4688.9 5198.4 5227.5 5437.0 4955.2 5569.2 5569.3 5572.8 5623.2 4449.8 5092.8 5404.3 5417.2 5479.6 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20455.26 Median:4229.13 stddev:29329.37 (693.5%) stddev-sans-first:29374.18 18:30:32 INFO - PROCESS | 1641 | Values: 713.1 744.5 758.0 822.4 837.3 14641.3 14797.9 15233.8 15261.2 15280.1 13094.1 13198.4 13236.3 13310.3 13414.6 874.1 881.1 890.7 895.3 907.8 888.6 898.5 902.1 904.4 905.1 950.7 951.8 952.1 953.3 955.9 923.7 947.4 948.3 949.9 951.3 703.7 703.7 705.7 706.0 706.4 14514.6 14563.7 14877.6 15258.1 15454.3 13861.1 14020.2 14167.4 14233.5 14329.1 11698.6 11718.4 11794.6 11849.1 12024.4 3654.6 3747.9 3758.9 3787.7 3800.2 102322.5 102635.5 110782.0 111612.4 111703.4 9805.9 10237.0 10307.8 10422.5 10676.3 21100.7 21334.9 21478.1 21488.7 21510.0 6707.8 7058.6 7094.1 7370.5 7370.7 4132.5 4206.8 4251.4 4382.8 4392.9 3475.1 3511.4 3596.1 3599.7 3601.7 654.6 658.8 665.7 669.8 703.6 667.5 687.4 708.8 716.7 740.3 9985.1 10251.2 10306.7 10584.3 10597.4 593.0 681.2 692.6 705.2 722.3 4593.0 4898.2 4905.5 4984.2 5018.4 86499.6 87443.0 95698.0 107180.5 112746.0 76331.0 93490.5 95736.5 96149.6 98321.5 69938.7 71225.9 76859.8 78370.6 79282.0 9511.9 9530.9 9597.3 9628.4 9665.1 53716.2 54046.5 54908.0 55118.0 56364.0 56494.0 64284.7 64414.0 64936.0 74268.0 12907.4 13430.3 13555.7 13645.6 13904.7 14448.4 14800.6 14977.9 15020.8 15474.0 12290.3 13657.5 13700.9 13961.8 14008.0 13554.7 13652.2 14392.9 14434.7 14441.6 13288.7 13946.8 14106.4 14108.5 14162.0 11237.9 11354.5 11375.4 11532.9 11602.2 9633.8 10839.5 11179.7 11190.5 11395.7 18:30:32 INFO - PROCESS | 1641 | -------- Summary: end -------- 18:30:32 INFO - PROCESS | 1641 | 18:30:32 INFO - TEST-INFO | 1641: exit 0 18:30:33 INFO - TEST-OK | dromaeo_css | took 1119145ms 18:30:33 INFO - TEST-START | kraken 18:30:33 INFO - operating with platform_type : mac_ 18:30:33 INFO - Initialising browser for kraken test... 18:30:33 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpJZs1dB/profile http://localhost:49240/getInfo.html) 18:30:35 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:30:35 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:30:35 INFO - PROCESS | 1647 | colorDepth:24 18:30:35 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:30:35 INFO - PROCESS | 1647 | __metrics 18:30:35 INFO - PROCESS | 1647 | 1486175435475 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:30:35 INFO - PROCESS | 1647 | 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:30:35 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:36 INFO - TEST-INFO | 1647: exit 0 18:30:36 INFO - Browser initialized. 18:30:36 INFO - Running cycle 1/1 for kraken test... 18:30:36 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpJZs1dB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:48 INFO - PROCESS | 1648 | RSS: Main: 236142592 18:30:48 INFO - PROCESS | 1648 | 18:30:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 18:30:52 INFO - PROCESS | 1648 | RSS: Main: 288538624 18:30:52 INFO - PROCESS | 1648 | 18:30:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 18:30:55 INFO - PROCESS | 1648 | RSS: Main: 290054144 18:30:55 INFO - PROCESS | 1648 | 18:30:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 18:30:59 INFO - PROCESS | 1648 | RSS: Main: 383168512 18:30:59 INFO - PROCESS | 1648 | 18:31:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 18:31:01 INFO - PROCESS | 1648 | RSS: Main: 284635136 18:31:01 INFO - PROCESS | 1648 | 18:31:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:04 INFO - PROCESS | 1648 | RSS: Main: 286298112 18:31:04 INFO - PROCESS | 1648 | 18:31:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 18:31:08 INFO - PROCESS | 1648 | RSS: Main: 330043392 18:31:08 INFO - PROCESS | 1648 | 18:31:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 18:31:11 INFO - PROCESS | 1648 | RSS: Main: 332500992 18:31:11 INFO - PROCESS | 1648 | 18:31:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 18:31:15 INFO - PROCESS | 1648 | RSS: Main: 329371648 18:31:15 INFO - PROCESS | 1648 | 18:31:16 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:31:17 INFO - PROCESS | 1648 | RSS: Main: 305160192 18:31:17 INFO - PROCESS | 1648 | 18:31:19 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:31:20 INFO - PROCESS | 1648 | RSS: Main: 308117504 18:31:20 INFO - PROCESS | 1648 | 18:31:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:31:22 INFO - PROCESS | 1648 | RSS: Main: 312860672 18:31:22 INFO - PROCESS | 1648 | 18:31:25 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:31:26 INFO - PROCESS | 1648 | RSS: Main: 330600448 18:31:26 INFO - PROCESS | 1648 | 18:31:29 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:31:30 INFO - PROCESS | 1648 | RSS: Main: 324329472 18:31:30 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 18:31:32 INFO - PROCESS | 1648 | RSS: Main: 369709056 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | __start_tp_report 18:31:32 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:31:32 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:31:32 INFO - PROCESS | 1648 | |i|pagename|runs| 18:31:32 INFO - PROCESS | 1648 | |0;ai-astar;114;113;114;115;114;114;113;114;113;114 18:31:32 INFO - PROCESS | 1648 | |1;audio-beat-detection;137;109;109;146;110;110;110;110;129;110 18:31:32 INFO - PROCESS | 1648 | |2;audio-dft;183;181;186;182;185;179;182;181;183;183 18:31:32 INFO - PROCESS | 1648 | |3;audio-fft;80;57;49;88;79;83;82;82;79;60 18:31:32 INFO - PROCESS | 1648 | |4;audio-oscillator;88;71;88;70;69;70;85;68;69;83 18:31:32 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;87;89;88;89;85;90;86;89;85 18:31:32 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;94;94;94;94;94;103;128;94;94 18:31:32 INFO - PROCESS | 1648 | |7;imaging-desaturate;88;84;133;131;100;85;85;84;92;85 18:31:32 INFO - PROCESS | 1648 | |8;json-parse-financial;58;59;57;58;57;60;95;92;76;58 18:31:32 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;70;68;64;74;72;69;97;69;69;69 18:31:32 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;87;85;82;89;90;89;86;86;90;88 18:31:32 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;139;141;143;140;149;139;138;129;196;154 18:31:32 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;178;173;175;171;172;221;232;184;171;172 18:31:32 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;66;67;66;65;66;66;65;66;66;64 18:31:32 INFO - PROCESS | 1648 | __end_tp_report 18:31:32 INFO - PROCESS | 1648 | __start_cc_report 18:31:32 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2169 18:31:32 INFO - PROCESS | 1648 | __end_cc_report 18:31:32 INFO - PROCESS | 1648 | __startTimestamp1486175492361__endTimestamp 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:31:32 INFO - PROCESS | 1648 | Number of tests: 14 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 18:31:32 INFO - PROCESS | 1648 | Values: 114.0 113.0 114.0 115.0 114.0 114.0 113.0 114.0 113.0 114.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:110.00 stddev:13.94 (12.7%) stddev-sans-first:12.98 18:31:32 INFO - PROCESS | 1648 | Values: 137.0 109.0 109.0 146.0 110.0 110.0 110.0 110.0 129.0 110.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:2.01 (1.1%) stddev-sans-first:2.13 18:31:32 INFO - PROCESS | 1648 | Values: 183.0 181.0 186.0 182.0 185.0 179.0 182.0 181.0 183.0 183.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:73.90 Median:81.00 stddev:13.34 (16.5%) stddev-sans-first:13.96 18:31:32 INFO - PROCESS | 1648 | Values: 80.0 57.0 49.0 88.0 79.0 83.0 82.0 82.0 79.0 60.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.00 stddev:8.67 (11.3%) stddev-sans-first:8.06 18:31:32 INFO - PROCESS | 1648 | Values: 88.0 71.0 88.0 70.0 69.0 70.0 85.0 68.0 69.0 83.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.93 (2.2%) stddev-sans-first:1.88 18:31:32 INFO - PROCESS | 1648 | Values: 90.0 87.0 89.0 88.0 89.0 85.0 90.0 86.0 89.0 85.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:94.00 stddev:10.81 (11.5%) stddev-sans-first:11.36 18:31:32 INFO - PROCESS | 1648 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 103.0 128.0 94.0 94.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:85.00 stddev:19.25 (22.7%) stddev-sans-first:20.16 18:31:32 INFO - PROCESS | 1648 | Values: 88.0 84.0 133.0 131.0 100.0 85.0 85.0 84.0 92.0 85.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:59.50 stddev:15.08 (25.3%) stddev-sans-first:15.64 18:31:32 INFO - PROCESS | 1648 | Values: 58.0 59.0 57.0 58.0 57.0 60.0 95.0 92.0 76.0 58.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:69.50 stddev:9.12 (13.1%) stddev-sans-first:9.64 18:31:32 INFO - PROCESS | 1648 | Values: 70.0 68.0 64.0 74.0 72.0 69.0 97.0 69.0 69.0 69.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.50 stddev:2.53 (2.9%) stddev-sans-first:2.68 18:31:32 INFO - PROCESS | 1648 | Values: 87.0 85.0 82.0 89.0 90.0 89.0 86.0 86.0 90.0 88.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:142.00 stddev:18.52 (13.0%) stddev-sans-first:19.43 18:31:32 INFO - PROCESS | 1648 | Values: 139.0 141.0 143.0 140.0 149.0 139.0 138.0 129.0 196.0 154.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:176.50 stddev:22.43 (12.7%) stddev-sans-first:23.65 18:31:32 INFO - PROCESS | 1648 | Values: 178.0 173.0 175.0 171.0 172.0 221.0 232.0 184.0 171.0 172.0 18:31:32 INFO - PROCESS | 1648 | 18:31:32 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 18:31:32 INFO - PROCESS | 1648 | Values: 66.0 67.0 66.0 65.0 66.0 66.0 65.0 66.0 66.0 64.0 18:31:32 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:31:32 INFO - PROCESS | 1648 | 18:31:33 INFO - TEST-INFO | 1648: exit 0 18:31:33 INFO - TEST-OK | kraken | took 60281ms 18:31:33 INFO - SUITE-END | took 1179s 18:31:33 INFO - Completed test suite (00:19:39) 18:31:33 INFO - javascript benchmark 18:31:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7257.837982926336, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2769.8615069246534, 3305.8181800001007, 3341.315030418764, 3344.4147274227003, 3388.322335532894, 5566.4990150886415, 5654.095344744842, 5698.601097923141, 5761.539076873851, 5774.191613174154, 5664.490195882369, 5685.488306052454, 5714.485696287334, 5787.710614469281, 5806.158107074471, 5125.154349532323, 5174.586033117361, 5187.351581052369, 5221.347331583552, 5224.503672151159, 5332.92000619991, 5357.116075847481, 5358.490943360394, 5363.329583802025, 5419.83740487786, 5101.464346243647, 5115.437301896788, 5130.025295193901, 5191.247269145991, 5286.867828304304, 5185.766640501187, 5251.212318152269, 5275.208718692188, 5322.441143679896, 5347.6256662033675, 4366.6506679465565, 5169.586433085344, 5206.531412172884, 5255.86860328491, 5280.9438112377675, 5579.721013949286, 5604.915926261109, 5745.253117094792, 5766.481016708474, 5789.594728369016, 5183.3002544656365, 6163.722632481549, 6197.876042479125, 6227.532935029845, 6264.248290205204, 6178.907316390258, 6236.532260080512, 6267.05994100884, 6283.183186185766, 6311.495080393557, 5848.122781582797, 5901.203337549418, 5914.0, 5915.1718759373725, 5969.313528944183, 6134.846828498841, 6226.844328891723, 6251.843703907348, 6253.749850005949, 6282.089097080898, 6163.075538669235, 6229.906551401733, 6241.812745617548, 6245.531585131133, 6248.875022499616, 6275.874482510324, 6292.937070629235, 6306.369363063749, 6309.716062777186, 6320.905186422207, 6112.777056696867, 6120.081987701791, 6129.35641757609, 6245.656488893154, 6258.718357673915, 6168.876622467616, 6209.379062093845, 6223.502119830403, 6270.216222972129, 6342.8414289642205, 5022.4977502249485, 5025.170846810309, 5028.069807085628, 5033.672811267256, 5059.8988020239385, 4497.752623605733, 4499.775011249424, 4502.077074199781, 4505.986153115588, 4509.616682581952, 5586.497215250647, 5624.184493248456, 5624.959382514248, 5702.201691763156, 5714.685692286964, 6249.781257656051, 6252.3122456530655, 6284.43440090385, 6297.559170857996, 6319.778807741707, 5766.538676905838, 5783.595148339576, 5797.884042319214, 5798.217240672539, 5833.854153646193, 5309.256704061435, 5330.120530112488, 5365.704886231295, 5480.123180291134, 5480.671159730429, 6202.56582039262, 6237.75048998035, 6306.779262725782, 6316.905246421399, 6465.127207826883, 6146.524834039709, 6174.536909731698, 6178.845528861814, 6310.842228944313, 6337.493000560036, 6179.6910154493, 6237.563370564017, 6301.0863424804065, 6350.333215012357, 6365.458935990493, 6291.1192433058495, 6301.873962520815, 6303.086052522451, 6304.527160463075, 6311.053341998737, 6130.172426722335, 6132.84504737327, 6136.6722581676495, 6230.158928544589, 6239.563230573816, 5107.361579802525, 6197.194364732556, 6199.411055949678, 6210.285817131088, 6211.782587609412, 6178.104174894705, 6191.5665903388135, 6199.132121503083, 6200.81397558074, 6205.100260462117, 5732.713364331683, 5819.679917604572, 6075.7569697213385, 6076.361981992004, 6077.696115194134, 6037.547183961133, 6088.817335479943, 6141.232345956755, 6148.077788331786, 6216.53375996793, 6053.02751013817, 6053.606515576474, 6074.817755467343, 6078.179445774941, 6123.724432400463], "value": 5714.6985467189115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.59896691944164, 494.69081823860085, 517.9404368497621, 530.3927003580899, 532.5340327213688, 39119.60880391195, 41362.965925851866, 41455.58544414555, 41503.0, 41641.0, 40928.79535602325, 41491.170176596526, 41570.0, 41574.0, 41607.37588936168, 6677.565958212725, 8155.551444670541, 9162.083791620835, 9450.338476306626, 9480.24158067353, 9574.712758617252, 11068.944655276713, 11180.496877640526, 11247.66257012291, 11381.430928453585, 11189.44052797361, 11260.268082574647, 11263.380514071723, 11271.718207044807, 11283.492242849092, 7995.760127196135, 8232.50604963704, 11129.888701112966, 11178.217524773268, 11307.64308283003, 11217.60738374153, 11236.58413441106, 11244.943775281154, 11251.0436612888, 11253.324800511913, 39836.60163398358, 40142.19715605672, 40255.39616905749, 40276.79861600703, 40286.79856600698, 3937.625925537098, 3949.4273330367223, 3952.150287688161, 3958.1489979654225, 4081.877483691979, 28768.71231287702, 29502.0, 29942.95199668023, 29952.550711739343, 29980.70019299801, 3109.4713898637287, 3143.4184675834813, 3190.760692948015, 3194.393065317582, 3283.2448536836423, 437526.0, 439594.0, 440576.0, 441473.0, 444940.0, 48630.0, 49727.75136124296, 55309.170362444595, 55456.72271638616, 55732.442675572725, 53432.198517022276, 62403.0, 63182.68408657928, 63490.682546587894, 65899.67050164814, 14877.702445951021, 14985.850141498664, 15013.399464021317, 15047.548573542812, 15051.021683590532, 11944.98467630261, 12054.216475929037, 12060.914517693274, 12061.457234424472, 12064.336461494702, 15179.924100379427, 15564.688706225812, 15617.3753049879, 15645.765313520078, 15801.36568589231, 6621.238557565942, 6771.0, 6793.864122717617, 6803.2856550062525, 6849.965750171217, 631.4821846086257, 748.8015675845905, 804.9415019249785, 863.4042510667745, 872.7381785464336, 26878.5968210477, 28928.566071508943, 29358.559621605695, 29592.704072959426, 30202.9428969985, 1000.2748007694363, 1016.3698506925606, 1016.4307987527009, 1048.8793788714243, 1056.6460235820932, 13671.521496747766, 14188.716225675635, 14293.927955403386, 14577.97954143221, 14580.343884525222, 587235.0, 591457.0, 591736.0, 593253.0, 593297.0, 49762.50237497579, 50156.24765628519, 50165.49834501608, 50212.497875021516, 50261.246081308076, 1364.8430430500619, 1391.3877893726728, 1415.4691990503354, 1417.8156839610785, 1418.9077709949233, 23390.18134365289, 26424.0751573694, 26953.730462695905, 27568.44863102767, 27619.03333383322, 44545.0, 44786.3282050776, 44806.55193448154, 44952.775236124915, 44968.32547511724, 415453.0, 500618.0, 501072.0, 501321.0, 501636.0, 21484.785152148277, 21981.34055978323, 23688.170914017926, 23840.880795595913, 24463.0, 40715.77852664425, 40886.15421436915, 41283.96790080272, 41516.79241603773, 41766.79116604398, 27663.58504622392, 28225.71774282313, 33455.33089338248, 33840.32319353648, 34902.65097348994, 26895.596566051132, 30968.225794355323, 34497.44761485789, 34501.0, 34668.13329666779, 32703.528341225177, 33199.50200747039, 33489.497657534666, 34615.0, 35636.109097272776, 29766.0, 31427.057188284387, 31532.52701209438, 31551.684483154873, 31634.683653164095, 23494.76505234926, 23890.0, 23984.86796705484, 24027.879860601286, 24213.152539661027], "value": 18852.29024558988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.27186500551335, 401.5101576077185, 402.6557293514044, 416.46068341497755, 435.1167130724628, 12776.233425994447, 19802.90098549509, 19898.60202795945, 20693.379198624047, 20716.48208794779, 20005.999700014978, 20063.0, 20265.696014559795, 20321.085551150198, 20619.38141855743, 5326.201069839516, 5751.597388182829, 5813.06990881459, 5823.650580965155, 5844.094165404369, 4767.90464190716, 5660.405657405975, 5666.971665141689, 5760.798372056979, 5766.942330576703, 5714.628549144313, 5771.913421298663, 5773.422657734214, 5773.913391299134, 5787.102999035156, 5594.412586678381, 5766.711664416784, 5767.394423585526, 5775.191473193756, 5865.032269675498, 5801.7389217485315, 5805.7387417566315, 5813.128030795372, 5818.970905145489, 5832.737526811304, 13445.26051067191, 18214.27142914282, 24692.753072469226, 25153.0, 25470.617940730903, 2573.266619013591, 2595.8572278524675, 2597.59737240728, 2598.9350266243305, 2667.8932842686268, 9712.75718107046, 9976.501174941297, 10005.64980225696, 10522.21083418739, 10535.473226338729, 1836.3249587748496, 1858.7397764313007, 1861.255497800877, 1878.3613571385658, 1892.0, 120238.0, 121234.39382803117, 134899.0, 140395.59604404029, 141017.0, 9501.762455938657, 11489.597864074718, 11742.765144697058, 11781.234219775859, 11812.70468238301, 13940.721185576233, 14498.492552760814, 15676.843231567538, 15701.921490392704, 15733.370665173494, 7740.458167928191, 10337.689869303933, 10435.32170408921, 10484.26610137283, 10809.945950270197, 8345.582720863933, 8444.746657600283, 8456.154384561618, 8650.740477785677, 8840.33697472692, 10438.154633405316, 10535.209859260585, 10572.471376431147, 10680.092192163753, 10692.679219623424, 3995.880123596297, 4084.2648323301496, 4133.297339452301, 4162.62536371728, 4180.435641188462, 986.8519722041751, 1001.4992503748126, 1028.4343611013912, 1046.7644779924456, 1048.7640280936728, 20617.278395256093, 21061.473463163544, 21168.470788230417, 21267.893660531598, 21449.67825482619, 1017.2319898476603, 1045.268312181476, 1055.7044027672264, 1075.4031512510633, 1086.3590481615884, 10327.74180645475, 10657.786844263072, 10694.946525267324, 10710.94644526788, 10712.303700259457, 695647.0, 701836.0, 709547.1156682252, 725045.0, 727962.0, 125995.0, 145458.0, 149053.25473372563, 153421.0, 158573.2071339636, 894.3605322194662, 950.7385468995943, 959.194276807485, 969.767255858603, 993.4138858073627, 17894.373696920546, 18434.89390636566, 18472.445826625222, 18479.53801154982, 18532.444026679223, 47027.29459058048, 51475.485245148564, 55553.44446555483, 57350.42649573451, 57412.0, 84402.0, 89780.5510972441, 89902.55048724715, 90409.54795225982, 92601.0, 8184.631691574013, 8334.87497687523, 8353.498790072616, 8552.786180345542, 8660.437071590326, 10720.981506756845, 11465.656030319104, 11650.242734222249, 12154.0, 12765.680857978625, 9132.680356187502, 9651.0, 10727.624533141303, 11066.28069175501, 11091.611793587186, 9077.592973089182, 9381.437113773196, 10387.480625968823, 10829.592153020058, 10992.340459572451, 10441.425721585385, 10870.510827012646, 11131.276467029616, 11330.263532870336, 11340.659780206606, 7000.579965202104, 8137.575924213121, 8233.711820086268, 8401.067754416641, 8428.283595894418, 9166.266698664223, 10977.582391410031, 11049.447527623748, 11058.949399807005, 11186.04918581897], "value": 10148.692897317167, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.388225492919, 1439.4098419647946, 1461.1379286221133, 1461.8903582231333, 1464.7070585882823, 8467.153284671538, 8836.646534138627, 8838.42550234236, 8844.734657960264, 8868.689595864145, 8347.874781878276, 8663.436876603033, 8670.653173873037, 8761.123887611237, 8763.956180219106, 2296.6210575255104, 2328.3247858121126, 2330.0796185506715, 2331.428799944016, 2334.8599084054927, 2315.918942837001, 2352.4706940938236, 2361.5631108244943, 2362.8818559072065, 2363.0429675981254, 2308.3536609749212, 2309.064828744353, 2329.94175145621, 2335.182686059874, 2337.0, 1893.4698284480367, 2291.3584196424863, 2298.321995011467, 2317.5480781247666, 2324.6629238760456, 2265.4676151104477, 2340.8829558522007, 2351.294611616508, 2353.2822489140804, 2354.799842013431, 8600.8709869352, 8683.869741953877, 8686.391952563323, 8693.347998900108, 8704.608292626846, 5100.9489905100845, 5256.369235691704, 5260.92108618371, 5261.76322065508, 5266.183741520071, 3339.131825725329, 3397.59228892532, 3528.347255757693, 3550.5029295898594, 3588.0312315674623, 1078.282941843673, 1175.8295047218105, 1215.744693614342, 1249.2504497301636, 1263.974720505594, 9597.760055998586, 10623.468826558641, 10701.785964280749, 11291.20961532693, 11292.266002709797, 4152.896177595584, 4270.893227669271, 4270.957290427118, 4272.6154646082, 4460.130274596422, 8992.640294388282, 9044.773880653036, 9091.363604547618, 9114.088591140833, 9144.54277286133, 5212.765425555859, 5214.191800270964, 5220.660657057279, 5225.947740522623, 5227.477252274818, 4195.160967806451, 4630.3517507548295, 4695.85912422621, 4854.2233242681705, 4873.878153046131, 3157.715805577463, 3597.8021208833243, 3667.211549516867, 3727.1800203955086, 3748.0629842539365, 1150.6663067710438, 1155.6764106050318, 1157.4444266751839, 1161.355447726503, 1165.8251262310553, 846.4709556527171, 880.9075047120089, 881.5063564403954, 886.0394038647237, 913.1507697840939, 5425.647332923348, 5516.696581688046, 5534.5056834654415, 5710.543156547466, 5763.337216220105, 909.9636014559476, 959.9568019439142, 994.8756405449319, 1003.5784970312341, 1033.6485594897772, 3359.0258824940984, 3457.0665920201404, 3604.873829415958, 3653.0, 3797.4493698413576, 10018.298719089593, 10169.13562347194, 10406.635767748092, 10408.219383546264, 10420.32267902584, 10334.276600637882, 10339.689809305582, 10359.223058270509, 10370.377777333384, 10372.11836993864, 7506.174320824705, 7893.013373328334, 7902.604869756489, 8009.159038301011, 8009.559474228857, 5534.225208470737, 5565.499105080562, 5684.118961560965, 5740.68426236561, 5810.244668193108, 8913.153250441197, 9322.673706420243, 9556.713298601053, 9570.95214523923, 9659.661911833053, 9077.45535267886, 9353.719388418358, 9473.100055494717, 9775.187676806194, 9812.116909478182, 6824.556403833735, 7164.785056448315, 7166.227279723219, 7237.095363079615, 7331.120265568166, 8035.0, 8100.797480063045, 8109.594520273845, 8116.350691944748, 8125.431219814793, 7123.252058533986, 7285.526440781332, 7328.230535793665, 7354.558726476428, 7388.704451822082, 7051.259617740064, 7116.074910261633, 7201.4238860889955, 7225.385842203262, 7327.1207455103595, 6396.360363963752, 6522.934770652422, 6527.738890444329, 6528.804135875931, 6562.8359291018105, 4878.292647566012, 5030.346055012825, 5035.748212589429, 5115.56517696, 5128.051310507568, 4402.7138236015835, 4524.0951809637545, 4543.54564543543, 4618.145643056046, 4682.086993036325], "value": 4432.394941992174, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.7247625756238, 359.5792922280931, 360.4485137739259, 371.1019333213622, 377.5997442710725, 4565.0, 5064.392272927244, 5082.08522465956, 5088.287639730441, 5134.460881607433, 4154.605312495316, 4604.539546045402, 5006.824761133361, 5031.572316353106, 5303.2575439438415, 3461.774984626004, 3849.7497662651976, 3975.3639417693175, 4115.691323150776, 4159.708820382574, 3627.727920405967, 3676.522052133219, 4084.6323830855217, 4090.9181816363653, 4140.482439695038, 4116.259073366793, 4122.484689413824, 4141.4409054777525, 4144.8549300774475, 4145.917081658365, 3262.124278539849, 3863.1694185750057, 3954.149857780591, 4007.398890166484, 4103.589641035873, 3045.462041668965, 3217.1957010747315, 3221.6617255188103, 3366.9158271043175, 3677.4667673187237, 4559.586528176276, 4746.267612321512, 4768.709590382625, 5187.273781670569, 5214.165733482625, 4493.438320209973, 4632.698874573142, 4658.673892827504, 4709.811607535695, 4772.403449568804, 4847.830325938575, 5310.92033619496, 5465.682111132201, 5489.725513724298, 5603.439656034405, 3340.4822252550894, 4021.833668236208, 4025.0, 4074.979625101885, 4089.85685501009, 5114.744262786883, 5279.366476022863, 5470.7264636768, 5573.136163894603, 5713.285839270091, 3637.7453578249797, 3736.8415791104303, 3759.9248015039548, 3870.41943708446, 4102.733322334038, 4144.274751918402, 4166.687498437629, 4170.833166673299, 4181.849991252369, 4200.600942910419, 4571.81712731487, 4641.651876109306, 4669.649776266793, 4697.248440249544, 4740.265258884879, 5263.973680131651, 5499.340079190442, 5520.503154716095, 5521.723913804293, 5543.501084902378, 1126.6957921361186, 1233.2785320587377, 1264.968375790594, 1332.4942814619612, 1344.6907211341447, 931.8119397767765, 962.9903700962901, 963.5182408795603, 976.3263348289657, 976.4092723902079, 4290.849820256276, 4448.955510444919, 4587.357769912215, 4676.954434414207, 4738.573528382462, 4559.52125026874, 4745.2819352679935, 4754.405699287589, 4864.70811751289, 5396.408059622421, 4159.563245859202, 4217.755762050203, 4413.521470307483, 4773.498782627844, 4791.47293797682, 2462.4336402627496, 2713.470873179711, 2759.9300524606547, 2860.4982384248437, 2862.912093404493, 4748.1482221933475, 5335.359756829205, 5714.0571805652435, 5724.542036637059, 5735.365420855036, 4623.722576645412, 5039.44566097729, 5386.434424385461, 5537.667739935536, 5607.915881261784, 5048.671836330626, 5684.49360919365, 5705.4579814917515, 5709.972959599366, 5736.6844823534275, 5060.684222102206, 5371.919421208763, 5379.946200538101, 5380.031594312904, 5413.969761339497, 4722.952770472251, 4885.389326334208, 5138.486151384832, 5330.680159190461, 5337.185356978646, 4810.807567697253, 4938.48145944685, 5176.0, 5243.868903277296, 5664.631798933056, 3309.718673912744, 3324.3351329734633, 3434.3131373724855, 3457.6715212054814, 3691.963080369162, 4315.78421078951, 5385.698732482926, 5405.864853378697, 5426.891462170655, 5628.187899346, 4213.535081977077, 4922.92615610759, 5469.3983661797965, 5487.640314664733, 5487.914592455001, 5542.695151766691, 5565.666060036411, 5585.882823435248, 5587.385387607279, 5613.46672066153, 4293.227219100592, 4688.906221875475, 5198.402183748917, 5227.497576623748, 5437.02133615953, 4955.225073958594, 5569.248151499658, 5569.291476236727, 5572.832815015556, 5623.228682964853, 4449.822007119679, 5092.796288148433, 5404.270423492778, 5417.187421886749, 5479.561635069264], "value": 3977.466097900257, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.1121753416996, 744.497464211657, 758.0145810446418, 822.3585603229479, 837.2883049408003, 14641.267936603168, 14797.92601036996, 15233.847661523381, 15261.160636165021, 15280.083195008294, 13094.083414161012, 13198.406071726771, 13236.338183090831, 13310.334483275821, 13414.59756207313, 874.0603850860318, 881.088073843572, 890.7194233816359, 895.2971917045121, 907.8138981508807, 888.6489836514573, 898.4833720610675, 902.1429641840245, 904.3624244907357, 905.0677801864091, 950.6910254167424, 951.7715748220412, 952.0764858087643, 953.31361419778, 955.9044095590455, 923.7275003873873, 947.4457442396207, 948.2603569216022, 949.9002604726473, 951.3293128344535, 703.6657587645878, 703.714995426853, 705.6930235347589, 706.0186340985982, 706.4489698035541, 14514.637134071627, 14563.708725825423, 14877.628059298497, 15258.08451492914, 15454.304556294994, 13861.09902856311, 14020.166332545963, 14167.387316234397, 14233.501827436094, 14329.140251584939, 11698.590549330733, 11718.355490448022, 11794.632762758549, 11849.111316651286, 12024.398780060961, 3654.634536546324, 3747.9063023424633, 3758.891127117507, 3787.7159213058853, 3800.239952009609, 102322.48838755833, 102635.4868225654, 110781.9999999992, 111612.44193778979, 111703.44148279288, 9805.901940980642, 10236.994104920554, 10307.793844123076, 10422.478876056319, 10676.306040107369, 21100.683489747666, 21334.893325533274, 21478.140874365163, 21488.677669834964, 21510.0, 6707.8261304271555, 7058.647067646597, 7094.11323584552, 7370.484066115428, 7370.705171793068, 4132.504099508078, 4206.810693518799, 4251.44731184944, 4382.802773875183, 4392.934105988413, 3475.1485885957595, 3511.438169892805, 3596.1009747563107, 3599.7120230381506, 3601.747877648592, 654.5745265577318, 658.7858945842524, 665.6871270502855, 669.8091044052421, 703.5989485993038, 667.4560233409755, 687.3504538211442, 708.8086216721559, 716.6774951271949, 740.3484933258698, 9985.101340879355, 10251.179905607534, 10306.742331441774, 10584.259101862795, 10597.364158150536, 592.9762809487659, 681.1825809029185, 692.5982929900746, 705.1573369823114, 722.3282347416953, 4593.0, 4898.21628539432, 4905.484924082992, 4984.152694042022, 5018.447970723218, 86499.56750216208, 87443.0, 95698.0, 107180.46409767901, 112746.0, 76331.0, 93490.53254733683, 95736.52131739436, 96149.5577566337, 98321.50839245757, 69938.65030674916, 71225.93161102588, 76859.84710229351, 78370.6081469589, 79282.0, 9511.857322140035, 9530.904690953003, 9597.28020398459, 9628.422294662343, 9665.081817227352, 53716.19425708539, 54046.45953540572, 54908.0, 55118.0, 56364.0, 56494.0, 64284.67857660682, 64414.0, 64936.0, 74268.0, 12907.353947799187, 13430.328483575977, 13555.661108472366, 13645.590632281048, 13904.652383690489, 14448.422063117358, 14800.555983320517, 14977.925110374379, 15020.849791502382, 15473.994190377589, 12290.262584244974, 13657.521986730417, 13700.862991369959, 13961.790573141208, 14008.0, 13554.728905422033, 13652.24912629815, 14392.928035359757, 14434.711305774035, 14441.638959026108, 13288.734225315633, 13946.79079813824, 14106.365213565208, 14108.50620228328, 14162.0, 11237.943810280896, 11354.545818167511, 11375.374354410256, 11532.94233528827, 11602.245854019462, 9633.807323853624, 10839.480643429653, 11179.664610061385, 11190.496427660612, 11395.715107122389], "value": 7582.001894550918, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1470.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 109.0, 146.0, 110.0, 110.0, 110.0, 110.0, 129.0, 110.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 186.0, 182.0, 185.0, 179.0, 182.0, 181.0, 183.0, 183.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 49.0, 88.0, 79.0, 83.0, 82.0, 82.0, 79.0, 60.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 88.0, 70.0, 69.0, 70.0, 85.0, 68.0, 69.0, 83.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 88.0, 89.0, 85.0, 90.0, 86.0, 89.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 103.0, 128.0, 94.0, 94.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 133.0, 131.0, 100.0, 85.0, 85.0, 84.0, 92.0, 85.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 57.0, 58.0, 57.0, 60.0, 95.0, 92.0, 76.0, 58.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 64.0, 74.0, 72.0, 69.0, 97.0, 69.0, 69.0, 69.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 82.0, 89.0, 90.0, 89.0, 86.0, 86.0, 90.0, 88.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 143.0, 140.0, 149.0, 139.0, 138.0, 129.0, 196.0, 154.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 173.0, 175.0, 171.0, 172.0, 221.0, 232.0, 184.0, 171.0, 172.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 64.0], "value": 65.7, "unit": "score"}]}]} 18:31:33 INFO - Return code: 0 18:31:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:31:33 ERROR - # TBPL SUCCESS # 18:31:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:33 INFO - 2017-02-03 18:31:33.673 screenresolution[1652:10224] starting screenresolution argv=screenresolution get 18:31:33 INFO - 2017-02-03 18:31:33.677 screenresolution[1652:10224] Display 0: 1600x1200x32@60 18:31:33 INFO - 2017-02-03 18:31:33.683 screenresolution[1653:10227] starting screenresolution argv=screenresolution list 18:31:33 INFO - Available Modes on Display 0 18:31:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:33 INFO - Intel Iris: 18:31:33 INFO - Chipset Model: Intel Iris 18:31:33 INFO - Type: GPU 18:31:33 INFO - Bus: Built-In 18:31:33 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:33 INFO - Vendor: Intel (0x8086) 18:31:33 INFO - Device ID: 0x0a2e 18:31:33 INFO - Revision ID: 0x0009 18:31:33 INFO - Displays: 18:31:33 INFO - Display: 18:31:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:33 INFO - Display Serial Number: 1600x1200 60 18:31:33 INFO - Main Display: Yes 18:31:33 INFO - Mirror: Off 18:31:33 INFO - Online: Yes 18:31:33 INFO - Rotation: Supported 18:31:33 INFO - Return code: 0 18:31:33 INFO - Running post-action listener: _resource_record_post_action 18:31:33 INFO - [mozharness: 2017-02-04 02:31:33.923079Z] Finished run-tests step (success) 18:31:33 INFO - Running post-run listener: _resource_record_post_run 18:31:33 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33610752; Write bytes: 556162560; Read time: 310; Write time: 1621 18:31:33 INFO - TinderboxPrint: CPU usage
53.9% 18:31:33 INFO - TinderboxPrint: I/O read bytes / time
33,610,752 / 310 18:31:33 INFO - TinderboxPrint: I/O write bytes / time
556,162,560 / 1,621 18:31:33 INFO - TinderboxPrint: CPU idle
2,217.6 (46.1%) 18:31:33 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 18:31:33 INFO - TinderboxPrint: CPU user
1,627.6 (33.8%) 18:31:33 INFO - TinderboxPrint: Swap in / out
280,899,584 / 0 18:31:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250376704; Write bytes: 246278144; Read time: 18120; Write time: 488 18:31:33 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 29060096; Write bytes: 298509824; Read time: 278; Write time: 1097 18:31:34 INFO - Running post-run listener: _upload_blobber_files 18:31:34 INFO - Blob upload gear active. 18:31:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:31:34 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:31:34 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:31:34 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:31:34 INFO - (blobuploader) - INFO - Open directory for files ... 18:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:35 INFO - (blobuploader) - INFO - Done attempting. 18:31:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:31:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:37 INFO - (blobuploader) - INFO - Done attempting. 18:31:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:31:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:31:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:38 INFO - (blobuploader) - INFO - Done attempting. 18:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:41 INFO - (blobuploader) - INFO - Done attempting. 18:31:41 INFO - (blobuploader) - INFO - Iteration through files over. 18:31:41 INFO - Return code: 0 18:31:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:31:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:31:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f731b43fd6e2d5e451bb58f84ed09bbf78407a48b49eff1845939312c93d5d831a8b9416328bcebba5b5ed93282a4996238b7fcf83a70116bb8e9740b7d9f8d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8df74e0eb14be0ec31cb0b59a2062dc08899cba34fcc20a4fbe767f1e6ff305f00e131351bba169db0e86846df63ed930e7c44239707f8167f56c28044467f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48cd4ba7e701bd6f27aca8de75cfedca75dd7ba0653a10ef7df505238b7e588fcb7d06ceb9a347d6c2b3a2baf6e68e8a0753633ed133434ad7e2d3dc8c29da2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b30a0feeda6e2190e49ae34d8c8d93ed04cb7ad503ece093039e636f5ebc73af94c9b5cd5cc7933f1271dcb769dde5a720bd8128d2b9f07d785bb6b235e0c55"} 18:31:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:31:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:31:41 INFO - Contents: 18:31:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f731b43fd6e2d5e451bb58f84ed09bbf78407a48b49eff1845939312c93d5d831a8b9416328bcebba5b5ed93282a4996238b7fcf83a70116bb8e9740b7d9f8d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8df74e0eb14be0ec31cb0b59a2062dc08899cba34fcc20a4fbe767f1e6ff305f00e131351bba169db0e86846df63ed930e7c44239707f8167f56c28044467f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48cd4ba7e701bd6f27aca8de75cfedca75dd7ba0653a10ef7df505238b7e588fcb7d06ceb9a347d6c2b3a2baf6e68e8a0753633ed133434ad7e2d3dc8c29da2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b30a0feeda6e2190e49ae34d8c8d93ed04cb7ad503ece093039e636f5ebc73af94c9b5cd5cc7933f1271dcb769dde5a720bd8128d2b9f07d785bb6b235e0c55"} 18:31:41 INFO - Running post-run listener: copy_logs_to_upload_dir 18:31:41 INFO - Copying logs to upload dir... 18:31:41 INFO - mkdir: /builds/slave/test/build/upload/logs 18:31:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.221929 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-03 18:31:41.282330) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 18:31:41.286257) ========= 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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/f731b43fd6e2d5e451bb58f84ed09bbf78407a48b49eff1845939312c93d5d831a8b9416328bcebba5b5ed93282a4996238b7fcf83a70116bb8e9740b7d9f8d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8df74e0eb14be0ec31cb0b59a2062dc08899cba34fcc20a4fbe767f1e6ff305f00e131351bba169db0e86846df63ed930e7c44239707f8167f56c28044467f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48cd4ba7e701bd6f27aca8de75cfedca75dd7ba0653a10ef7df505238b7e588fcb7d06ceb9a347d6c2b3a2baf6e68e8a0753633ed133434ad7e2d3dc8c29da2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b30a0feeda6e2190e49ae34d8c8d93ed04cb7ad503ece093039e636f5ebc73af94c9b5cd5cc7933f1271dcb769dde5a720bd8128d2b9f07d785bb6b235e0c55"} build_url:https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/WHNEwAUFT46LLvJ_81_tJA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f731b43fd6e2d5e451bb58f84ed09bbf78407a48b49eff1845939312c93d5d831a8b9416328bcebba5b5ed93282a4996238b7fcf83a70116bb8e9740b7d9f8d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8df74e0eb14be0ec31cb0b59a2062dc08899cba34fcc20a4fbe767f1e6ff305f00e131351bba169db0e86846df63ed930e7c44239707f8167f56c28044467f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/48cd4ba7e701bd6f27aca8de75cfedca75dd7ba0653a10ef7df505238b7e588fcb7d06ceb9a347d6c2b3a2baf6e68e8a0753633ed133434ad7e2d3dc8c29da2a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b30a0feeda6e2190e49ae34d8c8d93ed04cb7ad503ece093039e636f5ebc73af94c9b5cd5cc7933f1271dcb769dde5a720bd8128d2b9f07d785bb6b235e0c55"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 18:31:41.324590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 18:31:41.325394) ========= 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.SgFBF2cCNW/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.iaZDIuzCZz/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005174 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 18:31:41.375736) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 18:31:41.376080) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 18:31:41.376407) ========= ========= Total master_lag: 0.18 =========