builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0203 starttime: 1488160245.71 results: success (0) buildid: 20170226162556 builduid: 3c933b819e9a423296550a164ce60117 revision: b06968288cff469814bf830aa90f1c84da490f61 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:45.708592) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:45.709050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:45.709315) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:46.015995) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-26 17:50:46.016603) ========= 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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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-26 17:50:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-02-26 17:50:46 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.529362 ========= master_lag: 0.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-26 17:50:47.463377) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:47.463686) ========= 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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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.026104 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:47.557427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-26 17:50:47.557720) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b06968288cff469814bf830aa90f1c84da490f61 --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 b06968288cff469814bf830aa90f1c84da490f61 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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-26 17:50:48,037 truncating revision to first 12 chars 2017-02-26 17:50:48,038 Setting DEBUG logging. 2017-02-26 17:50:48,038 attempt 1/10 2017-02-26 17:50:48,038 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b06968288cff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-26 17:50:50,027 unpacking tar archive at: mozilla-central-b06968288cff/testing/mozharness/ program finished with exit code 0 elapsedTime=2.264448 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-26 17:50:49.867532) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:49.867888) ========= script_repo_revision: b06968288cff469814bf830aa90f1c84da490f61 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:49.868750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:49.869088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-26 17:50:49.889906) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-02-26 17:50:49.890504) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:50:50 INFO - MultiFileLogger online at 20170226 17:50:50 in /builds/slave/test 17:50:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:50:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:50:50 INFO - {'append_to_log': False, 17:50:50 INFO - 'base_work_dir': '/builds/slave/test', 17:50:50 INFO - 'blob_upload_branch': 'Firefox', 17:50:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:50:50 INFO - 'branch': 'Firefox', 17:50:50 INFO - 'buildbot_json_path': 'buildprops.json', 17:50:50 INFO - 'config_files': ('talos/mac_config.py',), 17:50:50 INFO - 'default_actions': ('clobber', 17:50:50 INFO - 'read-buildbot-config', 17:50:50 INFO - 'download-and-extract', 17:50:50 INFO - 'populate-webroot', 17:50:50 INFO - 'create-virtualenv', 17:50:50 INFO - 'install', 17:50:50 INFO - 'run-tests'), 17:50:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:50:50 INFO - 'download_minidump_stackwalk': True, 17:50:50 INFO - 'download_symbols': 'ondemand', 17:50:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:50:50 INFO - 'tooltool.py': '/tools/tooltool.py', 17:50:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:50:50 INFO - '/tools/misc-python/virtualenv.py')}, 17:50:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:50:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:50:50 INFO - 'gecko_profile': False, 17:50:50 INFO - 'gecko_profile_interval': 0, 17:50:50 INFO - 'installer_path': 'installer.exe', 17:50:50 INFO - 'log_level': 'info', 17:50:50 INFO - 'log_name': 'talos', 17:50:50 INFO - 'log_to_console': True, 17:50:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:50:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:50:50 INFO - 'opt_config_files': (), 17:50:50 INFO - 'pip_index': False, 17:50:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:50:50 INFO - 'cmd': ('bash', 17:50:50 INFO - '-c', 17:50:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:50:50 INFO - 'enabled': True, 17:50:50 INFO - 'halt_on_failure': False, 17:50:50 INFO - 'name': 'check_screen_resolution'},), 17:50:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:50:50 INFO - 'cmd': ('bash', 17:50:50 INFO - '-c', 17:50:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:50:50 INFO - 'enabled': True, 17:50:50 INFO - 'halt_on_failure': False, 17:50:50 INFO - 'name': 'check_screen_resolution'},), 17:50:50 INFO - 'run_cmd_checks_enabled': True, 17:50:50 INFO - 'suite': 'dromaeojs-e10s', 17:50:50 INFO - 'system_bits': '32', 17:50:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:50:50 INFO - 'title': 't-yosemite-r7-0203', 17:50:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:50:50 INFO - 'use_talos_json': True, 17:50:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:50:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:50:50 INFO - 'work_dir': 'build'} 17:50:50 INFO - [mozharness: 2017-02-27 01:50:50.453707Z] Running clobber step. 17:50:50 INFO - Running pre-action listener: _resource_record_pre_action 17:50:50 INFO - Running main action method: clobber 17:50:50 INFO - rmtree: /builds/slave/test/build 17:50:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:50:52 INFO - Running post-action listener: _resource_record_post_action 17:50:52 INFO - [mozharness: 2017-02-27 01:50:52.912743Z] Finished clobber step (success) 17:50:52 INFO - [mozharness: 2017-02-27 01:50:52.912871Z] Running read-buildbot-config step. 17:50:52 INFO - Running pre-action listener: _resource_record_pre_action 17:50:52 INFO - Running main action method: read_buildbot_config 17:50:52 INFO - Using buildbot properties: 17:50:52 INFO - { 17:50:52 INFO - "project": "", 17:50:52 INFO - "product": "firefox", 17:50:52 INFO - "script_repo_revision": "production", 17:50:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:50:52 INFO - "repository": "", 17:50:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:50:52 INFO - "buildid": "20170226162556", 17:50:52 INFO - "pgo_build": "False", 17:50:52 INFO - "basedir": "/builds/slave/test", 17:50:52 INFO - "buildnumber": 100, 17:50:52 INFO - "slavename": "t-yosemite-r7-0203", 17:50:52 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:50:52 INFO - "platform": "yosemite_r7", 17:50:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:50:52 INFO - "branch": "mozilla-central", 17:50:52 INFO - "repo_path": "mozilla-central", 17:50:52 INFO - "revision": "b06968288cff469814bf830aa90f1c84da490f61", 17:50:52 INFO - "stage_platform": "macosx64", 17:50:52 INFO - "builduid": "3c933b819e9a423296550a164ce60117", 17:50:52 INFO - "slavebuilddir": "test" 17:50:52 INFO - } 17:50:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 17:50:52 INFO - Running post-action listener: _resource_record_post_action 17:50:52 INFO - [mozharness: 2017-02-27 01:50:52.916106Z] Finished read-buildbot-config step (success) 17:50:52 INFO - [mozharness: 2017-02-27 01:50:52.916241Z] Running download-and-extract step. 17:50:52 INFO - Running pre-action listener: _resource_record_pre_action 17:50:52 INFO - Running main action method: download_and_extract 17:50:52 INFO - mkdir: /builds/slave/test/build/tests 17:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:52 INFO - trying https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 17:50:52 INFO - Downloading https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/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 17:50:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/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 17:50:54 INFO - Downloaded 1199 bytes. 17:50:54 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:50:54 INFO - Using the following test package requirements: 17:50:54 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:50:54 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 17:50:54 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 17:50:54 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'jsshell-mac.zip'], 17:50:54 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 17:50:54 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:50:54 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 17:50:54 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 17:50:54 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 17:50:54 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:50:54 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 17:50:54 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:50:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:50:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:50:54 INFO - Fetch https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:50:55 INFO - Expected file size: 51468105 17:50:55 INFO - Obtained file size: 51468105 17:51:11 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 17:51:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:51:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:51:11 INFO - Fetch https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:51:12 INFO - Expected file size: 51468105 17:51:12 INFO - Obtained file size: 51468105 17:51:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 17:51:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:51:27 INFO - Fetch https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 17:51:28 INFO - Expected file size: 14404319 17:51:28 INFO - Obtained file size: 14404319 17:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:28 INFO - trying https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:51:28 INFO - Downloading https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 17:51:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/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 17:51:30 INFO - Downloaded 74798983 bytes. 17:51:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:51:30 INFO - mkdir: /builds/slave/test/properties 17:51:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:51:30 INFO - Writing to file /builds/slave/test/properties/build_url 17:51:30 INFO - Contents: 17:51:30 INFO - build_url:https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:51:31 INFO - Running post-action listener: _resource_record_post_action 17:51:31 INFO - Running post-action listener: set_extra_try_arguments 17:51:31 INFO - [mozharness: 2017-02-27 01:51:31.393876Z] Finished download-and-extract step (success) 17:51:31 INFO - [mozharness: 2017-02-27 01:51:31.393992Z] Running populate-webroot step. 17:51:31 INFO - Running pre-action listener: _resource_record_pre_action 17:51:31 INFO - Running main action method: populate_webroot 17:51:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:51:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:51:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:51:31 INFO - '--tppagecycles', 17:51:31 INFO - '7'], 17:51:31 INFO - 'tests': ['tsvgm']}}, 17:51:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:51:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:51:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:51:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:51:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:51:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:51:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['damp', 'tps']}, 17:51:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'tests': ['damp', 'tps']}, 17:51:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['dromaeo_dom']}, 17:51:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:51:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['basic_compositor_video']}, 17:51:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:51:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['a11yr', 17:51:31 INFO - 'ts_paint', 17:51:31 INFO - 'tpaint', 17:51:31 INFO - 'sessionrestore', 17:51:31 INFO - 'sessionrestore_no_auto_restore', 17:51:31 INFO - 'tabpaint']}, 17:51:31 INFO - 'other-e10s': {'tests': ['a11yr', 17:51:31 INFO - 'ts_paint', 17:51:31 INFO - 'tpaint', 17:51:31 INFO - 'sessionrestore', 17:51:31 INFO - 'sessionrestore_no_auto_restore', 17:51:31 INFO - 'tabpaint']}, 17:51:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:51:31 INFO - 'ts_paint', 17:51:31 INFO - 'tpaint', 17:51:31 INFO - 'sessionrestore', 17:51:31 INFO - 'sessionrestore_no_auto_restore', 17:51:31 INFO - 'tabpaint']}, 17:51:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:51:31 INFO - 'ts_paint', 17:51:31 INFO - 'tpaint', 17:51:31 INFO - 'sessionrestore', 17:51:31 INFO - 'sessionrestore_no_auto_restore', 17:51:31 INFO - 'tabpaint']}, 17:51:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['a11yr', 17:51:31 INFO - 'ts_paint', 17:51:31 INFO - 'tpaint', 17:51:31 INFO - 'sessionrestore', 17:51:31 INFO - 'sessionrestore_no_auto_restore', 17:51:31 INFO - 'tabpaint']}, 17:51:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['a11yr', 17:51:31 INFO - 'ts_paint', 17:51:31 INFO - 'tpaint', 17:51:31 INFO - 'sessionrestore', 17:51:31 INFO - 'sessionrestore_no_auto_restore', 17:51:31 INFO - 'tabpaint']}, 17:51:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['tsvgx', 17:51:31 INFO - 'tsvgr_opacity', 17:51:31 INFO - 'tart', 17:51:31 INFO - 'tscrollx', 17:51:31 INFO - 'cart', 17:51:31 INFO - 'tsvg_static']}, 17:51:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:51:31 INFO - 'tsvgr_opacity', 17:51:31 INFO - 'tart', 17:51:31 INFO - 'tscrollx', 17:51:31 INFO - 'cart', 17:51:31 INFO - 'tsvg_static']}, 17:51:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'talos_options': ['--disable-e10s'], 17:51:31 INFO - 'tests': ['tp5o']}, 17:51:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:51:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'talos_options': ['--disable-e10s', 17:51:31 INFO - '--xperf_path', 17:51:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:51:31 INFO - 'tests': ['tp5n']}, 17:51:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:51:31 INFO - 'talos_options': ['--xperf_path', 17:51:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:51:31 INFO - 'tests': ['tp5n']}}, 17:51:31 INFO - 'talos.zip': {'path': '', 17:51:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:51:31 INFO - Running post-action listener: _resource_record_post_action 17:51:31 INFO - [mozharness: 2017-02-27 01:51:31.412279Z] Finished populate-webroot step (success) 17:51:31 INFO - [mozharness: 2017-02-27 01:51:31.412393Z] Running create-virtualenv step. 17:51:31 INFO - Running pre-action listener: _resource_record_pre_action 17:51:31 INFO - Running main action method: create_virtualenv 17:51:31 INFO - Creating virtualenv /builds/slave/test/build/venv 17:51:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:51:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:51:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:51:31 INFO - Using real prefix '/tools/python27' 17:51:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:51:32 INFO - Installing distribute.............................................................................................................................................................................................done. 17:51:35 INFO - Installing pip.................done. 17:51:35 INFO - Return code: 0 17:51:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:51:35 INFO - Reading from file tmpfile_stdout 17:51:35 INFO - Output received: 17:51:35 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:51:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:51: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')]} 17:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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')]} 17:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:35 INFO - 'HOME': '/Users/cltbld', 17:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:35 INFO - 'LOGNAME': 'cltbld', 17:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:35 INFO - 'MOZ_NO_REMOTE': '1', 17:51:35 INFO - 'NO_EM_RESTART': '1', 17:51:35 INFO - 'PAGER': '/bin/cat', 17:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:35 INFO - 'PWD': '/builds/slave/test', 17:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:35 INFO - 'SHELL': '/bin/bash', 17:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:35 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:35 INFO - 'USER': 'cltbld', 17:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:35 INFO - 'XPC_FLAGS': '0x0', 17:51:35 INFO - 'XPC_SERVICE_NAME': '0', 17:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:51:35 INFO - Cleaning up... 17:51:35 INFO - Return code: 0 17:51:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:51: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')]} 17:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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')]} 17:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:35 INFO - 'HOME': '/Users/cltbld', 17:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:35 INFO - 'LOGNAME': 'cltbld', 17:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:35 INFO - 'MOZ_NO_REMOTE': '1', 17:51:35 INFO - 'NO_EM_RESTART': '1', 17:51:35 INFO - 'PAGER': '/bin/cat', 17:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:35 INFO - 'PWD': '/builds/slave/test', 17:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:35 INFO - 'SHELL': '/bin/bash', 17:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:35 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:35 INFO - 'USER': 'cltbld', 17:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:35 INFO - 'XPC_FLAGS': '0x0', 17:51:35 INFO - 'XPC_SERVICE_NAME': '0', 17:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:35 INFO - Downloading/unpacking psutil>=3.1.1 17:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:51:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:51:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:51:40 INFO - Installing collected packages: psutil 17:51:40 INFO - Running setup.py install for psutil 17:51:40 INFO - building 'psutil._psutil_osx' extension 17:51:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:51:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:51:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:51:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:51:40 INFO - building 'psutil._psutil_posix' extension 17:51:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:51:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:51:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:51:40 INFO - ^ 17:51:40 INFO - 1 warning generated. 17:51:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:51:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:51:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:51:41 INFO - Successfully installed psutil 17:51:41 INFO - Cleaning up... 17:51:41 INFO - Return code: 0 17:51:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:41 INFO - 'HOME': '/Users/cltbld', 17:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:41 INFO - 'LOGNAME': 'cltbld', 17:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:41 INFO - 'MOZ_NO_REMOTE': '1', 17:51:41 INFO - 'NO_EM_RESTART': '1', 17:51:41 INFO - 'PAGER': '/bin/cat', 17:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:41 INFO - 'PWD': '/builds/slave/test', 17:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:41 INFO - 'SHELL': '/bin/bash', 17:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:41 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:41 INFO - 'USER': 'cltbld', 17:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:41 INFO - 'XPC_FLAGS': '0x0', 17:51:41 INFO - 'XPC_SERVICE_NAME': '0', 17:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:51:44 INFO - Installing collected packages: mozsystemmonitor 17:51:44 INFO - Running setup.py install for mozsystemmonitor 17:51:44 INFO - Successfully installed mozsystemmonitor 17:51:44 INFO - Cleaning up... 17:51:44 INFO - Return code: 0 17:51:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:51:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:44 INFO - 'HOME': '/Users/cltbld', 17:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:44 INFO - 'LOGNAME': 'cltbld', 17:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:44 INFO - 'MOZ_NO_REMOTE': '1', 17:51:44 INFO - 'NO_EM_RESTART': '1', 17:51:44 INFO - 'PAGER': '/bin/cat', 17:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:44 INFO - 'PWD': '/builds/slave/test', 17:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:44 INFO - 'SHELL': '/bin/bash', 17:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:44 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:44 INFO - 'USER': 'cltbld', 17:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:44 INFO - 'XPC_FLAGS': '0x0', 17:51:44 INFO - 'XPC_SERVICE_NAME': '0', 17:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:44 INFO - Downloading/unpacking jsonschema==2.5.1 17:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:51:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:47 INFO - Downloading functools32-3.2.3-2.tar.gz 17:51:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:51:47 INFO - warning: no files found matching '*.txt' 17:51:47 INFO - no previously-included directories found matching 'build' 17:51:47 INFO - no previously-included directories found matching 'dist' 17:51:47 INFO - no previously-included directories found matching '.git*' 17:51:47 INFO - Installing collected packages: jsonschema, functools32 17:51:47 INFO - Running setup.py install for functools32 17:51:48 INFO - warning: no files found matching '*.txt' 17:51:48 INFO - no previously-included directories found matching 'build' 17:51:48 INFO - no previously-included directories found matching 'dist' 17:51:48 INFO - no previously-included directories found matching '.git*' 17:51:48 INFO - Successfully installed jsonschema functools32 17:51:48 INFO - Cleaning up... 17:51:48 INFO - Return code: 0 17:51:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:48 INFO - 'HOME': '/Users/cltbld', 17:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:48 INFO - 'LOGNAME': 'cltbld', 17:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:48 INFO - 'MOZ_NO_REMOTE': '1', 17:51:48 INFO - 'NO_EM_RESTART': '1', 17:51:48 INFO - 'PAGER': '/bin/cat', 17:51:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:48 INFO - 'PWD': '/builds/slave/test', 17:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:48 INFO - 'SHELL': '/bin/bash', 17:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:48 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:48 INFO - 'USER': 'cltbld', 17:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:48 INFO - 'XPC_FLAGS': '0x0', 17:51:48 INFO - 'XPC_SERVICE_NAME': '0', 17:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:51:48 INFO - Cleaning up... 17:51:48 INFO - Return code: 0 17:51:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:48 INFO - 'HOME': '/Users/cltbld', 17:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:48 INFO - 'LOGNAME': 'cltbld', 17:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:48 INFO - 'MOZ_NO_REMOTE': '1', 17:51:48 INFO - 'NO_EM_RESTART': '1', 17:51:48 INFO - 'PAGER': '/bin/cat', 17:51:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:48 INFO - 'PWD': '/builds/slave/test', 17:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:48 INFO - 'SHELL': '/bin/bash', 17:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:48 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:48 INFO - 'USER': 'cltbld', 17:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:48 INFO - 'XPC_FLAGS': '0x0', 17:51:48 INFO - 'XPC_SERVICE_NAME': '0', 17:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:48 INFO - Downloading/unpacking blobuploader==1.2.4 17:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:51 INFO - Downloading blobuploader-1.2.4.tar.gz 17:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:51:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:51:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:52 INFO - Downloading docopt-0.6.1.tar.gz 17:51:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:51:52 INFO - Installing collected packages: blobuploader, requests, docopt 17:51:52 INFO - Running setup.py install for blobuploader 17:51:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:51:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:51:52 INFO - Running setup.py install for requests 17:51:53 INFO - Running setup.py install for docopt 17:51:53 INFO - Successfully installed blobuploader requests docopt 17:51:53 INFO - Cleaning up... 17:51:53 INFO - Return code: 0 17:51:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51: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')]} 17:51:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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')]} 17:51:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:51:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:51:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:53 INFO - 'HOME': '/Users/cltbld', 17:51:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:53 INFO - 'LOGNAME': 'cltbld', 17:51:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:53 INFO - 'MOZ_NO_REMOTE': '1', 17:51:53 INFO - 'NO_EM_RESTART': '1', 17:51:53 INFO - 'PAGER': '/bin/cat', 17:51:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:53 INFO - 'PWD': '/builds/slave/test', 17:51:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:53 INFO - 'SHELL': '/bin/bash', 17:51:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:53 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:53 INFO - 'USER': 'cltbld', 17:51:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:53 INFO - 'XPC_FLAGS': '0x0', 17:51:53 INFO - 'XPC_SERVICE_NAME': '0', 17:51:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:51:53 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-hXuCDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:51:53 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-7Q2tiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:51:53 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-h4nDkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-hDrkq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-ILIVZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-QiJmMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-zH0Mqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-upnR_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-dK9Rke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-cwqwwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-1Ekl4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-87epd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:51:54 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-Fi8kYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:51:55 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-1Udt5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:55 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-3DBgr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:51:55 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-0xvLEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:51:55 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-xWSb2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:51:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:51:55 INFO - Running setup.py install for manifestparser 17:51:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:51:55 INFO - Running setup.py install for mozcrash 17:51:55 INFO - Running setup.py install for mozdebug 17:51:55 INFO - Running setup.py install for mozdevice 17:51:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Running setup.py install for mozfile 17:51:56 INFO - Running setup.py install for mozhttpd 17:51:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Running setup.py install for mozinfo 17:51:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Running setup.py install for mozInstall 17:51:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Running setup.py install for mozleak 17:51:56 INFO - Running setup.py install for mozlog 17:51:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:51:56 INFO - Running setup.py install for moznetwork 17:51:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:51:57 INFO - Running setup.py install for mozprocess 17:51:57 INFO - Running setup.py install for mozprofile 17:51:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:51:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:51:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:51:57 INFO - Running setup.py install for mozrunner 17:51:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:51:57 INFO - Running setup.py install for mozscreenshot 17:51:57 INFO - Running setup.py install for moztest 17:51:57 INFO - Running setup.py install for mozversion 17:51:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:51:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:51:58 INFO - Cleaning up... 17:51:58 INFO - Return code: 0 17:51:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:58 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 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:58 INFO - 'HOME': '/Users/cltbld', 17:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:58 INFO - 'LOGNAME': 'cltbld', 17:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:58 INFO - 'MOZ_NO_REMOTE': '1', 17:51:58 INFO - 'NO_EM_RESTART': '1', 17:51:58 INFO - 'PAGER': '/bin/cat', 17:51:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:58 INFO - 'PWD': '/builds/slave/test', 17:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:58 INFO - 'SHELL': '/bin/bash', 17:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:51:58 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:58 INFO - 'USER': 'cltbld', 17:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:58 INFO - 'XPC_FLAGS': '0x0', 17:51:58 INFO - 'XPC_SERVICE_NAME': '0', 17:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-kSJL_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-_wgW46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:51:58 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)) 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-8KMRoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-DdG8vT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:51:58 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)) 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-IZHp94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-CNNGMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:51:58 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-f7OiIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-vqwJYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-Ma1e1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-2VlX1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-1gODoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-rC9Igv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-KJeevp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-4ota54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:51:59 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)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-QD5lFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:51:59 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-zjelTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:52:00 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)) 17:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:52:00 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-kJpmBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:52:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:52:00 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)) 17:52:00 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)) 17:52:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:52:00 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)) 17:52:00 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)) 17:52:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:52:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:02 INFO - Downloading blessings-1.6.tar.gz 17:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:52:02 INFO - Installing collected packages: blessings 17:52:02 INFO - Running setup.py install for blessings 17:52:03 INFO - Successfully installed blessings 17:52:03 INFO - Cleaning up... 17:52:03 INFO - Return code: 0 17:52:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:52:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:52:03 INFO - Reading from file tmpfile_stdout 17:52:03 INFO - Current package versions: 17:52:03 INFO - blessings == 1.6 17:52:03 INFO - blobuploader == 1.2.4 17:52:03 INFO - docopt == 0.6.1 17:52:03 INFO - functools32 == 3.2.3-2 17:52:03 INFO - jsonschema == 2.5.1 17:52:03 INFO - manifestparser == 1.1 17:52:03 INFO - mozInstall == 1.12 17:52:03 INFO - mozcrash == 1.0 17:52:03 INFO - mozdebug == 0.1 17:52:03 INFO - mozdevice == 0.49 17:52:03 INFO - mozfile == 1.2 17:52:03 INFO - mozhttpd == 0.7 17:52:03 INFO - mozinfo == 0.9 17:52:03 INFO - mozleak == 0.1 17:52:03 INFO - mozlog == 3.4 17:52:03 INFO - moznetwork == 0.27 17:52:03 INFO - mozprocess == 0.25 17:52:03 INFO - mozprofile == 0.28 17:52:03 INFO - mozrunner == 6.13 17:52:03 INFO - mozscreenshot == 0.1 17:52:03 INFO - mozsystemmonitor == 0.3 17:52:03 INFO - moztest == 0.8 17:52:03 INFO - mozversion == 1.4 17:52:03 INFO - psutil == 3.1.1 17:52:03 INFO - requests == 1.2.3 17:52:03 INFO - wsgiref == 0.1.2 17:52:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52: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')]} 17:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:52:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:52:03 INFO - 'HOME': '/Users/cltbld', 17:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:52:03 INFO - 'LOGNAME': 'cltbld', 17:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:03 INFO - 'MOZ_NO_REMOTE': '1', 17:52:03 INFO - 'NO_EM_RESTART': '1', 17:52:03 INFO - 'PAGER': '/bin/cat', 17:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:03 INFO - 'PWD': '/builds/slave/test', 17:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:52:03 INFO - 'SHELL': '/bin/bash', 17:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:52:03 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:52:03 INFO - 'USER': 'cltbld', 17:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:03 INFO - 'XPC_FLAGS': '0x0', 17:52:03 INFO - 'XPC_SERVICE_NAME': '0', 17:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:52:03 INFO - Cleaning up... 17:52:03 INFO - Return code: 0 17:52:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52: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')]} 17:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8858450fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, '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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:52:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:52:03 INFO - 'HOME': '/Users/cltbld', 17:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:52:03 INFO - 'LOGNAME': 'cltbld', 17:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:03 INFO - 'MOZ_NO_REMOTE': '1', 17:52:03 INFO - 'NO_EM_RESTART': '1', 17:52:03 INFO - 'PAGER': '/bin/cat', 17:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:03 INFO - 'PWD': '/builds/slave/test', 17:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:52:03 INFO - 'SHELL': '/bin/bash', 17:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:52:03 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:52:03 INFO - 'USER': 'cltbld', 17:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:03 INFO - 'XPC_FLAGS': '0x0', 17:52:03 INFO - 'XPC_SERVICE_NAME': '0', 17:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:52:03 INFO - Cleaning up... 17:52:03 INFO - Return code: 0 17:52:03 INFO - Running post-action listener: _resource_record_post_action 17:52:03 INFO - Running post-action listener: _start_resource_monitoring 17:52:03 INFO - Starting resource monitoring. 17:52:03 INFO - [mozharness: 2017-02-27 01:52:03.962281Z] Finished create-virtualenv step (success) 17:52:03 INFO - [mozharness: 2017-02-27 01:52:03.963215Z] Running install step. 17:52:03 INFO - Running pre-action listener: _resource_record_pre_action 17:52:03 INFO - Running main action method: install 17:52:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:52:04 INFO - Reading from file tmpfile_stdout 17:52:04 INFO - Detecting whether we're running mozinstall >=1.0... 17:52:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:52:04 INFO - Reading from file tmpfile_stdout 17:52:04 INFO - Output received: 17:52:04 INFO - Usage: mozinstall [options] installer 17:52:04 INFO - Options: 17:52:04 INFO - -h, --help show this help message and exit 17:52:04 INFO - -d DEST, --destination=DEST 17:52:04 INFO - Directory to install application into. [default: 17:52:04 INFO - "/builds/slave/test"] 17:52:04 INFO - --app=APP Application being installed. [default: firefox] 17:52:04 INFO - mkdir: /builds/slave/test/build/application 17:52:04 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'] 17:52:04 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 17:52:24 INFO - Reading from file tmpfile_stdout 17:52:24 INFO - Output received: 17:52:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:52:24 INFO - Running post-action listener: _resource_record_post_action 17:52:24 INFO - [mozharness: 2017-02-27 01:52:24.538075Z] Finished install step (success) 17:52:24 INFO - [mozharness: 2017-02-27 01:52:24.538254Z] Running run-tests step. 17:52:24 INFO - Running pre-action listener: _resource_record_pre_action 17:52:24 INFO - Running main action method: run_tests 17:52:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:52:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:52:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:52:24 INFO - 2017-02-26 17:52:24.552 screenresolution[1641:13459] starting screenresolution argv=screenresolution get 17:52:24 INFO - 2017-02-26 17:52:24.555 screenresolution[1641:13459] Display 0: 1600x1200x32@60 17:52:24 INFO - 2017-02-26 17:52:24.561 screenresolution[1642:13462] starting screenresolution argv=screenresolution list 17:52:24 INFO - Available Modes on Display 0 17:52:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:52:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:52:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:52:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:52:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:52:24 INFO - Intel Iris: 17:52:24 INFO - Chipset Model: Intel Iris 17:52:24 INFO - Type: GPU 17:52:24 INFO - Bus: Built-In 17:52:24 INFO - VRAM (Dynamic, Max): 1536 MB 17:52:24 INFO - Vendor: Intel (0x8086) 17:52:24 INFO - Device ID: 0x0a2e 17:52:24 INFO - Revision ID: 0x0009 17:52:24 INFO - Displays: 17:52:24 INFO - Display: 17:52:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:52:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:52:24 INFO - Display Serial Number: 1600x1200 60 17:52:24 INFO - Main Display: Yes 17:52:24 INFO - Mirror: Off 17:52:24 INFO - Online: Yes 17:52:24 INFO - Rotation: Supported 17:52:24 INFO - Return code: 0 17:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:52:24 INFO - Python 2.7.3 17:52:24 INFO - Return code: 0 17:52:24 INFO - grabbing minidump binary from tooltool 17:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:52:24 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 0x10362fd30>, '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 0x103556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103624be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:52:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:52:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:52:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:52:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:52:24 INFO - Return code: 0 17:52:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:52:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:52:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:52:24 INFO - ENV: RUST_BACKTRACE is now 1 17:52:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:52:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0203', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0203 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:52:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iAJXeeo2Ic/Render', 17:52:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:52:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:52:24 INFO - 'HOME': '/Users/cltbld', 17:52:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:52:24 INFO - 'LOGNAME': 'cltbld', 17:52:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:52:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:24 INFO - 'MOZ_NO_REMOTE': '1', 17:52:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:52:24 INFO - 'NO_EM_RESTART': '1', 17:52:24 INFO - 'PAGER': '/bin/cat', 17:52:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:24 INFO - 'PWD': '/builds/slave/test', 17:52:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:52:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:52:24 INFO - 'RUST_BACKTRACE': '1', 17:52:24 INFO - 'SHELL': '/bin/bash', 17:52:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A1U9xfkUWx/Listeners', 17:52:24 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 17:52:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:52:24 INFO - 'USER': 'cltbld', 17:52:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:52:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:24 INFO - 'XPC_FLAGS': '0x0', 17:52:24 INFO - 'XPC_SERVICE_NAME': '0', 17:52:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:52:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0203', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:52:25 INFO - mozversion application_buildid: 20170226162556 17:52:25 INFO - mozversion application_changeset: b06968288cff469814bf830aa90f1c84da490f61 17:52:25 INFO - mozversion application_display_name: Nightly 17:52:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:52:25 INFO - mozversion application_name: Firefox 17:52:25 INFO - mozversion application_remotingname: firefox 17:52:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:52:25 INFO - mozversion application_vendor: Mozilla 17:52:25 INFO - mozversion application_version: 54.0a1 17:52:25 INFO - mozversion platform_buildid: 20170226162556 17:52:25 INFO - mozversion platform_changeset: b06968288cff469814bf830aa90f1c84da490f61 17:52:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:52:25 INFO - mozversion platform_version: 54.0a1 17:52:25 INFO - using testdate: 1488160345 17:52:25 INFO - actual date: 1488160345 17:52:25 INFO - starting webserver on 'localhost:49245' 17:52:25 INFO - SUITE-START | Running 2 tests 17:52:25 INFO - TEST-START | dromaeo_css 17:52:25 INFO - operating with platform_type : mac_ 17:52:25 INFO - Initialising browser for dromaeo_css test... 17:52:25 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpUAHXfd/profile http://localhost:49245/getInfo.html) 17:52:28 INFO - PROCESS | 1652 | 2017-02-26 17:52:28.013 plugin-container[1655:13647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 17:52:28 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:28 INFO - PROCESS | 1652 | 2017-02-26 17:52:28.016 plugin-container[1655:13647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 17:52:28 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:28 INFO - PROCESS | 1652 | 2017-02-26 17:52:28.017 plugin-container[1655:13647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:52:28 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 17:52:28 INFO - PROCESS | 1652 | colorDepth:24 17:52:28 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 17:52:28 INFO - PROCESS | 1652 | __metrics 17:52:28 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 17:52:28 INFO - TEST-INFO | 1652: exit 0 17:52:28 INFO - Browser initialized. 17:52:28 INFO - Running cycle 1/1 for dromaeo_css test... 17:52:28 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpUAHXfd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:52:31 INFO - PROCESS | 1656 | 2017-02-26 17:52:31.471 plugin-container[1657:13779] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 17:52:31 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:31 INFO - PROCESS | 1656 | 2017-02-26 17:52:31.471 plugin-container[1657:13779] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 17:52:31 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:31 INFO - PROCESS | 1656 | 2017-02-26 17:52:31.471 plugin-container[1657:13779] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:52:41 INFO - PROCESS | 1656 | RSS: Main: 226287616 17:52:41 INFO - PROCESS | 1656 | 17:55:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 17:55:45 INFO - PROCESS | 1656 | RSS: Main: 250916864 17:55:45 INFO - PROCESS | 1656 | 17:57:29 INFO - PROCESS | 1656 | ************************* 17:57:29 INFO - PROCESS | 1656 | A coding exception was thrown and uncaught in a Task. 17:57:29 INFO - PROCESS | 1656 | 17:57:29 ERROR - PROCESS | 1656 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:57:29 INFO - PROCESS | 1656 | Full stack: 17:57:29 INFO - PROCESS | 1656 | ************************* 17:58:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 17:58:48 INFO - PROCESS | 1656 | RSS: Main: 257519616 17:58:48 INFO - PROCESS | 1656 | 18:01:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 18:01:51 INFO - PROCESS | 1656 | RSS: Main: 268263424 18:01:51 INFO - PROCESS | 1656 | 18:04:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 18:04:53 INFO - PROCESS | 1656 | RSS: Main: 261115904 18:04:53 INFO - PROCESS | 1656 | 18:07:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 18:07:57 INFO - PROCESS | 1656 | RSS: Main: 272424960 18:07:57 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 18:11:00 INFO - PROCESS | 1656 | RSS: Main: 265879552 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | __start_tp_report 18:11:00 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 18:11:00 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 18:11:00 INFO - PROCESS | 1656 | |i|pagename|runs| 18:11:00 INFO - PROCESS | 1656 | |0;dojo.html;3331.383694016607;3337.46600543913;3374.3926093303194;3387.2040070583416;3412.368711788319;5461.508464238218;5574.163875418689;5628.718564071801;5686.289213848264;5694.4590263924965;4673.345731597579;5500.2299678045;5642.1254705520605;5695.288088988877;5762.596618236715;3837.6546110850018;5094.6433749637545;5163.070647283506;5266.605004624649;5280.788768449257;4618.330342100394;5119.897602047957;5193.298904647881;5297.682139071649;5366.838994830142;4980.651354405193;5244.344456942882;5248.947510524884;5257.263983042358;5263.552598029172;5026.271190677331;5082.364704411949;5150.61370397218;5167.509086636764;5234.8691282717855;5137.7944882204665;5140.920406714595;5165.018646457161;5175.844724658266;5224.712640804754;5575.553955683536;5654.6041777075625;5663.122216056518;5699.088145896637;5723;4890.73100979446;6025.975584150705;6031.728572214217;6125.264968203844;6216.03651434024;6148.754049838001;6186.628802271878;6222.3466536013975;6229.127922090911;6602.141721576212;4598.632109431237;4751.59611433032;5324.227986941872;5877.089051197028;5950.018246989242;5405.270288511079;5443.292371991616;5656.151577263361;6252.999520076766;6254.6872656366995;5798.82603521895;5841.970790146022;5872.58891877573;5959.106134079922;5959.433853783884;5429.131338985743;6098.786542470993;6121.940772763084;6160.722767475474;6281.874362512815;5926;5961.642301461926;5964.284285885721;6056.878862422727;6082.1789058477425;6184.165137706352;6247.437730604177;6257.061440783918;6258.593191442542;6273.466755325757;4951.529604687549;4956.207006878882;4975.900481990412;4984.2025275955675;4993;4300.376445415397;4311.698181127354;4347.913041739147;4409.11817636474;4426.313921342197;4386.363977223285;5733.8566535836935;5735.942640573624;5784.1902133701315;5856.4143585641095;6269.592476489013;6273.466755325757;6287.811365659038;6292.685365731695;6309.810705678745;5785.1322301654245;5802.303723553201;5805.332386775491;5835.270591176103;5868.471837534557;5206.8958620828125;5239.790408383698;5401.675899446046;5422.078246698071;5426.891462170814;6251.468625166821;6270.529710271839;6276.309605943343;6281.246250449975;6282.246130464374;6137.570370074052;6259.749610015549;6264.467520260829;6284.277308109626;6288.528360373082;5935.673537955454;6139.093519790236;6167.660778657217;6197.101420294122;6288.591241569284;5054.74726263678;6215.96892015537;6218.502519798315;6229.813105606839;6262.029385445263;6221.06683997404;6267.2792628848265;6272.059191121186;6284.277308109443;6294.590851594632;5738.512226460798;5850.23946886902;6153.384661533811;6161.383861613803;6182.412670796267;5276.920846187233;5633.492985631313;5921.822345329647;5958.76164953397;6041.697915104315;5697.914531282117;5947.256592925884;5954.07711804671;6003.6397816131175;6010.579259451796;5841.50347220491;5864.149698293809;5879.853003674943;6013.5189184865985;6151.84620384494;5934.821955341346;5991.2510936132985;6006.549508786771;6026.367231440636;6047.0627052806885 18:11:00 INFO - PROCESS | 1656 | |1;ext.html;443.4213351576193;461.503883325425;467.69833457419963;490.20831357357866;545.3319683387853;36307.81846090769;39160;39499;39525;39542.802285988604;40750.79624601873;41338.96652583687;41339.75980720576;41484.17031659365;41525;8178.959105204465;9202.217811486033;9593.136617704406;10973.890261097407;11418.371989540572;11056.668299951014;11132.220744547885;11138.27601205919;11153.386563738992;11163.265000074953;11200.103991680688;11200.887991120066;11224;11253.987141157295;11263.4368281586;11134.220604557684;11173.832392514038;11181.552737890475;11186.993170614644;11193.44032798365;9235;11149.052330551913;11189.272697274651;11222.719432014183;11229.989300962952;36481.63518364809;36633;36946.63053369486;37024;37058;3724.952520739638;3727.403615421547;3755.530558680165;3760.8871733848027;3889.8055097245306;26845.865770671022;27259.727402725915;27761.583576246372;27788.444231115467;27858.16425507238;3040.5705762069;3043.863026163806;3211.791233569834;3228.7094161525342;3233.4988076848126;466430;467648;468436;469274;469549.956750649;45121.77439112849;46021.76989115033;49151;54710.17934730982;55723.44276557264;57680;57789.71105144447;57821.71089144528;58105.128423073686;58176.418235817946;11916.404179790976;15018.549443516711;15049.774253386207;15080.54758357251;15087.924560377129;11380.203385762918;11392.829107563393;11495.195336326544;11520.827187592025;11534.250273732348;17074.40239591633;17075;17109.743353849703;17141;17154.656906861794;6545.705443255065;7346.603124907132;7819.882701759479;7857.174996625272;7867.488613240121;639.7185238495048;662.5329142954206;730.3062091013559;793.6626933553217;821.7000794709888;25723.871380642977;28398.888200124176;29413.411731765675;29630.555541666894;30130;954.8351011765635;1018.6180182431589;1061.713337398898;1077.849101125843;1170.6488053583892;13163;14545.92727036358;15186.088834669956;15198.620034499005;15404.229788510529;578650;588332;588651;591053.0447347822;591226;57196.42803571911;57281.42718572761;57865.421345786846;58025.70987145037;58085.70957145187;1156.1964434717959;1269.6508460173527;1328.4885319152197;1329.0829327763813;1378.7656098463085;27923;28460.003899863405;29063.418731625672;29174.601285408506;29382.559261610666;47855;48004.75997619989;48024.27963580619;48025.03949921052;48140.27789583229;428398;431090;432268;432564;433026;21883.12467501282;22474.32577022692;24953.126640567494;25289.241322760347;25830.741692582833;45028.87427814331;45190.77404612956;45403.318950215114;46542.767286163355;46714;28083.578746319232;35843;36336.27327453489;37111;37510.24979500449;30428.84785576058;31726;36017.279654406244;37003.62996370002;37207.88376348714;35156.47265290972;35318;36098.45852312165;36338;36539.451908220864;30406.84796576003;32206;32207;32312.515312269865;32320.515192272604;19122.426327210207;22475.887620561793;23926.401839954142;24031.399215019763;24375.26874193777 18:11:00 INFO - PROCESS | 1656 | |2;jquery.html;389.33229511387964;390.4475167637793;395.6597326299382;398.69101446379045;431.3012919071105;13216.93391533041;14724.558263252093;17633.853799503024;20580.073896674647;21265.57468850622;20308.69536956947;20354.796452035513;21286.148554057818;21502.462438439048;21751.782482175175;5814.97092514539;5836.15375770514;6005.159277701126;6078.817635470921;6081.148639190517;5804.912926306109;5957.493613042897;6059.848503787396;6063.242094738158;6063.514918806485;5101.464346243628;5956.255468066492;5965.522758179335;6024;6034.185384973039;5715.742791574389;5920.585559010892;5924;5981.790637327695;5993.670348130851;5255;5256.684598924076;5257.290265814105;5266.315379000744;5274.156135018378;25412.491750164892;26123;26324;26334.946602135893;26674.46651066987;2186.5517568898495;2375.2280508834524;2666.226794229671;2710.132757517595;2734.53512902807;11641.883581164164;15509.844901550952;15587.519185677342;16057.598560036093;16357.345706171738;1470.8087948566724;1813.3743858368841;1830.5172810023923;1859.1540848913846;1890.9527261818428;98675.50662246715;101315.49342253315;102849.99999999926;123540;129941.70058299485;9453.763655908657;10030.247731420173;11901.166918315635;12080.51677932873;12088.516459341703;15417.691646167013;15465;15469.458568950033;15498.5350439487;15510.612234693997;7264.854702906018;8073.9192608074345;8137.95931020353;8427.578621068922;8544.871826922601;6595.868082638321;8011.238932301422;8048.83902321962;8078.839397710184;8203.179682031749;4776.904461910812;4800.66395352322;4801.663883528189;4830.686005409637;4916.385451818523;1684.6041180322613;1853.7209325565316;1982.5440148765576;2077.241806740529;2077.4390914453015;923.8290916180528;998.8651532043226;1018.7911478146962;1022.9488525573692;1045.2056437107854;20300.695489567668;20726.860022698595;20813.1674733012;20822.687659685118;20875.478113047295;846.86873534601;985.5466485416645;988.4069558264983;1018.4245901065874;1023.518946095334;10202.183825293958;10795.46022698862;10803.405812680221;10806.189535784848;10833.512491937881;553350;671375;677779;683245;696808;99952.00047999427;133256.33371833077;134198.16036792682;135936;137069.3146534281;981.5582987655583;1034.3121823987115;1054.3463052907246;1057.0856209378985;1058.9205809564314;19618.117184726438;19693.91683457366;19892;19909.203631855144;19950;45964.77017614891;55459.44540554704;56904.71547642401;58202;58668.70665646644;82412.76380854173;85396.57301713452;85511;86711;87503;6774.830629234309;8089.191080891864;8274.710385136492;8383.95808020956;8402.705905293285;10284.691459255935;12347.938260308641;12530.686732831753;12819.42312595954;12895.548655797002;7787.766367008999;10405.219608529542;10412.583496660049;10825.729356766144;10833.837492437462;10303.124234440156;10415.1667866572;10484;10906.836397453886;10922.672319830417;8940.776480587778;10252.64115755945;10788.892111079103;11127.720312164205;11199.832002519806;7251.927480725336;7310.926890731024;7521.05986751656;7841.098273698598;8508.914910850812;8085.797855053671;9663.855042174513;10387.428691422045;10935;11182.881711828752 18:11:00 INFO - PROCESS | 1656 | |3;mootools.html;1303.3352989975112;1455.1996401978915;1475.1739026145367;1480.6594483268848;1481.8221813382395;6895.9655201724045;8183.427160098796;8293.58532073397;8541.060483346826;8625.654973801056;7651.846963060735;8457.112003239663;8471.534065626389;8568.742937711862;8605.39762216645;1723.534645645675;2088.7284652995168;2213.6607352551714;2301.2750983440246;2314.3057082875152;2288.5766133265397;2322.326525307667;2327.8254130940163;2328.569214695287;2331.62693968965;2276.658501224821;2279.623862062758;2311.9537609247805;2312.364099872532;2314.6990891184205;2206.735191776997;2278.5329007553496;2299.8160147188187;2305.988470057656;2309.2379514760087;1905.8951757653324;1953.990230048855;2031.3702752146883;2296.3455415206754;2330.7436182019965;8284.7100351488;8496.277816385615;8511.97856257253;8602.09677983809;8637.179467950535;5126.179811230185;5267.130923397634;5300.966311569246;5309.41596424393;5311.017461769585;2845.900393486218;2873.4971380008624;3415.112070861594;3433;3522.4716292556063;1188.4533114767216;1196.096006373869;1213.5691829400523;1218.7257866979946;1244.2347956007081;10828.133749300037;10866.891331086666;10905.672829815117;10967.232293739442;11108.222424430292;4223.535411104776;4497.673186410017;4569.771511424416;4576.8626941191815;4596.264597664424;8762.299016078698;8807.163319484767;8828.823423531494;8833.64665413389;8863.202311791969;5144.742762861842;5154.381474223117;5155.819546315861;5157.561607263425;5163.896722065538;4576.26779715244;4637.420322459692;4763.285507173951;4785.569298763128;4837.516248375205;3683.37382644951;3694.261147770457;3762.567304759933;3793.867214647474;3804.6575808177395;1159.6231224851956;1170.1107158559387;1171.1743221029153;1178.6581891251442;1181.8227265910011;836.2557323981662;869.3002133282641;872.376250980552;910.4719262827545;930.181440332503;5621.775128994795;5662.886742265214;5710.114932185518;5834.474897259182;6034.668093254913;942.7972985808084;949.4208532794989;952.4951775558965;959.318883592643;985.3152059318704;3673.1918977824835;3674.3202507536193;3784.8675296364495;3858.4212368145004;3886.7473614214987;9707.563159657833;10062.295639305325;10063.345882517759;10064.245181611408;10068;9945.900540994642;9967.55146018431;9993.900060999296;10000.449975251431;10004.449755263531;7397.889031664529;7768.028996375566;7779.572123533147;7893.684252629945;7897.960510197412;5638.915416268677;5639.154126881001;5816.214811000461;5837.737301821513;5873.3832947541605;8880.86678699833;8963.865542017005;8995.280377569648;9156.679516217167;9221.538923053955;7684.769456916078;7772.339351154989;9253.444793312423;9324.766880828034;9500.239980801382;7177.856442871218;7368;7375.11498620169;7382.6677799498075;7394.704211831681;7813.882791758015;7936.325412340016;7937.801554960941;7938.960305198437;7971.322437592869;7105.32499412555;7140.000399943908;7266.273372662691;7279.22139468509;7296.233895440902;5734.913976290276;6737.528373013842;6754.696038678173;6824.7952561423235;6879.933610290413;5482.5888058394985;6397.328280530477;6473.741050357933;6480.837979050373;6484.383983521558;4820.228763397839;4861.611071114373;4991.750412479434;5046.116929537389;5178.559822415137;4476.955230447654;4822.734749588806;5038.269450929522;5074.162763144188;5184.455632158569 18:11:00 INFO - PROCESS | 1656 | |4;prototype.html;336.20319841974515;363.09226932668327;376.95476542814856;379.8955287295995;385.8437332880184;4890.070886531556;5109.821156259532;5183.326167598207;5347.893042139155;5472.644278121924;4397.670174736892;4534.886627834306;5245.291885595443;5258.894822103565;5271.910942860992;3838.5201849768778;3944.4674968879194;4188.329867221245;4281.207976524353;4293.227219100576;3371.685042833297;4238.406623072772;4250.27745283301;4269.765162916038;4270.636995855356;3948.9917733500374;4037.8788636340955;4068.87793366198;4081.7346872453195;4089.3457046872504;3568.6074531801482;3748.400255959047;4091.9181616367655;4093.789353748972;4113.91772164555;3046.9847650761826;3228.322052369005;3535.9116022099397;3536.0452677776857;3581.7313701472235;4248.660107191448;4935.851924442272;5326.946730532683;5334.253204551366;5405.5135037846785;3820.3505404081375;4012.976690943791;4750.216214324632;4759.690620109682;4778.5699287064335;5509.944900550983;5532.3914369419335;5747.712614369265;5759.712014399305;5794.362620111784;3603.94594081089;3858.440526123696;3901;3929.607039296076;3933.665638420709;5355.785768569292;5797.507211886995;5955.374685657986;5999.460048595647;6047.274327080734;3578.6600272974024;3893.4159876018257;3894.279558281727;3914;3917.6865850731874;3967.206558688274;3976.383660532622;3978.468289708483;3988.840446382112;3989.740666856645;4532.229520981376;4673.205555055581;4717.268823332389;4725.4565724941385;4740.95259047412;4809.350737650442;5589.832305030855;5675.971620141873;5734.713264336767;5765.538756899438;1094.7482079121687;1138.6470194239812;1270.4791035675328;1284.17170924753;1292.8577856435784;722.3282347416848;804.3886646148969;822.4989394355325;875.746033650247;894.9821003579892;4685.250359942393;4740.905181896343;4755.072760811678;5122.334096567422;5191.299174611455;5079.847604571795;5316.3354580677415;5554.944450555443;5689.829305120853;5765.549641402471;4293.5706429356815;4557.134144512532;4616.6075883550275;4768.737719425465;4905.852824415273;2638.3931695710094;2905.491538980712;2973.628296462942;2985.1939976206304;3026.757859371245;5079.923801142986;5679.914801277984;5726.394490506809;5924.496417804535;5931.73307201177;5255.658382205144;5453.972730136404;5511.366192887869;5778.5377169826315;5827.79602713903;5415.810446634349;5537.778488860481;5551.25058117149;5815.999999999915;5941.494972927349;5218.347706536683;5539.789759782514;5709.600327977002;5763.164341170423;5779.393163717749;4940.888300132585;5408.945910540844;5441.809536666197;5555.833275012131;5738.426157384228;5288.334174734946;5637.940682708443;5807.651540907559;5807.983602869565;6052.788152414644;3320.007995202859;3768.3970564709516;3828.1769419574925;3910.1202229498135;3912.315344814703;4922.449428430033;5099.77051032697;5311.41574426805;5740.483356497935;5927.585069045125;4583.839565615188;5199.350081239845;5369.409364969928;5664.37691853904;5730.885382292414;4875.5632374539;4952.5542701157065;5020.523050310215;5530.649473973625;5689.146628005832;4815.181419158681;5036.773345199548;5122.466617043869;5327.733613319396;5571.245057806778;4577.3591697161755;4696.641901040773;5533.533613592321;5734.913976290442;5743.971280143573;4729.43246810385;4771.73755443454;4852.344933433941;5325.7337133143965;5598.832035038955 18:11:00 INFO - PROCESS | 1656 | |5;yui.html;693.8993845892346;705.3123204875251;772.9758070556514;797.625116195388;814.6415577146057;14406.279686015696;15066.54800355991;15122.62193445163;15131.31909064092;15315.234238288096;11953.402329883515;13746.312684365792;14010.579682609537;14172.503962361294;14219.289035548207;895.1674942303639;905.3074398085437;908.9727308180766;910.1308250620674;911.968081117161;832.2052439919893;874.5758307220992;894.5303715549323;900.5767289373983;906.2387594420685;951.8429459139234;952.105021279998;954.1508057828538;956.7560272130597;957.8994205608416;948.7438391634289;951.4909523405014;951.9952400237954;953.3374304858127;954.5036580977923;596.9522438204933;691.512483698996;698.5215127637584;703.1597241296653;710.2471380336859;15495.535133946;15666.921665391714;15668.981516201411;15677;15853.52439426819;13567.321633918265;13650.385732642055;13773.38019789112;13807.309634518335;13856.445742170301;11424.562660457397;11516.309021458655;11590.246633968849;11650.41747912601;11850.051995840313;3155.763317751155;3552.2185119273718;3659.5791483979415;3757.1170774867883;3797.4683544303543;78813.60593196997;79405.20594794035;98943;99819.0018099817;100186.99439016842;10348.586056557804;10616.522256498476;10679.466026698634;10749.892501075046;10803.351798892092;21984.45038874009;22001.11995520161;22269.331920042423;22330.888345558167;22441.32676019722;7282.927170728331;7347.485676002629;7365.594892280976;7432.219616940251;7515.736949206861;3863.690904727615;3874.380099184117;3892.026993251687;3923.92152156961;3942.4086387042175;3599.9820000899826;3668.413053911361;3695.9445608315896;3699.315626609086;3702.7037836972977;661.2792056658266;667.8096742428481;669.8459354348527;685.5441131647498;691.0532570378614;554.9583781216425;597.3041406761075;609.7469550136643;624.1813202079935;635.6376865186799;8011.278984891388;8359.456635318686;9377.307530292115;9625.32622716417;9885;524.8084449176099;659.261626977788;672.4855485553555;684.8116767888771;691.9031335613018;4378.934315985262;4774.331593576902;4814.831480898151;4838.95161048392;4879.609311346249;71048.44757762091;96055;97188.54217186748;99124.5043774791;100156.49921750344;69919.65040174767;70382.64808676027;72965;87308;90052.5497372509;68906;70087.94868076983;71585.6420717893;71624.64187679028;73150;9691.418514888846;10189.235807314333;10414.895851041392;10422.218333624856;10459.686209413425;46849.2972605418;47523.811904702656;48494.757526212146;48500.78748031327;48904.53286401414;64785;65421.691566169364;65575;66295.67408651703;67824;12241.632751017483;12311.938440307742;12394;12481.56314528987;12934.870651293742;12406.689832754253;13603.455861765418;14185.007049506436;14335.426582936983;14533.709325813214;11565.826512602485;11711;11874.228175168586;11879.821802672794;12260.632181034583;10935.398553079656;12857.742845143232;12879.162854414433;12972.156809807333;13054.477820887058;10021.699349019542;12625.305608191638;12652.936735316265;13067.738645227233;13133.474661013835;10406;10551.419671918118;10587.20595955291;10633.042766686767;11395;6247.781327653511;8552.700655477029;9127.497987610745;9781.119699227103;10451.42517161533 18:11:00 INFO - PROCESS | 1656 | __end_tp_report 18:11:00 INFO - PROCESS | 1656 | __start_cc_report 18:11:00 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,421 18:11:00 INFO - PROCESS | 1656 | __end_cc_report 18:11:00 INFO - PROCESS | 1656 | __startTimestamp1488161460464__endTimestamp 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | ------- Summary: start ------- 18:11:00 INFO - PROCESS | 1656 | Number of tests: 6 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5633.09 Median:5841.50 stddev:669.02 (11.5%) stddev-sans-first:646.24 18:11:00 INFO - PROCESS | 1656 | Values: 3331.4 3337.5 3374.4 3387.2 3412.4 5461.5 5574.2 5628.7 5686.3 5694.5 4673.3 5500.2 5642.1 5695.3 5762.6 3837.7 5094.6 5163.1 5266.6 5280.8 4618.3 5119.9 5193.3 5297.7 5366.8 4980.7 5244.3 5248.9 5257.3 5263.6 5026.3 5082.4 5150.6 5167.5 5234.9 5137.8 5140.9 5165.0 5175.8 5224.7 5575.6 5654.6 5663.1 5699.1 5723.0 4890.7 6026.0 6031.7 6125.3 6216.0 6148.8 6186.6 6222.3 6229.1 6602.1 4598.6 4751.6 5324.2 5877.1 5950.0 5405.3 5443.3 5656.2 6253.0 6254.7 5798.8 5842.0 5872.6 5959.1 5959.4 5429.1 6098.8 6121.9 6160.7 6281.9 5926.0 5961.6 5964.3 6056.9 6082.2 6184.2 6247.4 6257.1 6258.6 6273.5 4951.5 4956.2 4975.9 4984.2 4993.0 4300.4 4311.7 4347.9 4409.1 4426.3 4386.4 5733.9 5735.9 5784.2 5856.4 6269.6 6273.5 6287.8 6292.7 6309.8 5785.1 5802.3 5805.3 5835.3 5868.5 5206.9 5239.8 5401.7 5422.1 5426.9 6251.5 6270.5 6276.3 6281.2 6282.2 6137.6 6259.7 6264.5 6284.3 6288.5 5935.7 6139.1 6167.7 6197.1 6288.6 5054.7 6216.0 6218.5 6229.8 6262.0 6221.1 6267.3 6272.1 6284.3 6294.6 5738.5 5850.2 6153.4 6161.4 6182.4 5276.9 5633.5 5921.8 5958.8 6041.7 5697.9 5947.3 5954.1 6003.6 6010.6 5841.5 5864.1 5879.9 6013.5 6151.8 5934.8 5991.3 6006.5 6026.4 6047.1 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:62853.77 Median:35930.14 stddev:133335.27 (371.1%) stddev-sans-first:133626.98 18:11:00 INFO - PROCESS | 1656 | Values: 443.4 461.5 467.7 490.2 545.3 36307.8 39160.0 39499.0 39525.0 39542.8 40750.8 41339.0 41339.8 41484.2 41525.0 8179.0 9202.2 9593.1 10973.9 11418.4 11056.7 11132.2 11138.3 11153.4 11163.3 11200.1 11200.9 11224.0 11254.0 11263.4 11134.2 11173.8 11181.6 11187.0 11193.4 9235.0 11149.1 11189.3 11222.7 11230.0 36481.6 36633.0 36946.6 37024.0 37058.0 3725.0 3727.4 3755.5 3760.9 3889.8 26845.9 27259.7 27761.6 27788.4 27858.2 3040.6 3043.9 3211.8 3228.7 3233.5 466430.0 467648.0 468436.0 469274.0 469550.0 45121.8 46021.8 49151.0 54710.2 55723.4 57680.0 57789.7 57821.7 58105.1 58176.4 11916.4 15018.5 15049.8 15080.5 15087.9 11380.2 11392.8 11495.2 11520.8 11534.3 17074.4 17075.0 17109.7 17141.0 17154.7 6545.7 7346.6 7819.9 7857.2 7867.5 639.7 662.5 730.3 793.7 821.7 25723.9 28398.9 29413.4 29630.6 30130.0 954.8 1018.6 1061.7 1077.8 1170.6 13163.0 14545.9 15186.1 15198.6 15404.2 578650.0 588332.0 588651.0 591053.0 591226.0 57196.4 57281.4 57865.4 58025.7 58085.7 1156.2 1269.7 1328.5 1329.1 1378.8 27923.0 28460.0 29063.4 29174.6 29382.6 47855.0 48004.8 48024.3 48025.0 48140.3 428398.0 431090.0 432268.0 432564.0 433026.0 21883.1 22474.3 24953.1 25289.2 25830.7 45028.9 45190.8 45403.3 46542.8 46714.0 28083.6 35843.0 36336.3 37111.0 37510.2 30428.8 31726.0 36017.3 37003.6 37207.9 35156.5 35318.0 36098.5 36338.0 36539.5 30406.8 32206.0 32207.0 32312.5 32320.5 19122.4 22475.9 23926.4 24031.4 24375.3 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:36728.96 Median:26223.50 stddev:109429.23 (417.3%) stddev-sans-first:109702.19 18:11:00 INFO - PROCESS | 1656 | Values: 389.3 390.4 395.7 398.7 431.3 13216.9 14724.6 17633.9 20580.1 21265.6 20308.7 20354.8 21286.1 21502.5 21751.8 5815.0 5836.2 6005.2 6078.8 6081.1 5804.9 5957.5 6059.8 6063.2 6063.5 5101.5 5956.3 5965.5 6024.0 6034.2 5715.7 5920.6 5924.0 5981.8 5993.7 5255.0 5256.7 5257.3 5266.3 5274.2 25412.5 26123.0 26324.0 26334.9 26674.5 2186.6 2375.2 2666.2 2710.1 2734.5 11641.9 15509.8 15587.5 16057.6 16357.3 1470.8 1813.4 1830.5 1859.2 1891.0 98675.5 101315.5 102850.0 123540.0 129941.7 9453.8 10030.2 11901.2 12080.5 12088.5 15417.7 15465.0 15469.5 15498.5 15510.6 7264.9 8073.9 8138.0 8427.6 8544.9 6595.9 8011.2 8048.8 8078.8 8203.2 4776.9 4800.7 4801.7 4830.7 4916.4 1684.6 1853.7 1982.5 2077.2 2077.4 923.8 998.9 1018.8 1022.9 1045.2 20300.7 20726.9 20813.2 20822.7 20875.5 846.9 985.5 988.4 1018.4 1023.5 10202.2 10795.5 10803.4 10806.2 10833.5 553350.0 671375.0 677779.0 683245.0 696808.0 99952.0 133256.3 134198.2 135936.0 137069.3 981.6 1034.3 1054.3 1057.1 1058.9 19618.1 19693.9 19892.0 19909.2 19950.0 45964.8 55459.4 56904.7 58202.0 58668.7 82412.8 85396.6 85511.0 86711.0 87503.0 6774.8 8089.2 8274.7 8384.0 8402.7 10284.7 12347.9 12530.7 12819.4 12895.5 7787.8 10405.2 10412.6 10825.7 10833.8 10303.1 10415.2 10484.0 10906.8 10922.7 8940.8 10252.6 10788.9 11127.7 11199.8 7251.9 7310.9 7521.1 7841.1 8508.9 8085.8 9663.9 10387.4 10935.0 11182.9 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5392.26 Median:5181.51 stddev:2948.13 (56.9%) stddev-sans-first:2940.38 18:11:00 INFO - PROCESS | 1656 | Values: 1303.3 1455.2 1475.2 1480.7 1481.8 6896.0 8183.4 8293.6 8541.1 8625.7 7651.8 8457.1 8471.5 8568.7 8605.4 1723.5 2088.7 2213.7 2301.3 2314.3 2288.6 2322.3 2327.8 2328.6 2331.6 2276.7 2279.6 2312.0 2312.4 2314.7 2206.7 2278.5 2299.8 2306.0 2309.2 1905.9 1954.0 2031.4 2296.3 2330.7 8284.7 8496.3 8512.0 8602.1 8637.2 5126.2 5267.1 5301.0 5309.4 5311.0 2845.9 2873.5 3415.1 3433.0 3522.5 1188.5 1196.1 1213.6 1218.7 1244.2 10828.1 10866.9 10905.7 10967.2 11108.2 4223.5 4497.7 4569.8 4576.9 4596.3 8762.3 8807.2 8828.8 8833.6 8863.2 5144.7 5154.4 5155.8 5157.6 5163.9 4576.3 4637.4 4763.3 4785.6 4837.5 3683.4 3694.3 3762.6 3793.9 3804.7 1159.6 1170.1 1171.2 1178.7 1181.8 836.3 869.3 872.4 910.5 930.2 5621.8 5662.9 5710.1 5834.5 6034.7 942.8 949.4 952.5 959.3 985.3 3673.2 3674.3 3784.9 3858.4 3886.7 9707.6 10062.3 10063.3 10064.2 10068.0 9945.9 9967.6 9993.9 10000.4 10004.4 7397.9 7768.0 7779.6 7893.7 7898.0 5638.9 5639.2 5816.2 5837.7 5873.4 8880.9 8963.9 8995.3 9156.7 9221.5 7684.8 7772.3 9253.4 9324.8 9500.2 7177.9 7368.0 7375.1 7382.7 7394.7 7813.9 7936.3 7937.8 7939.0 7971.3 7105.3 7140.0 7266.3 7279.2 7296.2 5734.9 6737.5 6754.7 6824.8 6879.9 5482.6 6397.3 6473.7 6480.8 6484.4 4820.2 4861.6 4991.8 5046.1 5178.6 4477.0 4822.7 5038.3 5074.2 5184.5 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4489.60 Median:4914.15 stddev:1370.57 (27.9%) stddev-sans-first:1338.49 18:11:00 INFO - PROCESS | 1656 | Values: 336.2 363.1 377.0 379.9 385.8 4890.1 5109.8 5183.3 5347.9 5472.6 4397.7 4534.9 5245.3 5258.9 5271.9 3838.5 3944.5 4188.3 4281.2 4293.2 3371.7 4238.4 4250.3 4269.8 4270.6 3949.0 4037.9 4068.9 4081.7 4089.3 3568.6 3748.4 4091.9 4093.8 4113.9 3047.0 3228.3 3535.9 3536.0 3581.7 4248.7 4935.9 5326.9 5334.3 5405.5 3820.4 4013.0 4750.2 4759.7 4778.6 5509.9 5532.4 5747.7 5759.7 5794.4 3603.9 3858.4 3901.0 3929.6 3933.7 5355.8 5797.5 5955.4 5999.5 6047.3 3578.7 3893.4 3894.3 3914.0 3917.7 3967.2 3976.4 3978.5 3988.8 3989.7 4532.2 4673.2 4717.3 4725.5 4741.0 4809.4 5589.8 5676.0 5734.7 5765.5 1094.7 1138.6 1270.5 1284.2 1292.9 722.3 804.4 822.5 875.7 895.0 4685.3 4740.9 4755.1 5122.3 5191.3 5079.8 5316.3 5554.9 5689.8 5765.5 4293.6 4557.1 4616.6 4768.7 4905.9 2638.4 2905.5 2973.6 2985.2 3026.8 5079.9 5679.9 5726.4 5924.5 5931.7 5255.7 5454.0 5511.4 5778.5 5827.8 5415.8 5537.8 5551.3 5816.0 5941.5 5218.3 5539.8 5709.6 5763.2 5779.4 4940.9 5408.9 5441.8 5555.8 5738.4 5288.3 5637.9 5807.7 5808.0 6052.8 3320.0 3768.4 3828.2 3910.1 3912.3 4922.4 5099.8 5311.4 5740.5 5927.6 4583.8 5199.4 5369.4 5664.4 5730.9 4875.6 4952.6 5020.5 5530.6 5689.1 4815.2 5036.8 5122.5 5327.7 5571.2 4577.4 4696.6 5533.5 5734.9 5744.0 4729.4 4771.7 4852.3 5325.7 5598.8 18:11:00 INFO - PROCESS | 1656 | 18:11:00 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:18945.04 Median:4794.58 stddev:26449.58 (551.7%) stddev-sans-first:26488.27 18:11:00 INFO - PROCESS | 1656 | Values: 693.9 705.3 773.0 797.6 814.6 14406.3 15066.5 15122.6 15131.3 15315.2 11953.4 13746.3 14010.6 14172.5 14219.3 895.2 905.3 909.0 910.1 912.0 832.2 874.6 894.5 900.6 906.2 951.8 952.1 954.2 956.8 957.9 948.7 951.5 952.0 953.3 954.5 597.0 691.5 698.5 703.2 710.2 15495.5 15666.9 15669.0 15677.0 15853.5 13567.3 13650.4 13773.4 13807.3 13856.4 11424.6 11516.3 11590.2 11650.4 11850.1 3155.8 3552.2 3659.6 3757.1 3797.5 78813.6 79405.2 98943.0 99819.0 100187.0 10348.6 10616.5 10679.5 10749.9 10803.4 21984.5 22001.1 22269.3 22330.9 22441.3 7282.9 7347.5 7365.6 7432.2 7515.7 3863.7 3874.4 3892.0 3923.9 3942.4 3600.0 3668.4 3695.9 3699.3 3702.7 661.3 667.8 669.8 685.5 691.1 555.0 597.3 609.7 624.2 635.6 8011.3 8359.5 9377.3 9625.3 9885.0 524.8 659.3 672.5 684.8 691.9 4378.9 4774.3 4814.8 4839.0 4879.6 71048.4 96055.0 97188.5 99124.5 100156.5 69919.7 70382.6 72965.0 87308.0 90052.5 68906.0 70087.9 71585.6 71624.6 73150.0 9691.4 10189.2 10414.9 10422.2 10459.7 46849.3 47523.8 48494.8 48500.8 48904.5 64785.0 65421.7 65575.0 66295.7 67824.0 12241.6 12311.9 12394.0 12481.6 12934.9 12406.7 13603.5 14185.0 14335.4 14533.7 11565.8 11711.0 11874.2 11879.8 12260.6 10935.4 12857.7 12879.2 12972.2 13054.5 10021.7 12625.3 12652.9 13067.7 13133.5 10406.0 10551.4 10587.2 10633.0 11395.0 6247.8 8552.7 9127.5 9781.1 10451.4 18:11:00 INFO - PROCESS | 1656 | -------- Summary: end -------- 18:11:00 INFO - PROCESS | 1656 | 18:11:01 INFO - TEST-INFO | 1656: exit 0 18:11:01 INFO - TEST-OK | dromaeo_css | took 1116010ms 18:11:01 INFO - TEST-START | kraken 18:11:01 INFO - operating with platform_type : mac_ 18:11:01 INFO - Initialising browser for kraken test... 18:11:01 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpxR9_of/profile http://localhost:49245/getInfo.html) 18:11:03 INFO - PROCESS | 1663 | 2017-02-26 18:11:03.919 plugin-container[1664:15985] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 18:11:03 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:11:03 INFO - PROCESS | 1663 | 2017-02-26 18:11:03.924 plugin-container[1664:15985] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 18:11:03 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:11:03 INFO - PROCESS | 1663 | 2017-02-26 18:11:03.924 plugin-container[1664:15985] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:11:03 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 18:11:03 INFO - PROCESS | 1663 | colorDepth:24 18:11:03 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 18:11:03 INFO - PROCESS | 1663 | __metrics 18:11:04 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 18:11:04 INFO - TEST-INFO | 1663: exit 0 18:11:04 INFO - Browser initialized. 18:11:04 INFO - Running cycle 1/1 for kraken test... 18:11:04 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpxR9_of/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:11:07 INFO - PROCESS | 1665 | 2017-02-26 18:11:07.395 plugin-container[1666:16109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 18:11:07 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:11:07 INFO - PROCESS | 1665 | 2017-02-26 18:11:07.395 plugin-container[1666:16109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 18:11:07 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:11:07 INFO - PROCESS | 1665 | 2017-02-26 18:11:07.395 plugin-container[1666:16109] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:11:17 INFO - PROCESS | 1665 | RSS: Main: 227475456 18:11:17 INFO - PROCESS | 1665 | 18:11:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 18:11:21 INFO - PROCESS | 1665 | RSS: Main: 241225728 18:11:21 INFO - PROCESS | 1665 | 18:11:24 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 18:11:24 INFO - PROCESS | 1665 | RSS: Main: 249053184 18:11:24 INFO - PROCESS | 1665 | 18:11:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 18:11:28 INFO - PROCESS | 1665 | RSS: Main: 251756544 18:11:28 INFO - PROCESS | 1665 | 18:11:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 18:11:31 INFO - PROCESS | 1665 | RSS: Main: 252887040 18:11:31 INFO - PROCESS | 1665 | 18:11:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:11:34 INFO - PROCESS | 1665 | RSS: Main: 254541824 18:11:34 INFO - PROCESS | 1665 | 18:11:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 18:11:37 INFO - PROCESS | 1665 | RSS: Main: 260415488 18:11:37 INFO - PROCESS | 1665 | 18:11:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 18:11:41 INFO - PROCESS | 1665 | RSS: Main: 259788800 18:11:41 INFO - PROCESS | 1665 | 18:11:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 18:11:45 INFO - PROCESS | 1665 | RSS: Main: 259219456 18:11:45 INFO - PROCESS | 1665 | 18:11:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:11:47 INFO - PROCESS | 1665 | RSS: Main: 259137536 18:11:47 INFO - PROCESS | 1665 | 18:11:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:11:50 INFO - PROCESS | 1665 | RSS: Main: 258887680 18:11:50 INFO - PROCESS | 1665 | 18:11:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:11:53 INFO - PROCESS | 1665 | RSS: Main: 258998272 18:11:53 INFO - PROCESS | 1665 | 18:11:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:11:57 INFO - PROCESS | 1665 | RSS: Main: 259366912 18:11:57 INFO - PROCESS | 1665 | 18:12:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:12:00 INFO - PROCESS | 1665 | RSS: Main: 259063808 18:12:00 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 18:12:02 INFO - PROCESS | 1665 | RSS: Main: 262471680 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | __start_tp_report 18:12:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 18:12:02 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 18:12:02 INFO - PROCESS | 1665 | |i|pagename|runs| 18:12:02 INFO - PROCESS | 1665 | |0;ai-astar;109;119;120;161;120;174;125;131;173;160 18:12:02 INFO - PROCESS | 1665 | |1;audio-beat-detection;139;114;114;170;148;145;115;114;137;115 18:12:02 INFO - PROCESS | 1665 | |2;audio-dft;212;207;269;250;282;261;194;193;201;192 18:12:02 INFO - PROCESS | 1665 | |3;audio-fft;81;62;63;86;58;58;76;53;72;81 18:12:02 INFO - PROCESS | 1665 | |4;audio-oscillator;87;99;119;105;75;72;84;71;68;82 18:12:02 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;136;119;88;91;88;78;137;136;88;90 18:12:02 INFO - PROCESS | 1665 | |6;imaging-darkroom;132;100;147;108;155;156;96;95;96;107 18:12:02 INFO - PROCESS | 1665 | |7;imaging-desaturate;116;113;91;93;92;93;140;92;92;100 18:12:02 INFO - PROCESS | 1665 | |8;json-parse-financial;64;63;63;63;64;62;60;55;52;54 18:12:02 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;67;73;74;97;70;67;68;69;70;78 18:12:02 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;88;88;90;91;88;92;115;93;93;113 18:12:02 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;163;153;157;168;159;160;159;159;217;160 18:12:02 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;178;176;178;178;184;216;177;179;177;181 18:12:02 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;69;69;69;70;71;69;69;69;71;67 18:12:02 INFO - PROCESS | 1665 | __end_tp_report 18:12:02 INFO - PROCESS | 1665 | __start_cc_report 18:12:02 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1012 18:12:02 INFO - PROCESS | 1665 | __end_cc_report 18:12:02 INFO - PROCESS | 1665 | __startTimestamp1488161522819__endTimestamp 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | ------- Summary: start ------- 18:12:02 INFO - PROCESS | 1665 | Number of tests: 14 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:139.20 Median:145.50 stddev:24.92 (17.1%) stddev-sans-first:23.91 18:12:02 INFO - PROCESS | 1665 | Values: 109.0 119.0 120.0 161.0 120.0 174.0 125.0 131.0 173.0 160.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:138.00 stddev:19.68 (14.3%) stddev-sans-first:20.66 18:12:02 INFO - PROCESS | 1665 | Values: 139.0 114.0 114.0 170.0 148.0 145.0 115.0 114.0 137.0 115.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:226.10 Median:231.00 stddev:35.33 (15.3%) stddev-sans-first:37.11 18:12:02 INFO - PROCESS | 1665 | Values: 212.0 207.0 269.0 250.0 282.0 261.0 194.0 193.0 201.0 192.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:69.00 Median:74.00 stddev:11.63 (15.7%) stddev-sans-first:11.50 18:12:02 INFO - PROCESS | 1665 | Values: 81.0 62.0 63.0 86.0 58.0 58.0 76.0 53.0 72.0 81.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:78.50 stddev:16.69 (21.3%) stddev-sans-first:17.69 18:12:02 INFO - PROCESS | 1665 | Values: 87.0 99.0 119.0 105.0 75.0 72.0 84.0 71.0 68.0 82.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:105.10 Median:88.00 stddev:23.94 (27.2%) stddev-sans-first:22.63 18:12:02 INFO - PROCESS | 1665 | Values: 136.0 119.0 88.0 91.0 88.0 78.0 137.0 136.0 88.0 90.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:119.20 Median:155.50 stddev:25.56 (16.4%) stddev-sans-first:26.68 18:12:02 INFO - PROCESS | 1665 | Values: 132.0 100.0 147.0 108.0 155.0 156.0 96.0 95.0 96.0 107.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:92.00 stddev:16.15 (17.6%) stddev-sans-first:16.34 18:12:02 INFO - PROCESS | 1665 | Values: 116.0 113.0 91.0 93.0 92.0 93.0 140.0 92.0 92.0 100.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:63.00 stddev:4.57 (7.3%) stddev-sans-first:4.61 18:12:02 INFO - PROCESS | 1665 | Values: 64.0 63.0 63.0 63.0 64.0 62.0 60.0 55.0 52.0 54.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:71.50 stddev:9.02 (12.6%) stddev-sans-first:9.27 18:12:02 INFO - PROCESS | 1665 | Values: 67.0 73.0 74.0 97.0 70.0 67.0 68.0 69.0 70.0 78.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:90.50 stddev:10.16 (11.2%) stddev-sans-first:10.45 18:12:02 INFO - PROCESS | 1665 | Values: 88.0 88.0 90.0 91.0 88.0 92.0 115.0 93.0 93.0 113.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:165.50 Median:160.00 stddev:18.50 (11.6%) stddev-sans-first:19.60 18:12:02 INFO - PROCESS | 1665 | Values: 163.0 153.0 157.0 168.0 159.0 160.0 159.0 159.0 217.0 160.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:178.50 stddev:12.03 (6.7%) stddev-sans-first:12.65 18:12:02 INFO - PROCESS | 1665 | Values: 178.0 176.0 178.0 178.0 184.0 216.0 177.0 179.0 177.0 181.0 18:12:02 INFO - PROCESS | 1665 | 18:12:02 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 18:12:02 INFO - PROCESS | 1665 | Values: 69.0 69.0 69.0 70.0 71.0 69.0 69.0 69.0 71.0 67.0 18:12:02 INFO - PROCESS | 1665 | -------- Summary: end -------- 18:12:02 INFO - PROCESS | 1665 | 18:12:03 INFO - TEST-INFO | 1665: exit 0 18:12:03 INFO - TEST-OK | kraken | took 62721ms 18:12:03 INFO - SUITE-END | took 1178s 18:12:03 INFO - Completed test suite (00:19:38) 18:12:03 INFO - javascript benchmark 18:12:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7138.285375035789, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.383694016607, 3337.46600543913, 3374.3926093303194, 3387.2040070583416, 3412.368711788319, 5461.508464238218, 5574.163875418689, 5628.718564071801, 5686.289213848264, 5694.4590263924965, 4673.345731597579, 5500.2299678045, 5642.1254705520605, 5695.288088988877, 5762.596618236715, 3837.6546110850018, 5094.6433749637545, 5163.070647283506, 5266.605004624649, 5280.788768449257, 4618.330342100394, 5119.897602047957, 5193.298904647881, 5297.682139071649, 5366.838994830142, 4980.651354405193, 5244.344456942882, 5248.947510524884, 5257.263983042358, 5263.552598029172, 5026.271190677331, 5082.364704411949, 5150.61370397218, 5167.509086636764, 5234.8691282717855, 5137.7944882204665, 5140.920406714595, 5165.018646457161, 5175.844724658266, 5224.712640804754, 5575.553955683536, 5654.6041777075625, 5663.122216056518, 5699.088145896637, 5723.0, 4890.73100979446, 6025.975584150705, 6031.728572214217, 6125.264968203844, 6216.03651434024, 6148.754049838001, 6186.628802271878, 6222.3466536013975, 6229.127922090911, 6602.141721576212, 4598.632109431237, 4751.59611433032, 5324.227986941872, 5877.089051197028, 5950.018246989242, 5405.270288511079, 5443.292371991616, 5656.151577263361, 6252.999520076766, 6254.6872656366995, 5798.82603521895, 5841.970790146022, 5872.58891877573, 5959.106134079922, 5959.433853783884, 5429.131338985743, 6098.786542470993, 6121.940772763084, 6160.722767475474, 6281.874362512815, 5926.0, 5961.642301461926, 5964.284285885721, 6056.878862422727, 6082.1789058477425, 6184.165137706352, 6247.437730604177, 6257.061440783918, 6258.593191442542, 6273.466755325757, 4951.529604687549, 4956.207006878882, 4975.900481990412, 4984.2025275955675, 4993.0, 4300.376445415397, 4311.698181127354, 4347.913041739147, 4409.11817636474, 4426.313921342197, 4386.363977223285, 5733.8566535836935, 5735.942640573624, 5784.1902133701315, 5856.4143585641095, 6269.592476489013, 6273.466755325757, 6287.811365659038, 6292.685365731695, 6309.810705678745, 5785.1322301654245, 5802.303723553201, 5805.332386775491, 5835.270591176103, 5868.471837534557, 5206.8958620828125, 5239.790408383698, 5401.675899446046, 5422.078246698071, 5426.891462170814, 6251.468625166821, 6270.529710271839, 6276.309605943343, 6281.246250449975, 6282.246130464374, 6137.570370074052, 6259.749610015549, 6264.467520260829, 6284.277308109626, 6288.528360373082, 5935.673537955454, 6139.093519790236, 6167.660778657217, 6197.101420294122, 6288.591241569284, 5054.74726263678, 6215.96892015537, 6218.502519798315, 6229.813105606839, 6262.029385445263, 6221.06683997404, 6267.2792628848265, 6272.059191121186, 6284.277308109443, 6294.590851594632, 5738.512226460798, 5850.23946886902, 6153.384661533811, 6161.383861613803, 6182.412670796267, 5276.920846187233, 5633.492985631313, 5921.822345329647, 5958.76164953397, 6041.697915104315, 5697.914531282117, 5947.256592925884, 5954.07711804671, 6003.6397816131175, 6010.579259451796, 5841.50347220491, 5864.149698293809, 5879.853003674943, 6013.5189184865985, 6151.84620384494, 5934.821955341346, 5991.2510936132985, 6006.549508786771, 6026.367231440636, 6047.0627052806885], "value": 5593.406696062588, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.4213351576193, 461.503883325425, 467.69833457419963, 490.20831357357866, 545.3319683387853, 36307.81846090769, 39160.0, 39499.0, 39525.0, 39542.802285988604, 40750.79624601873, 41338.96652583687, 41339.75980720576, 41484.17031659365, 41525.0, 8178.959105204465, 9202.217811486033, 9593.136617704406, 10973.890261097407, 11418.371989540572, 11056.668299951014, 11132.220744547885, 11138.27601205919, 11153.386563738992, 11163.265000074953, 11200.103991680688, 11200.887991120066, 11224.0, 11253.987141157295, 11263.4368281586, 11134.220604557684, 11173.832392514038, 11181.552737890475, 11186.993170614644, 11193.44032798365, 9235.0, 11149.052330551913, 11189.272697274651, 11222.719432014183, 11229.989300962952, 36481.63518364809, 36633.0, 36946.63053369486, 37024.0, 37058.0, 3724.952520739638, 3727.403615421547, 3755.530558680165, 3760.8871733848027, 3889.8055097245306, 26845.865770671022, 27259.727402725915, 27761.583576246372, 27788.444231115467, 27858.16425507238, 3040.5705762069, 3043.863026163806, 3211.791233569834, 3228.7094161525342, 3233.4988076848126, 466430.0, 467648.0, 468436.0, 469274.0, 469549.956750649, 45121.77439112849, 46021.76989115033, 49151.0, 54710.17934730982, 55723.44276557264, 57680.0, 57789.71105144447, 57821.71089144528, 58105.128423073686, 58176.418235817946, 11916.404179790976, 15018.549443516711, 15049.774253386207, 15080.54758357251, 15087.924560377129, 11380.203385762918, 11392.829107563393, 11495.195336326544, 11520.827187592025, 11534.250273732348, 17074.40239591633, 17075.0, 17109.743353849703, 17141.0, 17154.656906861794, 6545.705443255065, 7346.603124907132, 7819.882701759479, 7857.174996625272, 7867.488613240121, 639.7185238495048, 662.5329142954206, 730.3062091013559, 793.6626933553217, 821.7000794709888, 25723.871380642977, 28398.888200124176, 29413.411731765675, 29630.555541666894, 30130.0, 954.8351011765635, 1018.6180182431589, 1061.713337398898, 1077.849101125843, 1170.6488053583892, 13163.0, 14545.92727036358, 15186.088834669956, 15198.620034499005, 15404.229788510529, 578650.0, 588332.0, 588651.0, 591053.0447347822, 591226.0, 57196.42803571911, 57281.42718572761, 57865.421345786846, 58025.70987145037, 58085.70957145187, 1156.1964434717959, 1269.6508460173527, 1328.4885319152197, 1329.0829327763813, 1378.7656098463085, 27923.0, 28460.003899863405, 29063.418731625672, 29174.601285408506, 29382.559261610666, 47855.0, 48004.75997619989, 48024.27963580619, 48025.03949921052, 48140.27789583229, 428398.0, 431090.0, 432268.0, 432564.0, 433026.0, 21883.12467501282, 22474.32577022692, 24953.126640567494, 25289.241322760347, 25830.741692582833, 45028.87427814331, 45190.77404612956, 45403.318950215114, 46542.767286163355, 46714.0, 28083.578746319232, 35843.0, 36336.27327453489, 37111.0, 37510.24979500449, 30428.84785576058, 31726.0, 36017.279654406244, 37003.62996370002, 37207.88376348714, 35156.47265290972, 35318.0, 36098.45852312165, 36338.0, 36539.451908220864, 30406.84796576003, 32206.0, 32207.0, 32312.515312269865, 32320.515192272604, 19122.426327210207, 22475.887620561793, 23926.401839954142, 24031.399215019763, 24375.26874193777], "value": 19044.980171497376, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.33229511387964, 390.4475167637793, 395.6597326299382, 398.69101446379045, 431.3012919071105, 13216.93391533041, 14724.558263252093, 17633.853799503024, 20580.073896674647, 21265.57468850622, 20308.69536956947, 20354.796452035513, 21286.148554057818, 21502.462438439048, 21751.782482175175, 5814.97092514539, 5836.15375770514, 6005.159277701126, 6078.817635470921, 6081.148639190517, 5804.912926306109, 5957.493613042897, 6059.848503787396, 6063.242094738158, 6063.514918806485, 5101.464346243628, 5956.255468066492, 5965.522758179335, 6024.0, 6034.185384973039, 5715.742791574389, 5920.585559010892, 5924.0, 5981.790637327695, 5993.670348130851, 5255.0, 5256.684598924076, 5257.290265814105, 5266.315379000744, 5274.156135018378, 25412.491750164892, 26123.0, 26324.0, 26334.946602135893, 26674.46651066987, 2186.5517568898495, 2375.2280508834524, 2666.226794229671, 2710.132757517595, 2734.53512902807, 11641.883581164164, 15509.844901550952, 15587.519185677342, 16057.598560036093, 16357.345706171738, 1470.8087948566724, 1813.3743858368841, 1830.5172810023923, 1859.1540848913846, 1890.9527261818428, 98675.50662246715, 101315.49342253315, 102849.99999999926, 123540.0, 129941.70058299485, 9453.763655908657, 10030.247731420173, 11901.166918315635, 12080.51677932873, 12088.516459341703, 15417.691646167013, 15465.0, 15469.458568950033, 15498.5350439487, 15510.612234693997, 7264.854702906018, 8073.9192608074345, 8137.95931020353, 8427.578621068922, 8544.871826922601, 6595.868082638321, 8011.238932301422, 8048.83902321962, 8078.839397710184, 8203.179682031749, 4776.904461910812, 4800.66395352322, 4801.663883528189, 4830.686005409637, 4916.385451818523, 1684.6041180322613, 1853.7209325565316, 1982.5440148765576, 2077.241806740529, 2077.4390914453015, 923.8290916180528, 998.8651532043226, 1018.7911478146962, 1022.9488525573692, 1045.2056437107854, 20300.695489567668, 20726.860022698595, 20813.1674733012, 20822.687659685118, 20875.478113047295, 846.86873534601, 985.5466485416645, 988.4069558264983, 1018.4245901065874, 1023.518946095334, 10202.183825293958, 10795.46022698862, 10803.405812680221, 10806.189535784848, 10833.512491937881, 553350.0, 671375.0, 677779.0, 683245.0, 696808.0, 99952.00047999427, 133256.33371833077, 134198.16036792682, 135936.0, 137069.3146534281, 981.5582987655583, 1034.3121823987115, 1054.3463052907246, 1057.0856209378985, 1058.9205809564314, 19618.117184726438, 19693.91683457366, 19892.0, 19909.203631855144, 19950.0, 45964.77017614891, 55459.44540554704, 56904.71547642401, 58202.0, 58668.70665646644, 82412.76380854173, 85396.57301713452, 85511.0, 86711.0, 87503.0, 6774.830629234309, 8089.191080891864, 8274.710385136492, 8383.95808020956, 8402.705905293285, 10284.691459255935, 12347.938260308641, 12530.686732831753, 12819.42312595954, 12895.548655797002, 7787.766367008999, 10405.219608529542, 10412.583496660049, 10825.729356766144, 10833.837492437462, 10303.124234440156, 10415.1667866572, 10484.0, 10906.836397453886, 10922.672319830417, 8940.776480587778, 10252.64115755945, 10788.892111079103, 11127.720312164205, 11199.832002519806, 7251.927480725336, 7310.926890731024, 7521.05986751656, 7841.098273698598, 8508.914910850812, 8085.797855053671, 9663.855042174513, 10387.428691422045, 10935.0, 11182.881711828752], "value": 9652.761823144492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.3352989975112, 1455.1996401978915, 1475.1739026145367, 1480.6594483268848, 1481.8221813382395, 6895.9655201724045, 8183.427160098796, 8293.58532073397, 8541.060483346826, 8625.654973801056, 7651.846963060735, 8457.112003239663, 8471.534065626389, 8568.742937711862, 8605.39762216645, 1723.534645645675, 2088.7284652995168, 2213.6607352551714, 2301.2750983440246, 2314.3057082875152, 2288.5766133265397, 2322.326525307667, 2327.8254130940163, 2328.569214695287, 2331.62693968965, 2276.658501224821, 2279.623862062758, 2311.9537609247805, 2312.364099872532, 2314.6990891184205, 2206.735191776997, 2278.5329007553496, 2299.8160147188187, 2305.988470057656, 2309.2379514760087, 1905.8951757653324, 1953.990230048855, 2031.3702752146883, 2296.3455415206754, 2330.7436182019965, 8284.7100351488, 8496.277816385615, 8511.97856257253, 8602.09677983809, 8637.179467950535, 5126.179811230185, 5267.130923397634, 5300.966311569246, 5309.41596424393, 5311.017461769585, 2845.900393486218, 2873.4971380008624, 3415.112070861594, 3433.0, 3522.4716292556063, 1188.4533114767216, 1196.096006373869, 1213.5691829400523, 1218.7257866979946, 1244.2347956007081, 10828.133749300037, 10866.891331086666, 10905.672829815117, 10967.232293739442, 11108.222424430292, 4223.535411104776, 4497.673186410017, 4569.771511424416, 4576.8626941191815, 4596.264597664424, 8762.299016078698, 8807.163319484767, 8828.823423531494, 8833.64665413389, 8863.202311791969, 5144.742762861842, 5154.381474223117, 5155.819546315861, 5157.561607263425, 5163.896722065538, 4576.26779715244, 4637.420322459692, 4763.285507173951, 4785.569298763128, 4837.516248375205, 3683.37382644951, 3694.261147770457, 3762.567304759933, 3793.867214647474, 3804.6575808177395, 1159.6231224851956, 1170.1107158559387, 1171.1743221029153, 1178.6581891251442, 1181.8227265910011, 836.2557323981662, 869.3002133282641, 872.376250980552, 910.4719262827545, 930.181440332503, 5621.775128994795, 5662.886742265214, 5710.114932185518, 5834.474897259182, 6034.668093254913, 942.7972985808084, 949.4208532794989, 952.4951775558965, 959.318883592643, 985.3152059318704, 3673.1918977824835, 3674.3202507536193, 3784.8675296364495, 3858.4212368145004, 3886.7473614214987, 9707.563159657833, 10062.295639305325, 10063.345882517759, 10064.245181611408, 10068.0, 9945.900540994642, 9967.55146018431, 9993.900060999296, 10000.449975251431, 10004.449755263531, 7397.889031664529, 7768.028996375566, 7779.572123533147, 7893.684252629945, 7897.960510197412, 5638.915416268677, 5639.154126881001, 5816.214811000461, 5837.737301821513, 5873.3832947541605, 8880.86678699833, 8963.865542017005, 8995.280377569648, 9156.679516217167, 9221.538923053955, 7684.769456916078, 7772.339351154989, 9253.444793312423, 9324.766880828034, 9500.239980801382, 7177.856442871218, 7368.0, 7375.11498620169, 7382.6677799498075, 7394.704211831681, 7813.882791758015, 7936.325412340016, 7937.801554960941, 7938.960305198437, 7971.322437592869, 7105.32499412555, 7140.000399943908, 7266.273372662691, 7279.22139468509, 7296.233895440902, 5734.913976290276, 6737.528373013842, 6754.696038678173, 6824.7952561423235, 6879.933610290413, 5482.5888058394985, 6397.328280530477, 6473.741050357933, 6480.837979050373, 6484.383983521558, 4820.228763397839, 4861.611071114373, 4991.750412479434, 5046.116929537389, 5178.559822415137, 4476.955230447654, 4822.734749588806, 5038.269450929522, 5074.162763144188, 5184.455632158569], "value": 4374.103753995135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.20319841974515, 363.09226932668327, 376.95476542814856, 379.8955287295995, 385.8437332880184, 4890.070886531556, 5109.821156259532, 5183.326167598207, 5347.893042139155, 5472.644278121924, 4397.670174736892, 4534.886627834306, 5245.291885595443, 5258.894822103565, 5271.910942860992, 3838.5201849768778, 3944.4674968879194, 4188.329867221245, 4281.207976524353, 4293.227219100576, 3371.685042833297, 4238.406623072772, 4250.27745283301, 4269.765162916038, 4270.636995855356, 3948.9917733500374, 4037.8788636340955, 4068.87793366198, 4081.7346872453195, 4089.3457046872504, 3568.6074531801482, 3748.400255959047, 4091.9181616367655, 4093.789353748972, 4113.91772164555, 3046.9847650761826, 3228.322052369005, 3535.9116022099397, 3536.0452677776857, 3581.7313701472235, 4248.660107191448, 4935.851924442272, 5326.946730532683, 5334.253204551366, 5405.5135037846785, 3820.3505404081375, 4012.976690943791, 4750.216214324632, 4759.690620109682, 4778.5699287064335, 5509.944900550983, 5532.3914369419335, 5747.712614369265, 5759.712014399305, 5794.362620111784, 3603.94594081089, 3858.440526123696, 3901.0, 3929.607039296076, 3933.665638420709, 5355.785768569292, 5797.507211886995, 5955.374685657986, 5999.460048595647, 6047.274327080734, 3578.6600272974024, 3893.4159876018257, 3894.279558281727, 3914.0, 3917.6865850731874, 3967.206558688274, 3976.383660532622, 3978.468289708483, 3988.840446382112, 3989.740666856645, 4532.229520981376, 4673.205555055581, 4717.268823332389, 4725.4565724941385, 4740.95259047412, 4809.350737650442, 5589.832305030855, 5675.971620141873, 5734.713264336767, 5765.538756899438, 1094.7482079121687, 1138.6470194239812, 1270.4791035675328, 1284.17170924753, 1292.8577856435784, 722.3282347416848, 804.3886646148969, 822.4989394355325, 875.746033650247, 894.9821003579892, 4685.250359942393, 4740.905181896343, 4755.072760811678, 5122.334096567422, 5191.299174611455, 5079.847604571795, 5316.3354580677415, 5554.944450555443, 5689.829305120853, 5765.549641402471, 4293.5706429356815, 4557.134144512532, 4616.6075883550275, 4768.737719425465, 4905.852824415273, 2638.3931695710094, 2905.491538980712, 2973.628296462942, 2985.1939976206304, 3026.757859371245, 5079.923801142986, 5679.914801277984, 5726.394490506809, 5924.496417804535, 5931.73307201177, 5255.658382205144, 5453.972730136404, 5511.366192887869, 5778.5377169826315, 5827.79602713903, 5415.810446634349, 5537.778488860481, 5551.25058117149, 5815.999999999915, 5941.494972927349, 5218.347706536683, 5539.789759782514, 5709.600327977002, 5763.164341170423, 5779.393163717749, 4940.888300132585, 5408.945910540844, 5441.809536666197, 5555.833275012131, 5738.426157384228, 5288.334174734946, 5637.940682708443, 5807.651540907559, 5807.983602869565, 6052.788152414644, 3320.007995202859, 3768.3970564709516, 3828.1769419574925, 3910.1202229498135, 3912.315344814703, 4922.449428430033, 5099.77051032697, 5311.41574426805, 5740.483356497935, 5927.585069045125, 4583.839565615188, 5199.350081239845, 5369.409364969928, 5664.37691853904, 5730.885382292414, 4875.5632374539, 4952.5542701157065, 5020.523050310215, 5530.649473973625, 5689.146628005832, 4815.181419158681, 5036.773345199548, 5122.466617043869, 5327.733613319396, 5571.245057806778, 4577.3591697161755, 4696.641901040773, 5533.533613592321, 5734.913976290442, 5743.971280143573, 4729.43246810385, 4771.73755443454, 4852.344933433941, 5325.7337133143965, 5598.832035038955], "value": 4062.7281859563946, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.8993845892346, 705.3123204875251, 772.9758070556514, 797.625116195388, 814.6415577146057, 14406.279686015696, 15066.54800355991, 15122.62193445163, 15131.31909064092, 15315.234238288096, 11953.402329883515, 13746.312684365792, 14010.579682609537, 14172.503962361294, 14219.289035548207, 895.1674942303639, 905.3074398085437, 908.9727308180766, 910.1308250620674, 911.968081117161, 832.2052439919893, 874.5758307220992, 894.5303715549323, 900.5767289373983, 906.2387594420685, 951.8429459139234, 952.105021279998, 954.1508057828538, 956.7560272130597, 957.8994205608416, 948.7438391634289, 951.4909523405014, 951.9952400237954, 953.3374304858127, 954.5036580977923, 596.9522438204933, 691.512483698996, 698.5215127637584, 703.1597241296653, 710.2471380336859, 15495.535133946, 15666.921665391714, 15668.981516201411, 15677.0, 15853.52439426819, 13567.321633918265, 13650.385732642055, 13773.38019789112, 13807.309634518335, 13856.445742170301, 11424.562660457397, 11516.309021458655, 11590.246633968849, 11650.41747912601, 11850.051995840313, 3155.763317751155, 3552.2185119273718, 3659.5791483979415, 3757.1170774867883, 3797.4683544303543, 78813.60593196997, 79405.20594794035, 98943.0, 99819.0018099817, 100186.99439016842, 10348.586056557804, 10616.522256498476, 10679.466026698634, 10749.892501075046, 10803.351798892092, 21984.45038874009, 22001.11995520161, 22269.331920042423, 22330.888345558167, 22441.32676019722, 7282.927170728331, 7347.485676002629, 7365.594892280976, 7432.219616940251, 7515.736949206861, 3863.690904727615, 3874.380099184117, 3892.026993251687, 3923.92152156961, 3942.4086387042175, 3599.9820000899826, 3668.413053911361, 3695.9445608315896, 3699.315626609086, 3702.7037836972977, 661.2792056658266, 667.8096742428481, 669.8459354348527, 685.5441131647498, 691.0532570378614, 554.9583781216425, 597.3041406761075, 609.7469550136643, 624.1813202079935, 635.6376865186799, 8011.278984891388, 8359.456635318686, 9377.307530292115, 9625.32622716417, 9885.0, 524.8084449176099, 659.261626977788, 672.4855485553555, 684.8116767888771, 691.9031335613018, 4378.934315985262, 4774.331593576902, 4814.831480898151, 4838.95161048392, 4879.609311346249, 71048.44757762091, 96055.0, 97188.54217186748, 99124.5043774791, 100156.49921750344, 69919.65040174767, 70382.64808676027, 72965.0, 87308.0, 90052.5497372509, 68906.0, 70087.94868076983, 71585.6420717893, 71624.64187679028, 73150.0, 9691.418514888846, 10189.235807314333, 10414.895851041392, 10422.218333624856, 10459.686209413425, 46849.2972605418, 47523.811904702656, 48494.757526212146, 48500.78748031327, 48904.53286401414, 64785.0, 65421.691566169364, 65575.0, 66295.67408651703, 67824.0, 12241.632751017483, 12311.938440307742, 12394.0, 12481.56314528987, 12934.870651293742, 12406.689832754253, 13603.455861765418, 14185.007049506436, 14335.426582936983, 14533.709325813214, 11565.826512602485, 11711.0, 11874.228175168586, 11879.821802672794, 12260.632181034583, 10935.398553079656, 12857.742845143232, 12879.162854414433, 12972.156809807333, 13054.477820887058, 10021.699349019542, 12625.305608191638, 12652.936735316265, 13067.738645227233, 13133.474661013835, 10406.0, 10551.419671918118, 10587.20595955291, 10633.042766686767, 11395.0, 6247.781327653511, 8552.700655477029, 9127.497987610745, 9781.119699227103, 10451.42517161533], "value": 7239.38237223781, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1623.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 120.0, 161.0, 120.0, 174.0, 125.0, 131.0, 173.0, 160.0], "value": 139.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 114.0, 114.0, 170.0, 148.0, 145.0, 115.0, 114.0, 137.0, 115.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 207.0, 269.0, 250.0, 282.0, 261.0, 194.0, 193.0, 201.0, 192.0], "value": 226.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 63.0, 86.0, 58.0, 58.0, 76.0, 53.0, 72.0, 81.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 99.0, 119.0, 105.0, 75.0, 72.0, 84.0, 71.0, 68.0, 82.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 119.0, 88.0, 91.0, 88.0, 78.0, 137.0, 136.0, 88.0, 90.0], "value": 105.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 100.0, 147.0, 108.0, 155.0, 156.0, 96.0, 95.0, 96.0, 107.0], "value": 119.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 91.0, 93.0, 92.0, 93.0, 140.0, 92.0, 92.0, 100.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 62.0, 60.0, 55.0, 52.0, 54.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 74.0, 97.0, 70.0, 67.0, 68.0, 69.0, 70.0, 78.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 90.0, 91.0, 88.0, 92.0, 115.0, 93.0, 93.0, 113.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 153.0, 157.0, 168.0, 159.0, 160.0, 159.0, 159.0, 217.0, 160.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 178.0, 178.0, 184.0, 216.0, 177.0, 179.0, 177.0, 181.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 70.0, 71.0, 69.0, 69.0, 69.0, 71.0, 67.0], "value": 69.3, "unit": "score"}]}]} 18:12:04 INFO - Return code: 0 18:12:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:12:04 ERROR - # TBPL SUCCESS # 18:12:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:12:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:12:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:12:04 INFO - 2017-02-26 18:12:04.175 screenresolution[1670:16498] starting screenresolution argv=screenresolution get 18:12:04 INFO - 2017-02-26 18:12:04.177 screenresolution[1670:16498] Display 0: 1600x1200x32@60 18:12:04 INFO - 2017-02-26 18:12:04.184 screenresolution[1671:16501] starting screenresolution argv=screenresolution list 18:12:04 INFO - Available Modes on Display 0 18:12:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:12:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:12:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:12:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:12:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:12:04 INFO - Intel Iris: 18:12:04 INFO - Chipset Model: Intel Iris 18:12:04 INFO - Type: GPU 18:12:04 INFO - Bus: Built-In 18:12:04 INFO - VRAM (Dynamic, Max): 1536 MB 18:12:04 INFO - Vendor: Intel (0x8086) 18:12:04 INFO - Device ID: 0x0a2e 18:12:04 INFO - Revision ID: 0x0009 18:12:04 INFO - Displays: 18:12:04 INFO - Display: 18:12:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:12:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:12:04 INFO - Display Serial Number: 1600x1200 60 18:12:04 INFO - Main Display: Yes 18:12:04 INFO - Mirror: Off 18:12:04 INFO - Online: Yes 18:12:04 INFO - Rotation: Supported 18:12:04 INFO - Return code: 0 18:12:04 INFO - Running post-action listener: _resource_record_post_action 18:12:04 INFO - [mozharness: 2017-02-27 02:12:04.433145Z] Finished run-tests step (success) 18:12:04 INFO - Running post-run listener: _resource_record_post_run 18:12:04 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 33481216; Write bytes: 517163520; Read time: 202; Write time: 1599 18:12:04 INFO - TinderboxPrint: CPU usage
54.5% 18:12:04 INFO - TinderboxPrint: I/O read bytes / time
33,481,216 / 202 18:12:04 INFO - TinderboxPrint: I/O write bytes / time
517,163,520 / 1,599 18:12:04 INFO - TinderboxPrint: CPU idle
2,185.8 (45.5%) 18:12:04 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 18:12:04 INFO - TinderboxPrint: CPU user
1,649.8 (34.4%) 18:12:04 INFO - TinderboxPrint: Swap in / out
239,972,352 / 0 18:12:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206154240; Write bytes: 201629696; Read time: 15778; Write time: 419 18:12:04 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31683072; Write bytes: 303870976; Read time: 186; Write time: 1143 18:12:04 INFO - Running post-run listener: _upload_blobber_files 18:12:04 INFO - Blob upload gear active. 18:12:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:12:04 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:12:04 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:12:04 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:12:05 INFO - (blobuploader) - INFO - Open directory for files ... 18:12:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:12:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:12:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:06 INFO - (blobuploader) - INFO - Done attempting. 18:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:08 INFO - (blobuploader) - INFO - Done attempting. 18:12:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:12:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:12:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:09 INFO - (blobuploader) - INFO - Done attempting. 18:12:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:12:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:12:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:12:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:12:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:12:11 INFO - (blobuploader) - INFO - Done attempting. 18:12:11 INFO - (blobuploader) - INFO - Iteration through files over. 18:12:11 INFO - Return code: 0 18:12:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:12:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:12:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226af1163473e26e284a0cff06ee8ae074e682b11f5b8f379f29370ad595fdcffdc9e3e399ca863bb81fde29e773cc7226e9908bbd1a887bb17a56c81cf56fb6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a810438edc03bd64e689b336094707effd36752d0ee7dff14aa0c1b7f20fedb3d1dc4c4f83d3984894d98f33ff02ff29585e217872ba3687b81d491b0e8ba80", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee23b01f3ca46a09075007b27452b4d554dcf36f480cbc8e481b32bdd65521af220798124959ebca0853e58870a3d6caa34de9a13da70afde0c58298afb8ed24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c60fee4a8ca9ecdde7a282a9e44c787df19086d6f12a5f5109478e385c88a217c7dcc35b5325712f7740919afd86ca2277c86b6e97e7be80071df71ce3d8650"} 18:12:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:12:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:12:11 INFO - Contents: 18:12:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226af1163473e26e284a0cff06ee8ae074e682b11f5b8f379f29370ad595fdcffdc9e3e399ca863bb81fde29e773cc7226e9908bbd1a887bb17a56c81cf56fb6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a810438edc03bd64e689b336094707effd36752d0ee7dff14aa0c1b7f20fedb3d1dc4c4f83d3984894d98f33ff02ff29585e217872ba3687b81d491b0e8ba80", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee23b01f3ca46a09075007b27452b4d554dcf36f480cbc8e481b32bdd65521af220798124959ebca0853e58870a3d6caa34de9a13da70afde0c58298afb8ed24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c60fee4a8ca9ecdde7a282a9e44c787df19086d6f12a5f5109478e385c88a217c7dcc35b5325712f7740919afd86ca2277c86b6e97e7be80071df71ce3d8650"} 18:12:11 INFO - Running post-run listener: copy_logs_to_upload_dir 18:12:11 INFO - Copying logs to upload dir... 18:12:11 INFO - mkdir: /builds/slave/test/build/upload/logs 18:12:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.229309 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-02-26 18:12:11.479102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-26 18:12:11.483474) ========= 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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226af1163473e26e284a0cff06ee8ae074e682b11f5b8f379f29370ad595fdcffdc9e3e399ca863bb81fde29e773cc7226e9908bbd1a887bb17a56c81cf56fb6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a810438edc03bd64e689b336094707effd36752d0ee7dff14aa0c1b7f20fedb3d1dc4c4f83d3984894d98f33ff02ff29585e217872ba3687b81d491b0e8ba80", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee23b01f3ca46a09075007b27452b4d554dcf36f480cbc8e481b32bdd65521af220798124959ebca0853e58870a3d6caa34de9a13da70afde0c58298afb8ed24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c60fee4a8ca9ecdde7a282a9e44c787df19086d6f12a5f5109478e385c88a217c7dcc35b5325712f7740919afd86ca2277c86b6e97e7be80071df71ce3d8650"} build_url:https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/WbeWh7F5RLKEus8jbVgabw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226af1163473e26e284a0cff06ee8ae074e682b11f5b8f379f29370ad595fdcffdc9e3e399ca863bb81fde29e773cc7226e9908bbd1a887bb17a56c81cf56fb6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a810438edc03bd64e689b336094707effd36752d0ee7dff14aa0c1b7f20fedb3d1dc4c4f83d3984894d98f33ff02ff29585e217872ba3687b81d491b0e8ba80", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee23b01f3ca46a09075007b27452b4d554dcf36f480cbc8e481b32bdd65521af220798124959ebca0853e58870a3d6caa34de9a13da70afde0c58298afb8ed24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c60fee4a8ca9ecdde7a282a9e44c787df19086d6f12a5f5109478e385c88a217c7dcc35b5325712f7740919afd86ca2277c86b6e97e7be80071df71ce3d8650"}' ========= master_lag: 0.26 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-26 18:12:11.757342) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 18:12:11.757681) ========= 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.iAJXeeo2Ic/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.A1U9xfkUWx/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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.005362 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 18:12:11.807260) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-26 18:12:11.807587) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-26 18:12:11.807920) ========= ========= Total master_lag: 1.70 =========