builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0233 starttime: 1473395734.87 results: success (0) buildid: 20160908185320 builduid: 556f15fa862e4b4e80fc5510caa1f391 revision: 3f49ce4738e2ba432b0a2fddc85e4d16d7893de6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:34.872495) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:34.872909) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:34.873196) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:34.902256) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:34.902534) ========= 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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-08 21:35:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.91M=0.001s 2016-09-08 21:35:35 (7.91 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.598409 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:35.522562) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:35.522845) ========= 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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.021260 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:35.571054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-08 21:35:35.571414) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3f49ce4738e2ba432b0a2fddc85e4d16d7893de6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3f49ce4738e2ba432b0a2fddc85e4d16d7893de6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NKv7Uh2lT0/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.fQN2OY24rV/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-08 21:35:35,658 truncating revision to first 12 chars 2016-09-08 21:35:35,658 Setting DEBUG logging. 2016-09-08 21:35:35,658 attempt 1/10 2016-09-08 21:35:35,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3f49ce4738e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 21:35:37,435 unpacking tar archive at: fx-team-3f49ce4738e2/testing/mozharness/ program finished with exit code 0 elapsedTime=2.040968 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-08 21:35:37.634775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:37.635092) ========= script_repo_revision: 3f49ce4738e2ba432b0a2fddc85e4d16d7893de6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:37.635472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:37.635738) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 21:35:37.654698) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-09-08 21:35:37.655059) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NKv7Uh2lT0/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.fQN2OY24rV/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:35:37 INFO - MultiFileLogger online at 20160908 21:35:37 in /builds/slave/test 21:35:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:35:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:35:37 INFO - {'append_to_log': False, 21:35:37 INFO - 'base_work_dir': '/builds/slave/test', 21:35:37 INFO - 'blob_upload_branch': 'Fx-Team', 21:35:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:35:37 INFO - 'branch': 'Fx-Team', 21:35:37 INFO - 'buildbot_json_path': 'buildprops.json', 21:35:37 INFO - 'config_files': ('talos/mac_config.py',), 21:35:37 INFO - 'default_actions': ('clobber', 21:35:37 INFO - 'read-buildbot-config', 21:35:37 INFO - 'download-and-extract', 21:35:37 INFO - 'populate-webroot', 21:35:37 INFO - 'create-virtualenv', 21:35:37 INFO - 'install', 21:35:37 INFO - 'run-tests'), 21:35:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:35:37 INFO - 'download_minidump_stackwalk': True, 21:35:37 INFO - 'download_symbols': 'ondemand', 21:35:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:35:37 INFO - 'tooltool.py': '/tools/tooltool.py', 21:35:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:35:37 INFO - '/tools/misc-python/virtualenv.py')}, 21:35:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:35:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:35:37 INFO - 'installer_path': 'installer.exe', 21:35:37 INFO - 'log_level': 'info', 21:35:37 INFO - 'log_name': 'talos', 21:35:37 INFO - 'log_to_console': True, 21:35:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:35:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:35:37 INFO - 'opt_config_files': (), 21:35:37 INFO - 'pip_index': False, 21:35:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:35:37 INFO - 'cmd': ('bash', 21:35:37 INFO - '-c', 21:35:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:35:37 INFO - 'enabled': True, 21:35:37 INFO - 'halt_on_failure': False, 21:35:37 INFO - 'name': 'check_screen_resolution'},), 21:35:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:35:37 INFO - 'cmd': ('bash', 21:35:37 INFO - '-c', 21:35:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:35:37 INFO - 'enabled': True, 21:35:37 INFO - 'halt_on_failure': False, 21:35:37 INFO - 'name': 'check_screen_resolution'},), 21:35:37 INFO - 'run_cmd_checks_enabled': True, 21:35:37 INFO - 'sps_profile': False, 21:35:37 INFO - 'sps_profile_interval': 0, 21:35:37 INFO - 'suite': 'dromaeojs-e10s', 21:35:37 INFO - 'system_bits': '32', 21:35:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:35:37 INFO - 'title': 't-yosemite-r7-0233', 21:35:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:35:37 INFO - 'use_talos_json': True, 21:35:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:35:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:35:37 INFO - 'work_dir': 'build'} 21:35:37 INFO - [mozharness: 2016-09-09 04:35:37.847923Z] Running clobber step. 21:35:37 INFO - Running pre-action listener: _resource_record_pre_action 21:35:37 INFO - Running main action method: clobber 21:35:37 INFO - rmtree: /builds/slave/test/build 21:35:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:35:38 INFO - Running post-action listener: _resource_record_post_action 21:35:38 INFO - [mozharness: 2016-09-09 04:35:38.743793Z] Finished clobber step (success) 21:35:38 INFO - [mozharness: 2016-09-09 04:35:38.743936Z] Running read-buildbot-config step. 21:35:38 INFO - Running pre-action listener: _resource_record_pre_action 21:35:38 INFO - Running main action method: read_buildbot_config 21:35:38 INFO - Using buildbot properties: 21:35:38 INFO - { 21:35:38 INFO - "project": "", 21:35:38 INFO - "product": "firefox", 21:35:38 INFO - "script_repo_revision": "production", 21:35:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 21:35:38 INFO - "repository": "", 21:35:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 21:35:38 INFO - "buildid": "20160908185320", 21:35:38 INFO - "pgo_build": "False", 21:35:38 INFO - "basedir": "/builds/slave/test", 21:35:38 INFO - "buildnumber": 115, 21:35:38 INFO - "slavename": "t-yosemite-r7-0233", 21:35:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 21:35:38 INFO - "platform": "yosemite_r7", 21:35:38 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 21:35:38 INFO - "branch": "fx-team", 21:35:38 INFO - "repo_path": "integration/fx-team", 21:35:38 INFO - "revision": "3f49ce4738e2ba432b0a2fddc85e4d16d7893de6", 21:35:38 INFO - "stage_platform": "macosx64", 21:35:38 INFO - "builduid": "556f15fa862e4b4e80fc5510caa1f391", 21:35:38 INFO - "slavebuilddir": "test" 21:35:38 INFO - } 21:35:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:35:38 INFO - Running post-action listener: _resource_record_post_action 21:35:38 INFO - [mozharness: 2016-09-09 04:35:38.746988Z] Finished read-buildbot-config step (success) 21:35:38 INFO - [mozharness: 2016-09-09 04:35:38.747090Z] Running download-and-extract step. 21:35:38 INFO - Running pre-action listener: _resource_record_pre_action 21:35:38 INFO - Running main action method: download_and_extract 21:35:38 INFO - mkdir: /builds/slave/test/build/tests 21:35:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:38 INFO - trying https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:35:38 INFO - Downloading https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:35:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 21:35:39 INFO - Downloaded 1199 bytes. 21:35:39 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:35:39 INFO - Using the following test package requirements: 21:35:39 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:35:39 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:35:39 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:35:39 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'jsshell-mac.zip'], 21:35:39 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:35:39 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:35:39 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:35:39 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:35:39 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:35:39 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:35:39 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:35:39 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:35:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:35:39 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:35:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:35:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:35:47 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:35:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:35:54 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:35:54 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')]} 21:35:54 INFO - trying https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 21:35:58 INFO - Downloaded 111521188 bytes. 21:35:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:35:58 INFO - mkdir: /builds/slave/test/properties 21:35:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:35:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:35:58 INFO - Contents: 21:35:58 INFO - build_url:https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:35:58 INFO - Running post-action listener: _resource_record_post_action 21:35:58 INFO - Running post-action listener: set_extra_try_arguments 21:35:58 INFO - [mozharness: 2016-09-09 04:35:58.822424Z] Finished download-and-extract step (success) 21:35:58 INFO - [mozharness: 2016-09-09 04:35:58.822594Z] Running populate-webroot step. 21:35:58 INFO - Running pre-action listener: _resource_record_pre_action 21:35:58 INFO - Running main action method: populate_webroot 21:35:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:35:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:35:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:35:58 INFO - '--tppagecycles', 21:35:58 INFO - '7'], 21:35:58 INFO - 'tests': ['tsvgm']}}, 21:35:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:35:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:35:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:35:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:35:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:35:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:35:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['damp', 'tps']}, 21:35:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'tests': ['damp', 'tps']}, 21:35:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['dromaeo_dom']}, 21:35:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:35:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['basic_compositor_video']}, 21:35:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:35:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['a11yr', 21:35:58 INFO - 'ts_paint', 21:35:58 INFO - 'tpaint', 21:35:58 INFO - 'sessionrestore', 21:35:58 INFO - 'sessionrestore_no_auto_restore', 21:35:58 INFO - 'tabpaint']}, 21:35:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:35:58 INFO - 'ts_paint', 21:35:58 INFO - 'tpaint', 21:35:58 INFO - 'sessionrestore', 21:35:58 INFO - 'sessionrestore_no_auto_restore', 21:35:58 INFO - 'tabpaint']}, 21:35:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:35:58 INFO - 'ts_paint', 21:35:58 INFO - 'tpaint', 21:35:58 INFO - 'sessionrestore', 21:35:58 INFO - 'sessionrestore_no_auto_restore', 21:35:58 INFO - 'tabpaint']}, 21:35:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:35:58 INFO - 'ts_paint', 21:35:58 INFO - 'tpaint', 21:35:58 INFO - 'sessionrestore', 21:35:58 INFO - 'sessionrestore_no_auto_restore', 21:35:58 INFO - 'tabpaint']}, 21:35:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['a11yr', 21:35:58 INFO - 'ts_paint', 21:35:58 INFO - 'tpaint', 21:35:58 INFO - 'sessionrestore', 21:35:58 INFO - 'sessionrestore_no_auto_restore', 21:35:58 INFO - 'tabpaint']}, 21:35:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['a11yr', 21:35:58 INFO - 'ts_paint', 21:35:58 INFO - 'tpaint', 21:35:58 INFO - 'sessionrestore', 21:35:58 INFO - 'sessionrestore_no_auto_restore', 21:35:58 INFO - 'tabpaint']}, 21:35:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['tsvgx', 21:35:58 INFO - 'tsvgr_opacity', 21:35:58 INFO - 'tart', 21:35:58 INFO - 'tscrollx', 21:35:58 INFO - 'cart']}, 21:35:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:35:58 INFO - 'tsvgr_opacity', 21:35:58 INFO - 'tart', 21:35:58 INFO - 'tscrollx', 21:35:58 INFO - 'cart']}, 21:35:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'talos_options': ['--disable-e10s'], 21:35:58 INFO - 'tests': ['tp5o']}, 21:35:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'tests': ['tp5o']}, 21:35:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'talos_options': ['--disable-e10s', 21:35:58 INFO - '--xperf_path', 21:35:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:35:58 INFO - 'tests': ['tp5n']}, 21:35:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:58 INFO - 'talos_options': ['--xperf_path', 21:35:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:35:58 INFO - 'tests': ['tp5n']}}, 21:35:58 INFO - 'talos.zip': {'path': '', 21:35:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:35:58 INFO - Running post-action listener: _resource_record_post_action 21:35:58 INFO - [mozharness: 2016-09-09 04:35:58.841448Z] Finished populate-webroot step (success) 21:35:58 INFO - [mozharness: 2016-09-09 04:35:58.841575Z] Running create-virtualenv step. 21:35:58 INFO - Running pre-action listener: _resource_record_pre_action 21:35:58 INFO - Running main action method: create_virtualenv 21:35:58 INFO - Creating virtualenv /builds/slave/test/build/venv 21:35:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:35:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:35:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:35:58 INFO - Using real prefix '/tools/python27' 21:35:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:35:59 INFO - Installing distribute.............................................................................................................................................................................................done. 21:36:02 INFO - Installing pip.................done. 21:36:02 INFO - Return code: 0 21:36:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:02 INFO - 'HOME': '/Users/cltbld', 21:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:02 INFO - 'LOGNAME': 'cltbld', 21:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:02 INFO - 'MOZ_NO_REMOTE': '1', 21:36:02 INFO - 'NO_EM_RESTART': '1', 21:36:02 INFO - 'PAGER': '/bin/cat', 21:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:02 INFO - 'PWD': '/builds/slave/test', 21:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:02 INFO - 'SHELL': '/bin/bash', 21:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:02 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:02 INFO - 'USER': 'cltbld', 21:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:02 INFO - 'XPC_FLAGS': '0x0', 21:36:02 INFO - 'XPC_SERVICE_NAME': '0', 21:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:36:02 INFO - Cleaning up... 21:36:02 INFO - Return code: 0 21:36:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:02 INFO - 'HOME': '/Users/cltbld', 21:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:02 INFO - 'LOGNAME': 'cltbld', 21:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:02 INFO - 'MOZ_NO_REMOTE': '1', 21:36:02 INFO - 'NO_EM_RESTART': '1', 21:36:02 INFO - 'PAGER': '/bin/cat', 21:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:02 INFO - 'PWD': '/builds/slave/test', 21:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:02 INFO - 'SHELL': '/bin/bash', 21:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:02 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:02 INFO - 'USER': 'cltbld', 21:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:02 INFO - 'XPC_FLAGS': '0x0', 21:36:02 INFO - 'XPC_SERVICE_NAME': '0', 21:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:03 INFO - Downloading/unpacking psutil>=3.1.1 21:36:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:36:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:36:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:36:06 INFO - Installing collected packages: psutil 21:36:06 INFO - Running setup.py install for psutil 21:36:06 INFO - building 'psutil._psutil_osx' extension 21:36:06 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 21:36:06 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 21:36:06 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 21:36:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:36:07 INFO - building 'psutil._psutil_posix' extension 21:36:07 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 21:36:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:36:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:36:07 INFO - ^ 21:36:07 INFO - 1 warning generated. 21:36:07 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 21:36:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:36:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:36:07 INFO - Successfully installed psutil 21:36:07 INFO - Cleaning up... 21:36:07 INFO - Return code: 0 21:36:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:36:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:36:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:07 INFO - 'HOME': '/Users/cltbld', 21:36:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:07 INFO - 'LOGNAME': 'cltbld', 21:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:07 INFO - 'MOZ_NO_REMOTE': '1', 21:36:07 INFO - 'NO_EM_RESTART': '1', 21:36:07 INFO - 'PAGER': '/bin/cat', 21:36:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:07 INFO - 'PWD': '/builds/slave/test', 21:36:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:07 INFO - 'SHELL': '/bin/bash', 21:36:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:07 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:07 INFO - 'USER': 'cltbld', 21:36:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:07 INFO - 'XPC_FLAGS': '0x0', 21:36:07 INFO - 'XPC_SERVICE_NAME': '0', 21:36:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:36:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:36:10 INFO - Installing collected packages: mozsystemmonitor 21:36:10 INFO - Running setup.py install for mozsystemmonitor 21:36:10 INFO - Successfully installed mozsystemmonitor 21:36:10 INFO - Cleaning up... 21:36:10 INFO - Return code: 0 21:36:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:36:10 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')]} 21:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:10 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')]} 21:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:10 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:10 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 21:36:10 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 21:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:10 INFO - 'HOME': '/Users/cltbld', 21:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:10 INFO - 'LOGNAME': 'cltbld', 21:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:10 INFO - 'MOZ_NO_REMOTE': '1', 21:36:10 INFO - 'NO_EM_RESTART': '1', 21:36:10 INFO - 'PAGER': '/bin/cat', 21:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:10 INFO - 'PWD': '/builds/slave/test', 21:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:10 INFO - 'SHELL': '/bin/bash', 21:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:10 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:10 INFO - 'USER': 'cltbld', 21:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:10 INFO - 'XPC_FLAGS': '0x0', 21:36:10 INFO - 'XPC_SERVICE_NAME': '0', 21:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:10 INFO - Downloading/unpacking jsonschema==2.5.1 21:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:36:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 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 21:36:13 INFO - Downloading functools32-3.2.3-2.tar.gz 21:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:36:13 INFO - warning: no files found matching '*.txt' 21:36:13 INFO - no previously-included directories found matching 'build' 21:36:13 INFO - no previously-included directories found matching 'dist' 21:36:13 INFO - no previously-included directories found matching '.git*' 21:36:13 INFO - Installing collected packages: jsonschema, functools32 21:36:13 INFO - Running setup.py install for functools32 21:36:13 INFO - warning: no files found matching '*.txt' 21:36:13 INFO - no previously-included directories found matching 'build' 21:36:13 INFO - no previously-included directories found matching 'dist' 21:36:13 INFO - no previously-included directories found matching '.git*' 21:36:13 INFO - Successfully installed jsonschema functools32 21:36:13 INFO - Cleaning up... 21:36:13 INFO - Return code: 0 21:36:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:36:13 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')]} 21:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:13 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')]} 21:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:13 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:13 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 21:36:13 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 21:36:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:13 INFO - 'HOME': '/Users/cltbld', 21:36:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:13 INFO - 'LOGNAME': 'cltbld', 21:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:13 INFO - 'MOZ_NO_REMOTE': '1', 21:36:13 INFO - 'NO_EM_RESTART': '1', 21:36:13 INFO - 'PAGER': '/bin/cat', 21:36:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:13 INFO - 'PWD': '/builds/slave/test', 21:36:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:13 INFO - 'SHELL': '/bin/bash', 21:36:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:13 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:13 INFO - 'USER': 'cltbld', 21:36:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:13 INFO - 'XPC_FLAGS': '0x0', 21:36:13 INFO - 'XPC_SERVICE_NAME': '0', 21:36:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:36:14 INFO - Cleaning up... 21:36:14 INFO - Return code: 0 21:36:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:36:14 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')]} 21:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:14 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')]} 21:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:14 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:14 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 21:36:14 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 21:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:14 INFO - 'HOME': '/Users/cltbld', 21:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:14 INFO - 'LOGNAME': 'cltbld', 21:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:14 INFO - 'MOZ_NO_REMOTE': '1', 21:36:14 INFO - 'NO_EM_RESTART': '1', 21:36:14 INFO - 'PAGER': '/bin/cat', 21:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:14 INFO - 'PWD': '/builds/slave/test', 21:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:14 INFO - 'SHELL': '/bin/bash', 21:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:14 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:14 INFO - 'USER': 'cltbld', 21:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:14 INFO - 'XPC_FLAGS': '0x0', 21:36:14 INFO - 'XPC_SERVICE_NAME': '0', 21:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:14 INFO - Downloading/unpacking blobuploader==1.2.4 21:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:16 INFO - Downloading blobuploader-1.2.4.tar.gz 21:36:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:36:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:36:16 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 21:36:16 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 21:36:16 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 21:36:16 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 21:36:16 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 21:36:16 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 21:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:36:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:36:17 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 21:36:17 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 21:36:17 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 21:36:17 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 21:36:17 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 21:36:17 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 21:36:18 INFO - Downloading docopt-0.6.1.tar.gz 21:36:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:36:18 INFO - Installing collected packages: blobuploader, requests, docopt 21:36:18 INFO - Running setup.py install for blobuploader 21:36:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:36:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:36:18 INFO - Running setup.py install for requests 21:36:18 INFO - Running setup.py install for docopt 21:36:18 INFO - Successfully installed blobuploader requests docopt 21:36:18 INFO - Cleaning up... 21:36:19 INFO - Return code: 0 21:36:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:19 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')]} 21:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:19 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')]} 21:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:19 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:19 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 21:36:19 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 21:36:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:19 INFO - 'HOME': '/Users/cltbld', 21:36:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:19 INFO - 'LOGNAME': 'cltbld', 21:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:19 INFO - 'MOZ_NO_REMOTE': '1', 21:36:19 INFO - 'NO_EM_RESTART': '1', 21:36:19 INFO - 'PAGER': '/bin/cat', 21:36:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:19 INFO - 'PWD': '/builds/slave/test', 21:36:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:19 INFO - 'SHELL': '/bin/bash', 21:36:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:19 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:19 INFO - 'USER': 'cltbld', 21:36:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:19 INFO - 'XPC_FLAGS': '0x0', 21:36:19 INFO - 'XPC_SERVICE_NAME': '0', 21:36:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-2ie4eI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-MYLnEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-sGmZ6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-jfIv2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-mFUX9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-R_CwUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-tV24vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-1gBzXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Q9tnBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-69paq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-N2teuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-dhmZNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-MHSy83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-W065ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wN65Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-YwL4z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-l_7_MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:36:21 INFO - Running setup.py install for manifestparser 21:36:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:36:21 INFO - Running setup.py install for mozcrash 21:36:21 INFO - Running setup.py install for mozdebug 21:36:21 INFO - Running setup.py install for mozdevice 21:36:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:36:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:36:21 INFO - Running setup.py install for mozfile 21:36:21 INFO - Running setup.py install for mozhttpd 21:36:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:36:21 INFO - Running setup.py install for mozinfo 21:36:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Running setup.py install for mozInstall 21:36:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Running setup.py install for mozleak 21:36:22 INFO - Running setup.py install for mozlog 21:36:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Running setup.py install for moznetwork 21:36:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:36:22 INFO - Running setup.py install for mozprocess 21:36:22 INFO - Running setup.py install for mozprofile 21:36:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:36:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:36:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:36:23 INFO - Running setup.py install for mozrunner 21:36:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:36:23 INFO - Running setup.py install for mozscreenshot 21:36:23 INFO - Running setup.py install for moztest 21:36:23 INFO - Running setup.py install for mozversion 21:36:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:36:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:36:23 INFO - Cleaning up... 21:36:23 INFO - Return code: 0 21:36:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:23 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:23 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 21:36:23 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 21:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:23 INFO - 'HOME': '/Users/cltbld', 21:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:23 INFO - 'LOGNAME': 'cltbld', 21:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:23 INFO - 'MOZ_NO_REMOTE': '1', 21:36:23 INFO - 'NO_EM_RESTART': '1', 21:36:23 INFO - 'PAGER': '/bin/cat', 21:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:23 INFO - 'PWD': '/builds/slave/test', 21:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:23 INFO - 'SHELL': '/bin/bash', 21:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:23 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:23 INFO - 'USER': 'cltbld', 21:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:23 INFO - 'XPC_FLAGS': '0x0', 21:36:23 INFO - 'XPC_SERVICE_NAME': '0', 21:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:23 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4BvGmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Vp7o8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-w24sga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-hj5YiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-OHMac0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-SySEyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-nNzHCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-EFppoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-23QK7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:24 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)) 21:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:24 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bo0Hj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_EShww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:25 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)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-EnWWzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4ZGaPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:25 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)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-rdPv3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Q2Nr3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:25 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)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ketrkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:25 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-k6KLwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:25 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)) 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:36:25 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)) 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:36:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:36:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:36:25 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 21:36:25 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 21:36:25 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 21:36:25 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 21:36:25 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 21:36:25 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 21:36:28 INFO - Downloading blessings-1.6.tar.gz 21:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:36:28 INFO - Installing collected packages: blessings 21:36:28 INFO - Running setup.py install for blessings 21:36:28 INFO - Successfully installed blessings 21:36:28 INFO - Cleaning up... 21:36:28 INFO - Return code: 0 21:36:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:36:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:28 INFO - Reading from file tmpfile_stdout 21:36:28 INFO - Current package versions: 21:36:28 INFO - blessings == 1.6 21:36:28 INFO - blobuploader == 1.2.4 21:36:28 INFO - docopt == 0.6.1 21:36:28 INFO - functools32 == 3.2.3-2 21:36:28 INFO - jsonschema == 2.5.1 21:36:28 INFO - manifestparser == 1.1 21:36:28 INFO - mozInstall == 1.12 21:36:28 INFO - mozcrash == 0.17 21:36:28 INFO - mozdebug == 0.1 21:36:28 INFO - mozdevice == 0.48 21:36:28 INFO - mozfile == 1.2 21:36:28 INFO - mozhttpd == 0.7 21:36:28 INFO - mozinfo == 0.9 21:36:28 INFO - mozleak == 0.1 21:36:28 INFO - mozlog == 3.3 21:36:28 INFO - moznetwork == 0.27 21:36:28 INFO - mozprocess == 0.23 21:36:28 INFO - mozprofile == 0.28 21:36:28 INFO - mozrunner == 6.12 21:36:28 INFO - mozscreenshot == 0.1 21:36:28 INFO - mozsystemmonitor == 0.3 21:36:28 INFO - moztest == 0.7 21:36:28 INFO - mozversion == 1.4 21:36:28 INFO - psutil == 3.1.1 21:36:28 INFO - requests == 1.2.3 21:36:28 INFO - wsgiref == 0.1.2 21:36:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36: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')]} 21:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36: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')]} 21:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:28 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:28 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 21:36:28 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 21:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:28 INFO - 'HOME': '/Users/cltbld', 21:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:28 INFO - 'LOGNAME': 'cltbld', 21:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:28 INFO - 'MOZ_NO_REMOTE': '1', 21:36:28 INFO - 'NO_EM_RESTART': '1', 21:36:28 INFO - 'PAGER': '/bin/cat', 21:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:28 INFO - 'PWD': '/builds/slave/test', 21:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:28 INFO - 'SHELL': '/bin/bash', 21:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:28 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:28 INFO - 'USER': 'cltbld', 21:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:28 INFO - 'XPC_FLAGS': '0x0', 21:36:28 INFO - 'XPC_SERVICE_NAME': '0', 21:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 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)) 21:36:29 INFO - Cleaning up... 21:36:29 INFO - Return code: 0 21:36:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:36:29 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')]} 21:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:29 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')]} 21:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:36:29 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 0x109bd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee934a3f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, '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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 21:36:29 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 21:36:29 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 21:36:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:36:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:29 INFO - 'HOME': '/Users/cltbld', 21:36:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:29 INFO - 'LOGNAME': 'cltbld', 21:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:29 INFO - 'MOZ_NO_REMOTE': '1', 21:36:29 INFO - 'NO_EM_RESTART': '1', 21:36:29 INFO - 'PAGER': '/bin/cat', 21:36:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:29 INFO - 'PWD': '/builds/slave/test', 21:36:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:29 INFO - 'SHELL': '/bin/bash', 21:36:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:36:29 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:36:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:29 INFO - 'USER': 'cltbld', 21:36:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:29 INFO - 'XPC_FLAGS': '0x0', 21:36:29 INFO - 'XPC_SERVICE_NAME': '0', 21:36:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:36:29 INFO - Cleaning up... 21:36:29 INFO - Return code: 0 21:36:29 INFO - Running post-action listener: _resource_record_post_action 21:36:29 INFO - Running post-action listener: _start_resource_monitoring 21:36:29 INFO - Starting resource monitoring. 21:36:29 INFO - [mozharness: 2016-09-09 04:36:29.485906Z] Finished create-virtualenv step (success) 21:36:29 INFO - [mozharness: 2016-09-09 04:36:29.487005Z] Running install step. 21:36:29 INFO - Running pre-action listener: _resource_record_pre_action 21:36:29 INFO - Running main action method: install 21:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:29 INFO - Reading from file tmpfile_stdout 21:36:29 INFO - Detecting whether we're running mozinstall >=1.0... 21:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:36:29 INFO - Reading from file tmpfile_stdout 21:36:29 INFO - Output received: 21:36:29 INFO - Usage: mozinstall [options] installer 21:36:29 INFO - Options: 21:36:29 INFO - -h, --help show this help message and exit 21:36:29 INFO - -d DEST, --destination=DEST 21:36:29 INFO - Directory to install application into. [default: 21:36:29 INFO - "/builds/slave/test"] 21:36:29 INFO - --app=APP Application being installed. [default: firefox] 21:36:29 INFO - mkdir: /builds/slave/test/build/application 21:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:37:04 INFO - Reading from file tmpfile_stdout 21:37:04 INFO - Output received: 21:37:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:37:04 INFO - Running post-action listener: _resource_record_post_action 21:37:04 INFO - [mozharness: 2016-09-09 04:37:04.555790Z] Finished install step (success) 21:37:04 INFO - [mozharness: 2016-09-09 04:37:04.555937Z] Running run-tests step. 21:37:04 INFO - Running pre-action listener: _resource_record_pre_action 21:37:04 INFO - Running main action method: run_tests 21:37:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:37:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:37:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:37:04 INFO - 2016-09-08 21:37:04.570 screenresolution[3224:306904] starting screenresolution argv=screenresolution get 21:37:04 INFO - 2016-09-08 21:37:04.573 screenresolution[3224:306904] Display 0: 1600x1200x32@60 21:37:04 INFO - 2016-09-08 21:37:04.579 screenresolution[3225:306906] starting screenresolution argv=screenresolution list 21:37:04 INFO - Available Modes on Display 0 21:37:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:37:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:37:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:37:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:37:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:37:04 INFO - Intel Iris: 21:37:04 INFO - Chipset Model: Intel Iris 21:37:04 INFO - Type: GPU 21:37:04 INFO - Bus: Built-In 21:37:04 INFO - VRAM (Dynamic, Max): 1536 MB 21:37:04 INFO - Vendor: Intel (0x8086) 21:37:04 INFO - Device ID: 0x0a2e 21:37:04 INFO - Revision ID: 0x0009 21:37:04 INFO - Displays: 21:37:04 INFO - Display: 21:37:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:37:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:37:04 INFO - Display Serial Number: 1600x1200 60 21:37:04 INFO - Main Display: Yes 21:37:04 INFO - Mirror: Off 21:37:04 INFO - Online: Yes 21:37:04 INFO - Rotation: Supported 21:37:04 INFO - Return code: 0 21:37:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:37:04 INFO - Python 2.7.3 21:37:04 INFO - Return code: 0 21:37:04 INFO - grabbing minidump binary from tooltool 21:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:04 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 0x109b39d30>, '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 0x10989edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b31b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:37:04 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 21:37:04 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 21:37:04 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 21:37:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:37:04 INFO - Return code: 0 21:37:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:37:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:37:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:37:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:37:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:37:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 21:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --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 21:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NKv7Uh2lT0/Render', 21:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:37:04 INFO - 'HOME': '/Users/cltbld', 21:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:37:04 INFO - 'LOGNAME': 'cltbld', 21:37:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:37:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:04 INFO - 'MOZ_NO_REMOTE': '1', 21:37:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:37:04 INFO - 'NO_EM_RESTART': '1', 21:37:04 INFO - 'PAGER': '/bin/cat', 21:37:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:04 INFO - 'PWD': '/builds/slave/test', 21:37:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:37:04 INFO - 'SHELL': '/bin/bash', 21:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fQN2OY24rV/Listeners', 21:37:04 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 21:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:37:04 INFO - 'USER': 'cltbld', 21:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:04 INFO - 'XPC_FLAGS': '0x0', 21:37:04 INFO - 'XPC_SERVICE_NAME': '0', 21:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:37:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 21:37:05 INFO - mozversion application_buildid: 20160908185320 21:37:05 INFO - mozversion application_changeset: 3f49ce4738e2ba432b0a2fddc85e4d16d7893de6 21:37:05 INFO - mozversion application_display_name: Nightly 21:37:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:37:05 INFO - mozversion application_name: Firefox 21:37:05 INFO - mozversion application_remotingname: firefox 21:37:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:37:05 INFO - mozversion application_vendor: Mozilla 21:37:05 INFO - mozversion application_version: 51.0a1 21:37:05 INFO - mozversion platform_buildid: 20160908185320 21:37:05 INFO - mozversion platform_changeset: 3f49ce4738e2ba432b0a2fddc85e4d16d7893de6 21:37:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:37:05 INFO - mozversion platform_version: 51.0a1 21:37:05 INFO - using testdate: 1473395825 21:37:05 INFO - actual date: 1473395825 21:37:05 INFO - starting webserver on 'localhost:56049' 21:37:05 INFO - SUITE-START | Running 2 tests 21:37:05 INFO - TEST-START | dromaeo_css 21:37:05 INFO - operating with platform_type : mac_ 21:37:05 INFO - Initialising browser for dromaeo_css test... 21:37:05 INFO - TEST-INFO | started process 3235 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpnpHh9B/profile http://localhost:56049/getInfo.html) 21:37:07 INFO - PROCESS | 3235 | __metrics Screen width/height:1600/1200 21:37:07 INFO - PROCESS | 3235 | colorDepth:24 21:37:07 INFO - PROCESS | 3235 | Browser inner width/height: 1024/689 21:37:07 INFO - PROCESS | 3235 | __metrics 21:37:07 ERROR - PROCESS | 3235 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 21:37:08 INFO - TEST-INFO | 3235: exit 0 21:37:08 INFO - Browser initialized. 21:37:08 INFO - Running cycle 1/1 for dromaeo_css test... 21:37:08 INFO - TEST-INFO | started process 3238 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpnpHh9B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:37:20 INFO - PROCESS | 3238 | RSS: Main: 243904512 21:37:20 INFO - PROCESS | 3238 | 21:38:10 INFO - PROCESS | 3238 | 1473395890150 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:40:22 INFO - PROCESS | 3238 | Cycle 1(1): loaded http://localhost:56049/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56049/tests/dromaeo/cssquery-ext.html) 21:40:23 INFO - PROCESS | 3238 | RSS: Main: 266633216 21:40:23 INFO - PROCESS | 3238 | 21:43:25 INFO - PROCESS | 3238 | Cycle 1(1): loaded http://localhost:56049/tests/dromaeo/cssquery-ext.html (next: http://localhost:56049/tests/dromaeo/cssquery-jquery.html) 21:43:26 INFO - PROCESS | 3238 | RSS: Main: 274145280 21:43:26 INFO - PROCESS | 3238 | 21:46:28 INFO - PROCESS | 3238 | Cycle 1(1): loaded http://localhost:56049/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56049/tests/dromaeo/cssquery-mootools.html) 21:46:28 INFO - PROCESS | 3238 | RSS: Main: 276299776 21:46:28 INFO - PROCESS | 3238 | 21:49:30 INFO - PROCESS | 3238 | Cycle 1(1): loaded http://localhost:56049/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56049/tests/dromaeo/cssquery-prototype.html) 21:49:31 INFO - PROCESS | 3238 | RSS: Main: 271478784 21:49:31 INFO - PROCESS | 3238 | 21:52:34 INFO - PROCESS | 3238 | Cycle 1(1): loaded http://localhost:56049/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56049/tests/dromaeo/cssquery-yui.html) 21:52:35 INFO - PROCESS | 3238 | RSS: Main: 277557248 21:52:35 INFO - PROCESS | 3238 | 21:55:37 INFO - PROCESS | 3238 | Cycle 1(1): loaded http://localhost:56049/tests/dromaeo/cssquery-yui.html (next: http://localhost:56049/tests/dromaeo/cssquery-dojo.html) 21:55:38 INFO - PROCESS | 3238 | RSS: Main: 280645632 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | __start_tp_report 21:55:38 INFO - PROCESS | 3238 | _x_x_mozilla_page_load 21:55:38 INFO - PROCESS | 3238 | _x_x_mozilla_page_load_details 21:55:38 INFO - PROCESS | 3238 | |i|pagename|runs| 21:55:38 INFO - PROCESS | 3238 | |0;dojo.html;3271.3130242649027;3312.3706495765805;3320.103572035551;3341.365140623283;3343.9498407523893;4978.829974955883;5596.63621864579;5597.916031259534;5603.719814009299;5693.28833895763;5445.891082178354;5678.801241956522;5702.230198923134;5711.600187986843;5810.012297909366;5076.670016448919;5159.406668233145;5179.922301165486;5224;5231.410397016892;5210.16637338026;5231.73841307935;5273.393559740622;5276.103062479368;5294.232336311233;5067.189249720064;5097.515736005073;5154.252633368141;5168.974155129238;5175.715335656538;5148;5152.098382783035;5157.896842063175;5161.845144645667;5207.895842083137;5097.057044446752;5120.718360490171;5139.665921715114;5148.304978827848;5148.433672296082;4245.97877010613;4431;4561.521040290788;5401.297831281908;5575.944240557583;6185.948388773919;6191.659458729768;6225.439710426038;6242.781502647385;6244.0633904914175;6192.566520343577;6238.282597501255;6249.406306400884;6256.6871656417;6265.937340626581;5633.211350410946;5725.885482290414;5728.48443640066;5747;5750.338711048283;6191.845203869849;6207.81376558704;6215.409536094064;6220.657863817533;6227.501799856001;6064.878702426015;6135.417135372132;6142.846428839225;6153.230846144232;6160.1375807387;6098.786542470993;6104.7840518355415;6123.142760013601;6142.437386315926;6153.446189842846;6138.202033735586;6178.073289006595;6179.81460556184;6187.071939209065;6208.906866397007;6163.7842675506145;6209.751609935643;6227.875442491125;6240.375962403814;6253.6873156342;4792.976035119872;4874.488178741252;4881.536254055859;4916.213405855046;4920.089783390084;4402.625776809007;4404.867853964386;4412.7352358857925;4448.221561226773;4469.039156581285;5677.489025987681;5717.799877004285;5767.077267637242;5770.019096753478;5786.537077033827;6130.948521380002;6138.232720909887;6143.296949342275;6164.660943648053;6235.095911092866;5717.885642287131;5731.627444216113;5743.195952566644;5784.1902133701315;5787.71061446926;5349.625526213128;5379.54273886724;5380.43505431924;5391.406945236021;5398.1902714593125;6127.250613123855;6178.598391104564;6199.194104766532;6277.2153480814895;6281.905771413432;4679.859604211879;5085.974570127126;6207.286162091417;6213.347598502273;6216.844578885564;6235.781747638811;6290.559660823698;6314.810555683337;6318;6324;5637.267155269788;6098.359672234529;6112.685862051747;6187.9480488317995;6198.2252218472695;5196.974015130051;6147.739713358661;6177.876442471215;6201.720922558406;6214.005759078525;5910.468057874812;6114.266288045463;6121.938780612137;6123.908141377794;6142.95569753152;5931.495822854933;6016.2479690038745;6018.969905150446;6072.362401947908;6081.39186081406;5257.947420525898;5845.473907348359;5919.7040147991565;5951.821445356646;5986;6001.279846418458;6056.394360563909;6062.181605483203;6075.605085669594;6125.326214116354;5786.942130578809;5872.383399742966;5878.676672782867;5906.379830117948;5934.287885453773 21:55:38 INFO - PROCESS | 3238 | |1;ext.html;489.5153797740237;521.0854949563518;524.3131497737963;525.2174260352076;528.2525226804071;32961.34077318455;35842.64157358425;40854;40926.59073409265;40953.79523102384;42138.578614213846;42360.15279694404;42531.36202956958;42568;42635.78682106593;9576.233901287898;9736.51317434129;9804.411735295906;9814.411135331868;9836.606535738598;9641.662541811003;9766.609335626566;9768.36505627132;9770.169535589483;9770.951145244264;9739.172170365528;9762.755931101708;9765.365251258645;9766.853497197548;9791;9704.805903881917;9724;9766.853497197477;9771.413715177077;9778.022197780236;9582.616695332179;9705.175060119898;9711.417314961125;9711.562979665861;9712.562934667956;39710.60289397098;39909.20181596381;40076;40467.39298910519;40506.79746601248;3208.7593430492807;3274.214188594744;3341.7159541439005;3509.31568344173;3754.7747135171976;30172.24569385761;30715.38569228603;30926.0722178335;31034.534481982788;31454.925200115747;3188.984055079733;3224.564683767708;3234.4663130583663;3241.6110066792135;3323.102762254202;498887;503003;506184;507064;507548;59623.40376596178;60144.699276503336;60217.397826022054;60222;60278;33629.83185084108;34797.30405391878;34809.47785783215;63309.683451582445;63378;15792.36830526766;15890.443834465737;16073.196340182943;16131.354745810271;16168.75746863798;12399.25604463735;12485.500579976699;12500.437480313225;12501.93749031249;12503.437345319482;18240.45278641643;18314.45056648303;18378.540536486697;18385.54036149107;18397.356092536964;6524.608523488606;6858.417034552119;7040.662274167892;7376.520526165782;7687.692492300245;854.4318028511095;860.7331727164599;1019.2050200843354;1020.285799940045;1079.169039839335;21389.25137620176;23357.649635255486;29512.704872950995;29769.106926792232;30039;939.9295052871062;945.8155659646303;1004.5278719002057;1033.9948300258602;1081.6322450366913;12500.999920006378;15274.770878436831;15364.923175384052;15618;15707.371705131894;488573;488736;503532;562272;570007;46164.30753538699;52476.73761631167;54300;54594.18108728293;54991.45008549943;1331.3875617215995;1331.6338007047946;1365.556194236877;1372.3618517389261;1377.9173249604855;27866.58200126959;28540.001099961402;28966.855165724035;29342.972995945038;29396.26509337198;48293;49014;49094.509054908995;49132.508674912795;49239;440760;494534;504337;511914.8808512016;512836;25470;25758.969641214142;25818.48363032691;25898.093566725074;25914.22257332283;44575;44991.55008449873;44997.77501112474;45051.549484504736;45368.77315613401;36967.630323696416;37268.6273137276;37660.05849853776;38249.4262586067;38583;31515.896943606862;35337.64662353446;36610.63389366179;36789.81605091957;37731.62268377391;36329.63670363263;36950.63049369472;36974.445383318736;37317.44023839699;37442.43836342511;31755;32785.34429311448;33085;33257.334853303284;33294.50058249177;24691.259262222164;24894.502109958063;24919.87540062288;25065.87467062653;25441.618375724007 21:55:38 INFO - PROCESS | 3238 | |2;jquery.html;366.5015578812814;373.2702566482529;374.67964890019033;380.3951716770335;397.2710077008689;17800.64398712025;19419.805801941977;21073.683894741593;21317.893410532946;21567.35297941062;19978.100985455654;20147.59704805903;20653;20953.895230523864;21368.931553422306;5208.817691380802;5800;6005.939940600603;6065.029595264758;6079.361667024965;5950.5834591578605;5978.133170690247;6051.546134039964;6057.909131363033;6060.969695151518;5316.5215130638235;5843.678597677126;5877.6473411595225;5921.052631578948;5955.88088238235;5683.090705487102;5883.264591926009;5952.880942381172;5968.7612495500125;5986.431289027557;5148.948510514884;5173.922391164136;5183.352080989876;5186.740662966837;5199.792008319662;22153.556928861333;23705.81470926447;25521.361965951;25903.74096259051;25929.481410371685;2147.656374980011;2364.290712786157;2414.372263211578;2833.135893552466;3121.7814752967074;14972.625684357869;15226.771598426032;15749.685006299924;15821.446249381332;16165.595860103474;1880.5204672808484;1925.124068548807;1927.7108433734911;1927.9710804337947;1937.0992488492777;120678.39660801728;122736;123713;125656;126322;8834.249088827522;8998.86501702475;10224.415215641588;10365.689029329133;10866.07638350749;13003.219806811621;13712.588622341345;13743.793843092362;13781.586552403443;13792.517261895786;8218.876716849252;8271.917280827234;8402.117777633383;8416.452930559493;8578.485290882567;8324.417290789586;8346.457480263764;8376.581170941428;8567.485950842969;8619.698310559226;6654.434373078246;6686.531942764057;6712.865742685217;6728.192616886005;6765.42493888025;1795.7934837493856;1801.2884910460295;1805.1696219738806;1809.3124612647373;1830.7986121526621;902.7066203483894;929.3726734454216;955.7801705607749;996.4619105682995;998.9700308990742;21474;22455.66316505221;22490.100395984304;22519.662205066936;22629;910.4127837544879;914.6387177065022;921.9216366608779;950.9096635819586;951.8619800128943;11525.423728813526;11700.765984680258;11917.70205744846;11943.402829858473;11986.580469683518;547958;578204.1089794524;593961;606150;611545.9031392011;87891.24217515613;102553;116454;123874;129048;847.0428415890128;962.5572236771023;1022.7801022780138;1024.062982371137;1028.2596530498029;16351.754723678916;16794.58013549671;19581.671115910613;20029.29897453582;20362;57546;57564.42435575758;57633.423665762806;58045.646576506086;58614.70692646509;80245.59877200576;84018.15981840104;85331.14668853232;85572;86826;7843.372530197685;8100.83798324042;8335.624896879533;8378.162183781817;8570.74287771368;9250.936142343717;13265.933670331586;13315.933420332836;13352;13963.511277105252;11269.380184089954;11569.942150289195;11703.824442633537;11710.531578736754;11758.412079396167;8865.271272102136;10996.395198264172;11005.669829905115;11547.9298769116;11595.304281743123;11511.942440287745;11590.291106845729;11727.944484996391;11845;11918.225315354473;7193;7375.041244638162;7896.565688887166;8217.506949583045;8358.373122015995;10558.208134390105;11395.487203076047;11629.47667354988;11699.766004680028;11782.882171178178 21:55:38 INFO - PROCESS | 3238 | |3;mootools.html;1309.8100775387572;1501.678522899848;1648.0717560454268;1857.331360710145;1866.7573215481984;7984.041914970212;8557.400981931267;8638.827223455528;9158.221551168142;9187.402818816781;8788.8681669775;8801.251893589037;8890.44417226986;8909.153630405117;8909.509976951282;2184.2246002669062;2327.2552783109413;2389.2593296078226;2442.682451281331;2443.4380092578717;2100.9264675736354;2400.975990240093;2419.4677171022345;2454.6808914841044;2457.631355296711;2437.890294936732;2444.2422848916804;2447.9265622031367;2450.8774561271866;2465.790407815338;2083.1771450277133;2361.244401791427;2419.6249581314923;2431.4407686232175;2438.0247900839627;1920.2127127877643;2315.8610483371053;2318.8840557972035;2320.385097949045;2328.0571368595747;8909.109089091038;8964.327675424303;9005.774855628662;9013;9040.095990400972;4980.103581355354;5270.815521456769;5411.810586629449;5416.566674666017;5430.294061771984;3241.9675803241903;3374.139594403436;3464.9133771655656;3623.2934577757346;3659.8536058557625;1081.7620123572801;1103.2222283290262;1107.164091111214;1117.6479408986224;1124.65135807899;11705.590304339306;11787.705307367298;11805.937465628134;11899.762004759856;11921;4573.314002899525;4650.883727906829;4846.588040016638;4859.465458799529;4878.170710979142;9964.551595178234;9975.750606234757;10050.095491405662;10055.994400559886;10096;5398.352197736218;5405.486478784509;5432.619716619799;5437.673739575638;5440.156775699773;4292.463442069741;4456.710313829655;4962.826301079445;4980.327655766425;4993.176125939252;3905.824237909301;3926.5877082906454;4003.379476181138;4049.2104039712563;4079.673626109904;1255.1966741285585;1271.8664540223328;1305.6996890715188;1318.967025824343;1330.3880215101162;797.7128233835814;825.6614787936917;914.8078903430354;927.1146055516965;933.3093510802094;5842.182094506772;5938.317093534212;5962.701864906824;6079.6960151992225;6119.632822030692;433.6704104880314;962.629387685746;982.6511588386163;1025.5333823110466;1036.43514284715;3450.2064525159353;3492.8078955657684;3621.9818900905325;3623.3659109655705;3628.745987780883;8800.647974081094;8854.380193386402;10629.255952083435;11012.17408694335;11070.225084244026;10757.462126893624;10761.24671273019;10774.515146818412;10778.461076946121;10778.622748203776;8141.389395795339;8180.6318715657935;8288.336933045342;8329.791755206168;8370.581470926429;5807.738651760596;5874.265716785402;5915.497182739516;5947.821565353046;6029.306629737636;10090.94954525252;10190.694279171637;10210.234232432746;10243.07812296867;10243.7439064024;9945.403275803475;10224.079832815083;10277.486125693835;10333.018363255478;10399.94800025995;6500.7399704013205;6956.686949087202;7065.434765218667;7941.205879412013;8299.668013279643;8344.666213351398;8456.365772566958;8523.232909038215;8541.188587084218;8555.786105347415;7251.746188883575;7426.925730742623;7515.248475152441;7546.962265188639;7619.390448764197;7221.169565499825;7327.303906128909;7415.666295016629;7431.29402706742;7439.107307123179;6540.659164871095;6805.795826125224;6988;7018.227994920452;7085;4187.895302617337;4786.497417771223;4999.300097986212;5059.822906198413;5062.316587260672;4208.473940757405;5001.57486613642;5059.494050594911;5174.8965020700125;5179.171332586768 21:55:38 INFO - PROCESS | 3238 | |4;prototype.html;292.1148918776108;302.3921916946936;307.66771886362733;308.76842368223834;309.62071462458493;4314.697971142014;4551.815128738208;5405.243265942771;5511.897620475911;5566.749496272667;4748.786304616292;5078;5135.255387968742;5527.39198688144;5560.916586251199;3861.3242682530445;3880.5343358796986;4182.64447521961;4245.172191422674;4272.957270427302;3917.764934103963;4179.0806022675115;4243.066525364415;4263.4031235627035;4271.796152885336;3815.637514436124;4174.895627609319;4184.435101261336;4185.765199266209;4193.1404062167185;4200.7479551227025;4215.178040282161;4223.619874211336;4223.704340696153;4224.5353011168745;3264.265540253424;3516.613172551017;3683.5948045715218;3711.406175011985;3721.776693398405;5253.711045892474;5328.573714102862;5369;5403.243545903577;5418.376886658046;4832.637552183565;4835.975820120877;4837;4882.41411030675;4890.853274401774;4954.826581069645;5305.495977882095;5805.680687562182;5848.67832269225;5870.9158248821905;3754.0614846288427;3833.099221682902;4032.8386864525382;4047.959520404788;4055.655269302113;5823.912641310384;6032.215811944419;6066.757329706807;6097.634141951497;6129.356417576135;3434.4161492546327;3446.965530344715;3460.9826950865086;4075.286824805647;4103.850921741858;3468.4277094279664;4024.2756303865;4141.840284720283;4148.23257697327;4155.97922010388;3769.547654281449;4162.791860406967;4733.763311834395;4742.624638854693;4823.686460380134;4394.472663280427;5508.614396992252;5750.482456578928;5765.769369225184;5875.059990401515;1095.1950316517352;1276.8531618863765;1317.7562151002096;1371.35546293242;1432.3697573067816;810.295043312322;825.8513467575895;880.335346813152;930.0234753508923;944.3248077624531;5341.278927344836;5452.700101494456;5455.263539422207;5464.3442786865835;5489.462950373902;5139.665921715076;5748.13777933305;5942.910856337158;5970.34326224115;5994.880102397928;5155.561777248976;5228.2941802856885;5428;5444.292242008592;5460.781568737286;2654.296611397981;2657.627932089509;2669.8932042718075;2672.492226477002;2676.9693667937986;4706.670533062653;4997.27757269377;5183.377994640668;5189.896202075937;5235.895282094337;4391.67062470316;4850.393700787402;4885.892879029293;5019.494151754459;5086.745662716849;4622.61935103422;4781.418034261529;4868;4984.626153038536;5207.505286997729;3806.733528652968;4535.478419981744;4938.604911607134;5019.949800501949;5059.772310245974;4317.870463886088;4410.514843367163;4567.429071366079;5280.339957505312;5927.4665280125;5240.764165612481;6321.873562528632;6349.301576826638;6354.52341074412;6363.204599425072;3282.8522716477337;3664.992127165051;3693.7045036397562;3841.3661745812747;3955.4857868476915;4877.878157978826;5240.71176085319;5777.7977770777825;6058.5153187745755;6111.358307377662;5356.705381203916;5358.571314294925;5660.660360378391;5907.556933230111;5912.349641539341;4901.828436004723;5873.85459835328;5885.529157667462;5891.764329426775;5910.379410161871;4854.708717476963;4978.137570896676;5624.353199382123;5712.457316554921;5772.307323121252;5877.823665290048;5899.52220067564;5902.173695682522;5911.290645122441;5918.141869428822;5071.416787069386;5516.696581688046;5708.828735137952;5761.39505351932;5837.5038121758425 21:55:38 INFO - PROCESS | 3238 | |5;yui.html;737.6791095873294;744.5160645580372;766.3830616353833;800.7637746864674;854.743576926922;14821.184834834079;15693.136877471748;15694.52916412508;17021.659566808656;17764;15998.280077396515;16081.678366432665;16125.596860078474;16129.919350403261;16135.83864161358;828.1801016993163;847.7414388611471;850.2050582705159;851.2041241439274;851.2126283188027;660.141815639669;785.6032703484732;828.3704384667635;859.222403724627;861.8577934640808;881.7927786970057;886.995565022174;889.7286327670058;890.1587999340626;890.6437425029975;853.3514528957975;876.2551830943679;885.6191837509868;886.2644005475504;887.0508555845248;649.938255865692;653.6470306034736;653.7777155767014;656.1306269193337;658.6739563915844;15282.235888205545;15309.540713778604;15394.538163855103;15418.768718469231;15435.922820385938;13966.860331396658;14012.299385030708;14068.577942661737;14076.436942522287;14257.572272831832;11646.184767066308;11708.531658733726;11759.118066144987;11895.07909834844;11941;3693.353663108972;3734.2344819312248;3742.7145917923826;3759.3233218020746;3875.844966201377;120379;121594;124137.37931310285;124739.75260247372;126805.36597317047;10648.307859989076;10651.034059345044;10694.786104277871;10725.731856703645;10900;22163.44591385196;22352.88823555872;22379.328620141423;22384.440388990406;22413.543119697493;6203.976343903296;6805.829854253683;7533.849323013618;7780.377569794516;7882.7241046563095;4431.357453169337;4475.746790898554;4481.170983368087;4535.614472769786;4611.077784443125;3201.4717571600427;3209.7642407673216;3513.2622149348413;3584.121890136933;3890.319194141014;705.4250785609712;717.9625441237362;724.4096061709795;728.420905380224;747.3535391886088;532.48615086441;618.6226401894841;631.7757196195377;655.1737850950473;666.1306994372294;8335.54154521495;8844.071372505794;9239.39943903644;9275.62897484093;9506.38208516458;630.7760744935482;631.2961048430966;657.8322527755391;666.6600133731139;667.5727534378002;4226.619604235572;4319.322020347405;4364.367166760802;4664.510226426244;4672;116610;130534.34732826464;169951;170527;173357;82560;144761.82857257148;148014;148231;148272.51727482802;123036;123087;124426;124864.3756781192;127039;8291.917080829357;8690.696965304325;8716.041235464221;8716.738497845075;8880.555972201493;66749.66625166843;67516.32483675101;68708.31291687019;69033.27416814619;70157.29842701508;96968.51515742658;102054;103880.96119038713;105361;106713.9328606704;11476.770464590494;11650.242734222249;11750.29498230109;11800;11941.402929853646;12849.550265740654;12930.870691292967;12947.352632368156;12964.22214667123;13250.602481925558;11982.395579912016;12130.575429859913;12220.45007974661;12228;12621.116521843383;11764.235324703866;11819.82270265964;11965.162438629213;11983.221090629082;12316.384180791103;11854.170208085352;12013.159078864745;12038.879611203776;12228.309414637206;12434.937825310815;8951.776205594651;8994.055624159631;9272.258219342571;9416.95291523538;9466.621335146792;7473.43949203801;8160.26557609818;8724.302055835646;9438.339316248072;9621.037896210322 21:55:38 INFO - PROCESS | 3238 | __end_tp_report 21:55:38 INFO - PROCESS | 3238 | __start_cc_report 21:55:38 INFO - PROCESS | 3238 | _x_x_mozilla_cycle_collect,389 21:55:38 INFO - PROCESS | 3238 | __end_cc_report 21:55:38 INFO - PROCESS | 3238 | __startTimestamp1473396938118__endTimestamp 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | ------- Summary: start ------- 21:55:38 INFO - PROCESS | 3238 | Number of tests: 6 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | [#0] dojo.html Cycles:165 Average:5655.57 Median:5872.38 stddev:664.20 (11.3%) stddev-sans-first:639.36 21:55:38 INFO - PROCESS | 3238 | Values: 3271.3 3312.4 3320.1 3341.4 3343.9 4978.8 5596.6 5597.9 5603.7 5693.3 5445.9 5678.8 5702.2 5711.6 5810.0 5076.7 5159.4 5179.9 5224.0 5231.4 5210.2 5231.7 5273.4 5276.1 5294.2 5067.2 5097.5 5154.3 5169.0 5175.7 5148.0 5152.1 5157.9 5161.8 5207.9 5097.1 5120.7 5139.7 5148.3 5148.4 4246.0 4431.0 4561.5 5401.3 5575.9 6185.9 6191.7 6225.4 6242.8 6244.1 6192.6 6238.3 6249.4 6256.7 6265.9 5633.2 5725.9 5728.5 5747.0 5750.3 6191.8 6207.8 6215.4 6220.7 6227.5 6064.9 6135.4 6142.8 6153.2 6160.1 6098.8 6104.8 6123.1 6142.4 6153.4 6138.2 6178.1 6179.8 6187.1 6208.9 6163.8 6209.8 6227.9 6240.4 6253.7 4793.0 4874.5 4881.5 4916.2 4920.1 4402.6 4404.9 4412.7 4448.2 4469.0 5677.5 5717.8 5767.1 5770.0 5786.5 6130.9 6138.2 6143.3 6164.7 6235.1 5717.9 5731.6 5743.2 5784.2 5787.7 5349.6 5379.5 5380.4 5391.4 5398.2 6127.3 6178.6 6199.2 6277.2 6281.9 4679.9 5086.0 6207.3 6213.3 6216.8 6235.8 6290.6 6314.8 6318.0 6324.0 5637.3 6098.4 6112.7 6187.9 6198.2 5197.0 6147.7 6177.9 6201.7 6214.0 5910.5 6114.3 6121.9 6123.9 6143.0 5931.5 6016.2 6019.0 6072.4 6081.4 5257.9 5845.5 5919.7 5951.8 5986.0 6001.3 6056.4 6062.2 6075.6 6125.3 5786.9 5872.4 5878.7 5906.4 5934.3 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | [#1] ext.html Cycles:180 Average:63942.73 Median:39146.80 stddev:135153.54 (345.2%) stddev-sans-first:135448.72 21:55:38 INFO - PROCESS | 3238 | Values: 489.5 521.1 524.3 525.2 528.3 32961.3 35842.6 40854.0 40926.6 40953.8 42138.6 42360.2 42531.4 42568.0 42635.8 9576.2 9736.5 9804.4 9814.4 9836.6 9641.7 9766.6 9768.4 9770.2 9771.0 9739.2 9762.8 9765.4 9766.9 9791.0 9704.8 9724.0 9766.9 9771.4 9778.0 9582.6 9705.2 9711.4 9711.6 9712.6 39710.6 39909.2 40076.0 40467.4 40506.8 3208.8 3274.2 3341.7 3509.3 3754.8 30172.2 30715.4 30926.1 31034.5 31454.9 3189.0 3224.6 3234.5 3241.6 3323.1 498887.0 503003.0 506184.0 507064.0 507548.0 59623.4 60144.7 60217.4 60222.0 60278.0 33629.8 34797.3 34809.5 63309.7 63378.0 15792.4 15890.4 16073.2 16131.4 16168.8 12399.3 12485.5 12500.4 12501.9 12503.4 18240.5 18314.5 18378.5 18385.5 18397.4 6524.6 6858.4 7040.7 7376.5 7687.7 854.4 860.7 1019.2 1020.3 1079.2 21389.3 23357.6 29512.7 29769.1 30039.0 939.9 945.8 1004.5 1034.0 1081.6 12501.0 15274.8 15364.9 15618.0 15707.4 488573.0 488736.0 503532.0 562272.0 570007.0 46164.3 52476.7 54300.0 54594.2 54991.5 1331.4 1331.6 1365.6 1372.4 1377.9 27866.6 28540.0 28966.9 29343.0 29396.3 48293.0 49014.0 49094.5 49132.5 49239.0 440760.0 494534.0 504337.0 511914.9 512836.0 25470.0 25759.0 25818.5 25898.1 25914.2 44575.0 44991.6 44997.8 45051.5 45368.8 36967.6 37268.6 37660.1 38249.4 38583.0 31515.9 35337.6 36610.6 36789.8 37731.6 36329.6 36950.6 36974.4 37317.4 37442.4 31755.0 32785.3 33085.0 33257.3 33294.5 24691.3 24894.5 24919.9 25065.9 25441.6 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | [#2] jquery.html Cycles:180 Average:34970.55 Median:14381.31 stddev:98088.46 (682.1%) stddev-sans-first:98329.21 21:55:38 INFO - PROCESS | 3238 | Values: 366.5 373.3 374.7 380.4 397.3 17800.6 19419.8 21073.7 21317.9 21567.4 19978.1 20147.6 20653.0 20953.9 21368.9 5208.8 5800.0 6005.9 6065.0 6079.4 5950.6 5978.1 6051.5 6057.9 6061.0 5316.5 5843.7 5877.6 5921.1 5955.9 5683.1 5883.3 5952.9 5968.8 5986.4 5148.9 5173.9 5183.4 5186.7 5199.8 22153.6 23705.8 25521.4 25903.7 25929.5 2147.7 2364.3 2414.4 2833.1 3121.8 14972.6 15226.8 15749.7 15821.4 16165.6 1880.5 1925.1 1927.7 1928.0 1937.1 120678.4 122736.0 123713.0 125656.0 126322.0 8834.2 8998.9 10224.4 10365.7 10866.1 13003.2 13712.6 13743.8 13781.6 13792.5 8218.9 8271.9 8402.1 8416.5 8578.5 8324.4 8346.5 8376.6 8567.5 8619.7 6654.4 6686.5 6712.9 6728.2 6765.4 1795.8 1801.3 1805.2 1809.3 1830.8 902.7 929.4 955.8 996.5 999.0 21474.0 22455.7 22490.1 22519.7 22629.0 910.4 914.6 921.9 950.9 951.9 11525.4 11700.8 11917.7 11943.4 11986.6 547958.0 578204.1 593961.0 606150.0 611545.9 87891.2 102553.0 116454.0 123874.0 129048.0 847.0 962.6 1022.8 1024.1 1028.3 16351.8 16794.6 19581.7 20029.3 20362.0 57546.0 57564.4 57633.4 58045.6 58614.7 80245.6 84018.2 85331.1 85572.0 86826.0 7843.4 8100.8 8335.6 8378.2 8570.7 9250.9 13265.9 13315.9 13352.0 13963.5 11269.4 11569.9 11703.8 11710.5 11758.4 8865.3 10996.4 11005.7 11547.9 11595.3 11511.9 11590.3 11727.9 11845.0 11918.2 7193.0 7375.0 7896.6 8217.5 8358.4 10558.2 11395.5 11629.5 11699.8 11782.9 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | [#3] mootools.html Cycles:180 Average:5721.49 Median:4853.03 stddev:3231.68 (66.6%) stddev-sans-first:3223.74 21:55:38 INFO - PROCESS | 3238 | Values: 1309.8 1501.7 1648.1 1857.3 1866.8 7984.0 8557.4 8638.8 9158.2 9187.4 8788.9 8801.3 8890.4 8909.2 8909.5 2184.2 2327.3 2389.3 2442.7 2443.4 2100.9 2401.0 2419.5 2454.7 2457.6 2437.9 2444.2 2447.9 2450.9 2465.8 2083.2 2361.2 2419.6 2431.4 2438.0 1920.2 2315.9 2318.9 2320.4 2328.1 8909.1 8964.3 9005.8 9013.0 9040.1 4980.1 5270.8 5411.8 5416.6 5430.3 3242.0 3374.1 3464.9 3623.3 3659.9 1081.8 1103.2 1107.2 1117.6 1124.7 11705.6 11787.7 11805.9 11899.8 11921.0 4573.3 4650.9 4846.6 4859.5 4878.2 9964.6 9975.8 10050.1 10056.0 10096.0 5398.4 5405.5 5432.6 5437.7 5440.2 4292.5 4456.7 4962.8 4980.3 4993.2 3905.8 3926.6 4003.4 4049.2 4079.7 1255.2 1271.9 1305.7 1319.0 1330.4 797.7 825.7 914.8 927.1 933.3 5842.2 5938.3 5962.7 6079.7 6119.6 433.7 962.6 982.7 1025.5 1036.4 3450.2 3492.8 3622.0 3623.4 3628.7 8800.6 8854.4 10629.3 11012.2 11070.2 10757.5 10761.2 10774.5 10778.5 10778.6 8141.4 8180.6 8288.3 8329.8 8370.6 5807.7 5874.3 5915.5 5947.8 6029.3 10090.9 10190.7 10210.2 10243.1 10243.7 9945.4 10224.1 10277.5 10333.0 10399.9 6500.7 6956.7 7065.4 7941.2 8299.7 8344.7 8456.4 8523.2 8541.2 8555.8 7251.7 7426.9 7515.2 7547.0 7619.4 7221.2 7327.3 7415.7 7431.3 7439.1 6540.7 6805.8 6988.0 7018.2 7085.0 4187.9 4786.5 4999.3 5059.8 5062.3 4208.5 5001.6 5059.5 5174.9 5179.2 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | [#4] prototype.html Cycles:180 Average:4541.24 Median:4966.48 stddev:1409.43 (28.4%) stddev-sans-first:1376.82 21:55:38 INFO - PROCESS | 3238 | Values: 292.1 302.4 307.7 308.8 309.6 4314.7 4551.8 5405.2 5511.9 5566.7 4748.8 5078.0 5135.3 5527.4 5560.9 3861.3 3880.5 4182.6 4245.2 4273.0 3917.8 4179.1 4243.1 4263.4 4271.8 3815.6 4174.9 4184.4 4185.8 4193.1 4200.7 4215.2 4223.6 4223.7 4224.5 3264.3 3516.6 3683.6 3711.4 3721.8 5253.7 5328.6 5369.0 5403.2 5418.4 4832.6 4836.0 4837.0 4882.4 4890.9 4954.8 5305.5 5805.7 5848.7 5870.9 3754.1 3833.1 4032.8 4048.0 4055.7 5823.9 6032.2 6066.8 6097.6 6129.4 3434.4 3447.0 3461.0 4075.3 4103.9 3468.4 4024.3 4141.8 4148.2 4156.0 3769.5 4162.8 4733.8 4742.6 4823.7 4394.5 5508.6 5750.5 5765.8 5875.1 1095.2 1276.9 1317.8 1371.4 1432.4 810.3 825.9 880.3 930.0 944.3 5341.3 5452.7 5455.3 5464.3 5489.5 5139.7 5748.1 5942.9 5970.3 5994.9 5155.6 5228.3 5428.0 5444.3 5460.8 2654.3 2657.6 2669.9 2672.5 2677.0 4706.7 4997.3 5183.4 5189.9 5235.9 4391.7 4850.4 4885.9 5019.5 5086.7 4622.6 4781.4 4868.0 4984.6 5207.5 3806.7 4535.5 4938.6 5019.9 5059.8 4317.9 4410.5 4567.4 5280.3 5927.5 5240.8 6321.9 6349.3 6354.5 6363.2 3282.9 3665.0 3693.7 3841.4 3955.5 4877.9 5240.7 5777.8 6058.5 6111.4 5356.7 5358.6 5660.7 5907.6 5912.3 4901.8 5873.9 5885.5 5891.8 5910.4 4854.7 4978.1 5624.4 5712.5 5772.3 5877.8 5899.5 5902.2 5911.3 5918.1 5071.4 5516.7 5708.8 5761.4 5837.5 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - PROCESS | 3238 | [#5] yui.html Cycles:180 Average:26097.79 Median:3817.58 stddev:43230.74 (1132.4%) stddev-sans-first:43310.08 21:55:38 INFO - PROCESS | 3238 | Values: 737.7 744.5 766.4 800.8 854.7 14821.2 15693.1 15694.5 17021.7 17764.0 15998.3 16081.7 16125.6 16129.9 16135.8 828.2 847.7 850.2 851.2 851.2 660.1 785.6 828.4 859.2 861.9 881.8 887.0 889.7 890.2 890.6 853.4 876.3 885.6 886.3 887.1 649.9 653.6 653.8 656.1 658.7 15282.2 15309.5 15394.5 15418.8 15435.9 13966.9 14012.3 14068.6 14076.4 14257.6 11646.2 11708.5 11759.1 11895.1 11941.0 3693.4 3734.2 3742.7 3759.3 3875.8 120379.0 121594.0 124137.4 124739.8 126805.4 10648.3 10651.0 10694.8 10725.7 10900.0 22163.4 22352.9 22379.3 22384.4 22413.5 6204.0 6805.8 7533.8 7780.4 7882.7 4431.4 4475.7 4481.2 4535.6 4611.1 3201.5 3209.8 3513.3 3584.1 3890.3 705.4 718.0 724.4 728.4 747.4 532.5 618.6 631.8 655.2 666.1 8335.5 8844.1 9239.4 9275.6 9506.4 630.8 631.3 657.8 666.7 667.6 4226.6 4319.3 4364.4 4664.5 4672.0 116610.0 130534.3 169951.0 170527.0 173357.0 82560.0 144761.8 148014.0 148231.0 148272.5 123036.0 123087.0 124426.0 124864.4 127039.0 8291.9 8690.7 8716.0 8716.7 8880.6 66749.7 67516.3 68708.3 69033.3 70157.3 96968.5 102054.0 103881.0 105361.0 106713.9 11476.8 11650.2 11750.3 11800.0 11941.4 12849.6 12930.9 12947.4 12964.2 13250.6 11982.4 12130.6 12220.5 12228.0 12621.1 11764.2 11819.8 11965.2 11983.2 12316.4 11854.2 12013.2 12038.9 12228.3 12434.9 8951.8 8994.1 9272.3 9417.0 9466.6 7473.4 8160.3 8724.3 9438.3 9621.0 21:55:38 INFO - PROCESS | 3238 | -------- Summary: end -------- 21:55:38 INFO - PROCESS | 3238 | 21:55:38 INFO - TEST-INFO | 3238: exit 0 21:55:38 INFO - TEST-OK | dromaeo_css | took 1113555ms 21:55:38 INFO - TEST-START | kraken 21:55:38 INFO - operating with platform_type : mac_ 21:55:38 INFO - Initialising browser for kraken test... 21:55:38 INFO - TEST-INFO | started process 3243 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpp67yv4/profile http://localhost:56049/getInfo.html) 21:55:41 INFO - PROCESS | 3243 | __metrics Screen width/height:1600/1200 21:55:41 INFO - PROCESS | 3243 | colorDepth:24 21:55:41 INFO - PROCESS | 3243 | Browser inner width/height: 1024/689 21:55:41 INFO - PROCESS | 3243 | __metrics 21:55:41 ERROR - PROCESS | 3243 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 21:55:42 INFO - TEST-INFO | 3243: exit 0 21:55:42 INFO - Browser initialized. 21:55:42 INFO - Running cycle 1/1 for kraken test... 21:55:42 INFO - TEST-INFO | started process 3245 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpp67yv4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:54 INFO - PROCESS | 3245 | RSS: Main: 244084736 21:55:54 INFO - PROCESS | 3245 | 21:55:57 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56049/tests/kraken/driver.html?testName=audio-beat-detection) 21:55:58 INFO - PROCESS | 3245 | RSS: Main: 257028096 21:55:58 INFO - PROCESS | 3245 | 21:56:00 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56049/tests/kraken/driver.html?testName=audio-dft) 21:56:01 INFO - PROCESS | 3245 | RSS: Main: 265601024 21:56:01 INFO - PROCESS | 3245 | 21:56:05 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56049/tests/kraken/driver.html?testName=audio-fft) 21:56:05 INFO - PROCESS | 3245 | RSS: Main: 264507392 21:56:05 INFO - PROCESS | 3245 | 21:56:07 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56049/tests/kraken/driver.html?testName=audio-oscillator) 21:56:08 INFO - PROCESS | 3245 | RSS: Main: 266825728 21:56:08 INFO - PROCESS | 3245 | 21:56:10 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56049/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:56:11 INFO - PROCESS | 3245 | RSS: Main: 267366400 21:56:11 INFO - PROCESS | 3245 | 21:56:14 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56049/tests/kraken/driver.html?testName=imaging-darkroom) 21:56:14 INFO - PROCESS | 3245 | RSS: Main: 267878400 21:56:14 INFO - PROCESS | 3245 | 21:56:17 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56049/tests/kraken/driver.html?testName=imaging-desaturate) 21:56:18 INFO - PROCESS | 3245 | RSS: Main: 268521472 21:56:18 INFO - PROCESS | 3245 | 21:56:21 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56049/tests/kraken/driver.html?testName=json-parse-financial) 21:56:22 INFO - PROCESS | 3245 | RSS: Main: 268296192 21:56:22 INFO - PROCESS | 3245 | 21:56:23 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56049/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:56:24 INFO - PROCESS | 3245 | RSS: Main: 268218368 21:56:24 INFO - PROCESS | 3245 | 21:56:26 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:56:27 INFO - PROCESS | 3245 | RSS: Main: 267821056 21:56:27 INFO - PROCESS | 3245 | 21:56:29 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:56:29 INFO - PROCESS | 3245 | RSS: Main: 267935744 21:56:29 INFO - PROCESS | 3245 | 21:56:32 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:56:33 INFO - PROCESS | 3245 | RSS: Main: 268038144 21:56:33 INFO - PROCESS | 3245 | 21:56:36 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:56:36 INFO - PROCESS | 3245 | RSS: Main: 268050432 21:56:36 INFO - PROCESS | 3245 | 21:56:38 INFO - PROCESS | 3245 | Cycle 1(1): loaded http://localhost:56049/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56049/tests/kraken/driver.html?testName=ai-astar) 21:56:39 INFO - PROCESS | 3245 | RSS: Main: 270016512 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | __start_tp_report 21:56:39 INFO - PROCESS | 3245 | _x_x_mozilla_page_load 21:56:39 INFO - PROCESS | 3245 | _x_x_mozilla_page_load_details 21:56:39 INFO - PROCESS | 3245 | |i|pagename|runs| 21:56:39 INFO - PROCESS | 3245 | |0;ai-astar;107;119;164;161;118;119;115;102;171;186 21:56:39 INFO - PROCESS | 3245 | |1;audio-beat-detection;150;110;157;164;136;165;136;133;178;125 21:56:39 INFO - PROCESS | 3245 | |2;audio-dft;209;238;214;222;274;245;197;192;200;201 21:56:39 INFO - PROCESS | 3245 | |3;audio-fft;69;75;67;151;86;85;83;60;156;53 21:56:39 INFO - PROCESS | 3245 | |4;audio-oscillator;69;71;124;70;68;78;110;72;76;124 21:56:39 INFO - PROCESS | 3245 | |5;imaging-gaussian-blur;92;89;91;85;137;129;118;130;99;121 21:56:39 INFO - PROCESS | 3245 | |6;imaging-darkroom;102;101;163;157;101;101;101;143;175;102 21:56:39 INFO - PROCESS | 3245 | |7;imaging-desaturate;116;140;93;91;91;92;90;90;90;113 21:56:39 INFO - PROCESS | 3245 | |8;json-parse-financial;57;59;61;69;89;86;86;58;55;58 21:56:39 INFO - PROCESS | 3245 | |9;json-stringify-tinderbox;68;69;72;73;71;84;76;72;72;74 21:56:39 INFO - PROCESS | 3245 | |10;stanford-crypto-aes;87;84;87;88;87;86;88;89;89;87 21:56:39 INFO - PROCESS | 3245 | |11;stanford-crypto-ccm;140;170;151;158;153;151;158;164;192;145 21:56:39 INFO - PROCESS | 3245 | |12;stanford-crypto-pbkdf2;174;175;174;176;172;172;169;170;171;173 21:56:39 INFO - PROCESS | 3245 | |13;stanford-crypto-sha256-iterative;67;69;69;69;69;67;60;82;88;87 21:56:39 INFO - PROCESS | 3245 | __end_tp_report 21:56:39 INFO - PROCESS | 3245 | __start_cc_report 21:56:39 INFO - PROCESS | 3245 | _x_x_mozilla_cycle_collect,947 21:56:39 INFO - PROCESS | 3245 | __end_cc_report 21:56:39 INFO - PROCESS | 3245 | __startTimestamp1473396999034__endTimestamp 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | ------- Summary: start ------- 21:56:39 INFO - PROCESS | 3245 | Number of tests: 14 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#0] ai-astar Cycles:10 Average:136.20 Median:140.00 stddev:30.68 (21.9%) stddev-sans-first:30.67 21:56:39 INFO - PROCESS | 3245 | Values: 107.0 119.0 164.0 161.0 118.0 119.0 115.0 102.0 171.0 186.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:153.50 stddev:20.95 (13.6%) stddev-sans-first:22.15 21:56:39 INFO - PROCESS | 3245 | Values: 150.0 110.0 157.0 164.0 136.0 165.0 136.0 133.0 178.0 125.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#2] audio-dft Cycles:10 Average:219.20 Median:218.00 stddev:26.02 (11.9%) stddev-sans-first:27.34 21:56:39 INFO - PROCESS | 3245 | Values: 209.0 238.0 214.0 222.0 274.0 245.0 197.0 192.0 200.0 201.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#3] audio-fft Cycles:10 Average:88.50 Median:72.00 stddev:35.91 (49.9%) stddev-sans-first:37.39 21:56:39 INFO - PROCESS | 3245 | Values: 69.0 75.0 67.0 151.0 86.0 85.0 83.0 60.0 156.0 53.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:70.50 stddev:23.38 (33.2%) stddev-sans-first:23.95 21:56:39 INFO - PROCESS | 3245 | Values: 69.0 71.0 124.0 70.0 68.0 78.0 110.0 72.0 76.0 124.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:87.00 stddev:19.83 (22.8%) stddev-sans-first:20.04 21:56:39 INFO - PROCESS | 3245 | Values: 92.0 89.0 91.0 85.0 137.0 129.0 118.0 130.0 99.0 121.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#6] imaging-darkroom Cycles:10 Average:124.60 Median:122.50 stddev:31.01 (25.3%) stddev-sans-first:31.79 21:56:39 INFO - PROCESS | 3245 | Values: 102.0 101.0 163.0 157.0 101.0 101.0 101.0 143.0 175.0 102.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#7] imaging-desaturate Cycles:10 Average:100.60 Median:90.50 stddev:16.98 (18.8%) stddev-sans-first:17.08 21:56:39 INFO - PROCESS | 3245 | Values: 116.0 140.0 93.0 91.0 91.0 92.0 90.0 90.0 90.0 113.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:65.00 stddev:13.78 (21.2%) stddev-sans-first:14.05 21:56:39 INFO - PROCESS | 3245 | Values: 57.0 59.0 61.0 69.0 89.0 86.0 86.0 58.0 55.0 58.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:72.50 stddev:4.46 (6.1%) stddev-sans-first:4.33 21:56:39 INFO - PROCESS | 3245 | Values: 68.0 69.0 72.0 73.0 71.0 84.0 76.0 72.0 72.0 74.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:1.48 (1.7%) stddev-sans-first:1.56 21:56:39 INFO - PROCESS | 3245 | Values: 87.0 84.0 87.0 88.0 87.0 86.0 88.0 89.0 89.0 87.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:158.00 stddev:14.73 (9.3%) stddev-sans-first:14.07 21:56:39 INFO - PROCESS | 3245 | Values: 140.0 170.0 151.0 158.0 153.0 151.0 158.0 164.0 192.0 145.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.50 stddev:2.22 (1.3%) stddev-sans-first:2.30 21:56:39 INFO - PROCESS | 3245 | Values: 174.0 175.0 174.0 176.0 172.0 172.0 169.0 170.0 171.0 173.0 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - PROCESS | 3245 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:69.00 stddev:9.46 (13.7%) stddev-sans-first:9.81 21:56:39 INFO - PROCESS | 3245 | Values: 67.0 69.0 69.0 69.0 69.0 67.0 60.0 82.0 88.0 87.0 21:56:39 INFO - PROCESS | 3245 | -------- Summary: end -------- 21:56:39 INFO - PROCESS | 3245 | 21:56:39 INFO - TEST-INFO | 3245: exit 0 21:56:39 INFO - TEST-OK | kraken | took 61227ms 21:56:39 INFO - SUITE-END | took 1174s 21:56:40 INFO - Completed test suite (00:19:35) 21:56:40 INFO - javascript benchmark 21:56:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7317.959120535599, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.3130242649027, 3312.3706495765805, 3320.103572035551, 3341.365140623283, 3343.9498407523893, 4978.829974955883, 5596.63621864579, 5597.916031259534, 5603.719814009299, 5693.28833895763, 5445.891082178354, 5678.801241956522, 5702.230198923134, 5711.600187986843, 5810.012297909366, 5076.670016448919, 5159.406668233145, 5179.922301165486, 5224.0, 5231.410397016892, 5210.16637338026, 5231.73841307935, 5273.393559740622, 5276.103062479368, 5294.232336311233, 5067.189249720064, 5097.515736005073, 5154.252633368141, 5168.974155129238, 5175.715335656538, 5148.0, 5152.098382783035, 5157.896842063175, 5161.845144645667, 5207.895842083137, 5097.057044446752, 5120.718360490171, 5139.665921715114, 5148.304978827848, 5148.433672296082, 4245.97877010613, 4431.0, 4561.521040290788, 5401.297831281908, 5575.944240557583, 6185.948388773919, 6191.659458729768, 6225.439710426038, 6242.781502647385, 6244.0633904914175, 6192.566520343577, 6238.282597501255, 6249.406306400884, 6256.6871656417, 6265.937340626581, 5633.211350410946, 5725.885482290414, 5728.48443640066, 5747.0, 5750.338711048283, 6191.845203869849, 6207.81376558704, 6215.409536094064, 6220.657863817533, 6227.501799856001, 6064.878702426015, 6135.417135372132, 6142.846428839225, 6153.230846144232, 6160.1375807387, 6098.786542470993, 6104.7840518355415, 6123.142760013601, 6142.437386315926, 6153.446189842846, 6138.202033735586, 6178.073289006595, 6179.81460556184, 6187.071939209065, 6208.906866397007, 6163.7842675506145, 6209.751609935643, 6227.875442491125, 6240.375962403814, 6253.6873156342, 4792.976035119872, 4874.488178741252, 4881.536254055859, 4916.213405855046, 4920.089783390084, 4402.625776809007, 4404.867853964386, 4412.7352358857925, 4448.221561226773, 4469.039156581285, 5677.489025987681, 5717.799877004285, 5767.077267637242, 5770.019096753478, 5786.537077033827, 6130.948521380002, 6138.232720909887, 6143.296949342275, 6164.660943648053, 6235.095911092866, 5717.885642287131, 5731.627444216113, 5743.195952566644, 5784.1902133701315, 5787.71061446926, 5349.625526213128, 5379.54273886724, 5380.43505431924, 5391.406945236021, 5398.1902714593125, 6127.250613123855, 6178.598391104564, 6199.194104766532, 6277.2153480814895, 6281.905771413432, 4679.859604211879, 5085.974570127126, 6207.286162091417, 6213.347598502273, 6216.844578885564, 6235.781747638811, 6290.559660823698, 6314.810555683337, 6318.0, 6324.0, 5637.267155269788, 6098.359672234529, 6112.685862051747, 6187.9480488317995, 6198.2252218472695, 5196.974015130051, 6147.739713358661, 6177.876442471215, 6201.720922558406, 6214.005759078525, 5910.468057874812, 6114.266288045463, 6121.938780612137, 6123.908141377794, 6142.95569753152, 5931.495822854933, 6016.2479690038745, 6018.969905150446, 6072.362401947908, 6081.39186081406, 5257.947420525898, 5845.473907348359, 5919.7040147991565, 5951.821445356646, 5986.0, 6001.279846418458, 6056.394360563909, 6062.181605483203, 6075.605085669594, 6125.326214116354, 5786.942130578809, 5872.383399742966, 5878.676672782867, 5906.379830117948, 5934.287885453773], "value": 5613.390563040482, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.5153797740237, 521.0854949563518, 524.3131497737963, 525.2174260352076, 528.2525226804071, 32961.34077318455, 35842.64157358425, 40854.0, 40926.59073409265, 40953.79523102384, 42138.578614213846, 42360.15279694404, 42531.36202956958, 42568.0, 42635.78682106593, 9576.233901287898, 9736.51317434129, 9804.411735295906, 9814.411135331868, 9836.606535738598, 9641.662541811003, 9766.609335626566, 9768.36505627132, 9770.169535589483, 9770.951145244264, 9739.172170365528, 9762.755931101708, 9765.365251258645, 9766.853497197548, 9791.0, 9704.805903881917, 9724.0, 9766.853497197477, 9771.413715177077, 9778.022197780236, 9582.616695332179, 9705.175060119898, 9711.417314961125, 9711.562979665861, 9712.562934667956, 39710.60289397098, 39909.20181596381, 40076.0, 40467.39298910519, 40506.79746601248, 3208.7593430492807, 3274.214188594744, 3341.7159541439005, 3509.31568344173, 3754.7747135171976, 30172.24569385761, 30715.38569228603, 30926.0722178335, 31034.534481982788, 31454.925200115747, 3188.984055079733, 3224.564683767708, 3234.4663130583663, 3241.6110066792135, 3323.102762254202, 498887.0, 503003.0, 506184.0, 507064.0, 507548.0, 59623.40376596178, 60144.699276503336, 60217.397826022054, 60222.0, 60278.0, 33629.83185084108, 34797.30405391878, 34809.47785783215, 63309.683451582445, 63378.0, 15792.36830526766, 15890.443834465737, 16073.196340182943, 16131.354745810271, 16168.75746863798, 12399.25604463735, 12485.500579976699, 12500.437480313225, 12501.93749031249, 12503.437345319482, 18240.45278641643, 18314.45056648303, 18378.540536486697, 18385.54036149107, 18397.356092536964, 6524.608523488606, 6858.417034552119, 7040.662274167892, 7376.520526165782, 7687.692492300245, 854.4318028511095, 860.7331727164599, 1019.2050200843354, 1020.285799940045, 1079.169039839335, 21389.25137620176, 23357.649635255486, 29512.704872950995, 29769.106926792232, 30039.0, 939.9295052871062, 945.8155659646303, 1004.5278719002057, 1033.9948300258602, 1081.6322450366913, 12500.999920006378, 15274.770878436831, 15364.923175384052, 15618.0, 15707.371705131894, 488573.0, 488736.0, 503532.0, 562272.0, 570007.0, 46164.30753538699, 52476.73761631167, 54300.0, 54594.18108728293, 54991.45008549943, 1331.3875617215995, 1331.6338007047946, 1365.556194236877, 1372.3618517389261, 1377.9173249604855, 27866.58200126959, 28540.001099961402, 28966.855165724035, 29342.972995945038, 29396.26509337198, 48293.0, 49014.0, 49094.509054908995, 49132.508674912795, 49239.0, 440760.0, 494534.0, 504337.0, 511914.8808512016, 512836.0, 25470.0, 25758.969641214142, 25818.48363032691, 25898.093566725074, 25914.22257332283, 44575.0, 44991.55008449873, 44997.77501112474, 45051.549484504736, 45368.77315613401, 36967.630323696416, 37268.6273137276, 37660.05849853776, 38249.4262586067, 38583.0, 31515.896943606862, 35337.64662353446, 36610.63389366179, 36789.81605091957, 37731.62268377391, 36329.63670363263, 36950.63049369472, 36974.445383318736, 37317.44023839699, 37442.43836342511, 31755.0, 32785.34429311448, 33085.0, 33257.334853303284, 33294.50058249177, 24691.259262222164, 24894.502109958063, 24919.87540062288, 25065.87467062653, 25441.618375724007], "value": 19253.255432661346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.5015578812814, 373.2702566482529, 374.67964890019033, 380.3951716770335, 397.2710077008689, 17800.64398712025, 19419.805801941977, 21073.683894741593, 21317.893410532946, 21567.35297941062, 19978.100985455654, 20147.59704805903, 20653.0, 20953.895230523864, 21368.931553422306, 5208.817691380802, 5800.0, 6005.939940600603, 6065.029595264758, 6079.361667024965, 5950.5834591578605, 5978.133170690247, 6051.546134039964, 6057.909131363033, 6060.969695151518, 5316.5215130638235, 5843.678597677126, 5877.6473411595225, 5921.052631578948, 5955.88088238235, 5683.090705487102, 5883.264591926009, 5952.880942381172, 5968.7612495500125, 5986.431289027557, 5148.948510514884, 5173.922391164136, 5183.352080989876, 5186.740662966837, 5199.792008319662, 22153.556928861333, 23705.81470926447, 25521.361965951, 25903.74096259051, 25929.481410371685, 2147.656374980011, 2364.290712786157, 2414.372263211578, 2833.135893552466, 3121.7814752967074, 14972.625684357869, 15226.771598426032, 15749.685006299924, 15821.446249381332, 16165.595860103474, 1880.5204672808484, 1925.124068548807, 1927.7108433734911, 1927.9710804337947, 1937.0992488492777, 120678.39660801728, 122736.0, 123713.0, 125656.0, 126322.0, 8834.249088827522, 8998.86501702475, 10224.415215641588, 10365.689029329133, 10866.07638350749, 13003.219806811621, 13712.588622341345, 13743.793843092362, 13781.586552403443, 13792.517261895786, 8218.876716849252, 8271.917280827234, 8402.117777633383, 8416.452930559493, 8578.485290882567, 8324.417290789586, 8346.457480263764, 8376.581170941428, 8567.485950842969, 8619.698310559226, 6654.434373078246, 6686.531942764057, 6712.865742685217, 6728.192616886005, 6765.42493888025, 1795.7934837493856, 1801.2884910460295, 1805.1696219738806, 1809.3124612647373, 1830.7986121526621, 902.7066203483894, 929.3726734454216, 955.7801705607749, 996.4619105682995, 998.9700308990742, 21474.0, 22455.66316505221, 22490.100395984304, 22519.662205066936, 22629.0, 910.4127837544879, 914.6387177065022, 921.9216366608779, 950.9096635819586, 951.8619800128943, 11525.423728813526, 11700.765984680258, 11917.70205744846, 11943.402829858473, 11986.580469683518, 547958.0, 578204.1089794524, 593961.0, 606150.0, 611545.9031392011, 87891.24217515613, 102553.0, 116454.0, 123874.0, 129048.0, 847.0428415890128, 962.5572236771023, 1022.7801022780138, 1024.062982371137, 1028.2596530498029, 16351.754723678916, 16794.58013549671, 19581.671115910613, 20029.29897453582, 20362.0, 57546.0, 57564.42435575758, 57633.423665762806, 58045.646576506086, 58614.70692646509, 80245.59877200576, 84018.15981840104, 85331.14668853232, 85572.0, 86826.0, 7843.372530197685, 8100.83798324042, 8335.624896879533, 8378.162183781817, 8570.74287771368, 9250.936142343717, 13265.933670331586, 13315.933420332836, 13352.0, 13963.511277105252, 11269.380184089954, 11569.942150289195, 11703.824442633537, 11710.531578736754, 11758.412079396167, 8865.271272102136, 10996.395198264172, 11005.669829905115, 11547.9298769116, 11595.304281743123, 11511.942440287745, 11590.291106845729, 11727.944484996391, 11845.0, 11918.225315354473, 7193.0, 7375.041244638162, 7896.565688887166, 8217.506949583045, 8358.373122015995, 10558.208134390105, 11395.487203076047, 11629.47667354988, 11699.766004680028, 11782.882171178178], "value": 9808.545230114742, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.8100775387572, 1501.678522899848, 1648.0717560454268, 1857.331360710145, 1866.7573215481984, 7984.041914970212, 8557.400981931267, 8638.827223455528, 9158.221551168142, 9187.402818816781, 8788.8681669775, 8801.251893589037, 8890.44417226986, 8909.153630405117, 8909.509976951282, 2184.2246002669062, 2327.2552783109413, 2389.2593296078226, 2442.682451281331, 2443.4380092578717, 2100.9264675736354, 2400.975990240093, 2419.4677171022345, 2454.6808914841044, 2457.631355296711, 2437.890294936732, 2444.2422848916804, 2447.9265622031367, 2450.8774561271866, 2465.790407815338, 2083.1771450277133, 2361.244401791427, 2419.6249581314923, 2431.4407686232175, 2438.0247900839627, 1920.2127127877643, 2315.8610483371053, 2318.8840557972035, 2320.385097949045, 2328.0571368595747, 8909.109089091038, 8964.327675424303, 9005.774855628662, 9013.0, 9040.095990400972, 4980.103581355354, 5270.815521456769, 5411.810586629449, 5416.566674666017, 5430.294061771984, 3241.9675803241903, 3374.139594403436, 3464.9133771655656, 3623.2934577757346, 3659.8536058557625, 1081.7620123572801, 1103.2222283290262, 1107.164091111214, 1117.6479408986224, 1124.65135807899, 11705.590304339306, 11787.705307367298, 11805.937465628134, 11899.762004759856, 11921.0, 4573.314002899525, 4650.883727906829, 4846.588040016638, 4859.465458799529, 4878.170710979142, 9964.551595178234, 9975.750606234757, 10050.095491405662, 10055.994400559886, 10096.0, 5398.352197736218, 5405.486478784509, 5432.619716619799, 5437.673739575638, 5440.156775699773, 4292.463442069741, 4456.710313829655, 4962.826301079445, 4980.327655766425, 4993.176125939252, 3905.824237909301, 3926.5877082906454, 4003.379476181138, 4049.2104039712563, 4079.673626109904, 1255.1966741285585, 1271.8664540223328, 1305.6996890715188, 1318.967025824343, 1330.3880215101162, 797.7128233835814, 825.6614787936917, 914.8078903430354, 927.1146055516965, 933.3093510802094, 5842.182094506772, 5938.317093534212, 5962.701864906824, 6079.6960151992225, 6119.632822030692, 433.6704104880314, 962.629387685746, 982.6511588386163, 1025.5333823110466, 1036.43514284715, 3450.2064525159353, 3492.8078955657684, 3621.9818900905325, 3623.3659109655705, 3628.745987780883, 8800.647974081094, 8854.380193386402, 10629.255952083435, 11012.17408694335, 11070.225084244026, 10757.462126893624, 10761.24671273019, 10774.515146818412, 10778.461076946121, 10778.622748203776, 8141.389395795339, 8180.6318715657935, 8288.336933045342, 8329.791755206168, 8370.581470926429, 5807.738651760596, 5874.265716785402, 5915.497182739516, 5947.821565353046, 6029.306629737636, 10090.94954525252, 10190.694279171637, 10210.234232432746, 10243.07812296867, 10243.7439064024, 9945.403275803475, 10224.079832815083, 10277.486125693835, 10333.018363255478, 10399.94800025995, 6500.7399704013205, 6956.686949087202, 7065.434765218667, 7941.205879412013, 8299.668013279643, 8344.666213351398, 8456.365772566958, 8523.232909038215, 8541.188587084218, 8555.786105347415, 7251.746188883575, 7426.925730742623, 7515.248475152441, 7546.962265188639, 7619.390448764197, 7221.169565499825, 7327.303906128909, 7415.666295016629, 7431.29402706742, 7439.107307123179, 6540.659164871095, 6805.795826125224, 6988.0, 7018.227994920452, 7085.0, 4187.895302617337, 4786.497417771223, 4999.300097986212, 5059.822906198413, 5062.316587260672, 4208.473940757405, 5001.57486613642, 5059.494050594911, 5174.8965020700125, 5179.171332586768], "value": 4585.954622979623, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.1148918776108, 302.3921916946936, 307.66771886362733, 308.76842368223834, 309.62071462458493, 4314.697971142014, 4551.815128738208, 5405.243265942771, 5511.897620475911, 5566.749496272667, 4748.786304616292, 5078.0, 5135.255387968742, 5527.39198688144, 5560.916586251199, 3861.3242682530445, 3880.5343358796986, 4182.64447521961, 4245.172191422674, 4272.957270427302, 3917.764934103963, 4179.0806022675115, 4243.066525364415, 4263.4031235627035, 4271.796152885336, 3815.637514436124, 4174.895627609319, 4184.435101261336, 4185.765199266209, 4193.1404062167185, 4200.7479551227025, 4215.178040282161, 4223.619874211336, 4223.704340696153, 4224.5353011168745, 3264.265540253424, 3516.613172551017, 3683.5948045715218, 3711.406175011985, 3721.776693398405, 5253.711045892474, 5328.573714102862, 5369.0, 5403.243545903577, 5418.376886658046, 4832.637552183565, 4835.975820120877, 4837.0, 4882.41411030675, 4890.853274401774, 4954.826581069645, 5305.495977882095, 5805.680687562182, 5848.67832269225, 5870.9158248821905, 3754.0614846288427, 3833.099221682902, 4032.8386864525382, 4047.959520404788, 4055.655269302113, 5823.912641310384, 6032.215811944419, 6066.757329706807, 6097.634141951497, 6129.356417576135, 3434.4161492546327, 3446.965530344715, 3460.9826950865086, 4075.286824805647, 4103.850921741858, 3468.4277094279664, 4024.2756303865, 4141.840284720283, 4148.23257697327, 4155.97922010388, 3769.547654281449, 4162.791860406967, 4733.763311834395, 4742.624638854693, 4823.686460380134, 4394.472663280427, 5508.614396992252, 5750.482456578928, 5765.769369225184, 5875.059990401515, 1095.1950316517352, 1276.8531618863765, 1317.7562151002096, 1371.35546293242, 1432.3697573067816, 810.295043312322, 825.8513467575895, 880.335346813152, 930.0234753508923, 944.3248077624531, 5341.278927344836, 5452.700101494456, 5455.263539422207, 5464.3442786865835, 5489.462950373902, 5139.665921715076, 5748.13777933305, 5942.910856337158, 5970.34326224115, 5994.880102397928, 5155.561777248976, 5228.2941802856885, 5428.0, 5444.292242008592, 5460.781568737286, 2654.296611397981, 2657.627932089509, 2669.8932042718075, 2672.492226477002, 2676.9693667937986, 4706.670533062653, 4997.27757269377, 5183.377994640668, 5189.896202075937, 5235.895282094337, 4391.67062470316, 4850.393700787402, 4885.892879029293, 5019.494151754459, 5086.745662716849, 4622.61935103422, 4781.418034261529, 4868.0, 4984.626153038536, 5207.505286997729, 3806.733528652968, 4535.478419981744, 4938.604911607134, 5019.949800501949, 5059.772310245974, 4317.870463886088, 4410.514843367163, 4567.429071366079, 5280.339957505312, 5927.4665280125, 5240.764165612481, 6321.873562528632, 6349.301576826638, 6354.52341074412, 6363.204599425072, 3282.8522716477337, 3664.992127165051, 3693.7045036397562, 3841.3661745812747, 3955.4857868476915, 4877.878157978826, 5240.71176085319, 5777.7977770777825, 6058.5153187745755, 6111.358307377662, 5356.705381203916, 5358.571314294925, 5660.660360378391, 5907.556933230111, 5912.349641539341, 4901.828436004723, 5873.85459835328, 5885.529157667462, 5891.764329426775, 5910.379410161871, 4854.708717476963, 4978.137570896676, 5624.353199382123, 5712.457316554921, 5772.307323121252, 5877.823665290048, 5899.52220067564, 5902.173695682522, 5911.290645122441, 5918.141869428822, 5071.416787069386, 5516.696581688046, 5708.828735137952, 5761.39505351932, 5837.5038121758425], "value": 4091.242729214711, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.6791095873294, 744.5160645580372, 766.3830616353833, 800.7637746864674, 854.743576926922, 14821.184834834079, 15693.136877471748, 15694.52916412508, 17021.659566808656, 17764.0, 15998.280077396515, 16081.678366432665, 16125.596860078474, 16129.919350403261, 16135.83864161358, 828.1801016993163, 847.7414388611471, 850.2050582705159, 851.2041241439274, 851.2126283188027, 660.141815639669, 785.6032703484732, 828.3704384667635, 859.222403724627, 861.8577934640808, 881.7927786970057, 886.995565022174, 889.7286327670058, 890.1587999340626, 890.6437425029975, 853.3514528957975, 876.2551830943679, 885.6191837509868, 886.2644005475504, 887.0508555845248, 649.938255865692, 653.6470306034736, 653.7777155767014, 656.1306269193337, 658.6739563915844, 15282.235888205545, 15309.540713778604, 15394.538163855103, 15418.768718469231, 15435.922820385938, 13966.860331396658, 14012.299385030708, 14068.577942661737, 14076.436942522287, 14257.572272831832, 11646.184767066308, 11708.531658733726, 11759.118066144987, 11895.07909834844, 11941.0, 3693.353663108972, 3734.2344819312248, 3742.7145917923826, 3759.3233218020746, 3875.844966201377, 120379.0, 121594.0, 124137.37931310285, 124739.75260247372, 126805.36597317047, 10648.307859989076, 10651.034059345044, 10694.786104277871, 10725.731856703645, 10900.0, 22163.44591385196, 22352.88823555872, 22379.328620141423, 22384.440388990406, 22413.543119697493, 6203.976343903296, 6805.829854253683, 7533.849323013618, 7780.377569794516, 7882.7241046563095, 4431.357453169337, 4475.746790898554, 4481.170983368087, 4535.614472769786, 4611.077784443125, 3201.4717571600427, 3209.7642407673216, 3513.2622149348413, 3584.121890136933, 3890.319194141014, 705.4250785609712, 717.9625441237362, 724.4096061709795, 728.420905380224, 747.3535391886088, 532.48615086441, 618.6226401894841, 631.7757196195377, 655.1737850950473, 666.1306994372294, 8335.54154521495, 8844.071372505794, 9239.39943903644, 9275.62897484093, 9506.38208516458, 630.7760744935482, 631.2961048430966, 657.8322527755391, 666.6600133731139, 667.5727534378002, 4226.619604235572, 4319.322020347405, 4364.367166760802, 4664.510226426244, 4672.0, 116610.0, 130534.34732826464, 169951.0, 170527.0, 173357.0, 82560.0, 144761.82857257148, 148014.0, 148231.0, 148272.51727482802, 123036.0, 123087.0, 124426.0, 124864.3756781192, 127039.0, 8291.917080829357, 8690.696965304325, 8716.041235464221, 8716.738497845075, 8880.555972201493, 66749.66625166843, 67516.32483675101, 68708.31291687019, 69033.27416814619, 70157.29842701508, 96968.51515742658, 102054.0, 103880.96119038713, 105361.0, 106713.9328606704, 11476.770464590494, 11650.242734222249, 11750.29498230109, 11800.0, 11941.402929853646, 12849.550265740654, 12930.870691292967, 12947.352632368156, 12964.22214667123, 13250.602481925558, 11982.395579912016, 12130.575429859913, 12220.45007974661, 12228.0, 12621.116521843383, 11764.235324703866, 11819.82270265964, 11965.162438629213, 11983.221090629082, 12316.384180791103, 11854.170208085352, 12013.159078864745, 12038.879611203776, 12228.309414637206, 12434.937825310815, 8951.776205594651, 8994.055624159631, 9272.258219342571, 9416.95291523538, 9466.621335146792, 7473.43949203801, 8160.26557609818, 8724.302055835646, 9438.339316248072, 9621.037896210322], "value": 7721.036947008125, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1641.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 164.0, 161.0, 118.0, 119.0, 115.0, 102.0, 171.0, 186.0], "value": 136.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 110.0, 157.0, 164.0, 136.0, 165.0, 136.0, 133.0, 178.0, 125.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 238.0, 214.0, 222.0, 274.0, 245.0, 197.0, 192.0, 200.0, 201.0], "value": 219.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 67.0, 151.0, 86.0, 85.0, 83.0, 60.0, 156.0, 53.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 124.0, 70.0, 68.0, 78.0, 110.0, 72.0, 76.0, 124.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 91.0, 85.0, 137.0, 129.0, 118.0, 130.0, 99.0, 121.0], "value": 109.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 163.0, 157.0, 101.0, 101.0, 101.0, 143.0, 175.0, 102.0], "value": 124.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 140.0, 93.0, 91.0, 91.0, 92.0, 90.0, 90.0, 90.0, 113.0], "value": 100.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 61.0, 69.0, 89.0, 86.0, 86.0, 58.0, 55.0, 58.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 73.0, 71.0, 84.0, 76.0, 72.0, 72.0, 74.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 88.0, 87.0, 86.0, 88.0, 89.0, 89.0, 87.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 170.0, 151.0, 158.0, 153.0, 151.0, 158.0, 164.0, 192.0, 145.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 174.0, 176.0, 172.0, 172.0, 169.0, 170.0, 171.0, 173.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 69.0, 69.0, 67.0, 60.0, 82.0, 88.0, 87.0], "value": 72.7, "unit": "score"}]}]} 21:56:40 INFO - Return code: 0 21:56:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:56:40 ERROR - # TBPL SUCCESS # 21:56:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:56:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:56:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:56:40 INFO - 2016-09-08 21:56:40.333 screenresolution[3250:309726] starting screenresolution argv=screenresolution get 21:56:40 INFO - 2016-09-08 21:56:40.336 screenresolution[3250:309726] Display 0: 1600x1200x32@60 21:56:40 INFO - 2016-09-08 21:56:40.342 screenresolution[3251:309729] starting screenresolution argv=screenresolution list 21:56:40 INFO - Available Modes on Display 0 21:56:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:56:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:56:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:56:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:56:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:56:40 INFO - Intel Iris: 21:56:40 INFO - Chipset Model: Intel Iris 21:56:40 INFO - Type: GPU 21:56:40 INFO - Bus: Built-In 21:56:40 INFO - VRAM (Dynamic, Max): 1536 MB 21:56:40 INFO - Vendor: Intel (0x8086) 21:56:40 INFO - Device ID: 0x0a2e 21:56:40 INFO - Revision ID: 0x0009 21:56:40 INFO - Displays: 21:56:40 INFO - Display: 21:56:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:56:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:56:40 INFO - Display Serial Number: 1600x1200 60 21:56:40 INFO - Main Display: Yes 21:56:40 INFO - Mirror: Off 21:56:40 INFO - Online: Yes 21:56:40 INFO - Rotation: Supported 21:56:40 INFO - Return code: 0 21:56:40 INFO - Running post-action listener: _resource_record_post_action 21:56:40 INFO - [mozharness: 2016-09-09 04:56:40.590480Z] Finished run-tests step (success) 21:56:40 INFO - Running post-run listener: _resource_record_post_run 21:56:40 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 241664; Write bytes: 706919424; Read time: 1; Write time: 2054 21:56:40 INFO - TinderboxPrint: CPU usage
54.6% 21:56:40 INFO - TinderboxPrint: I/O read bytes / time
241,664 / 1 21:56:40 INFO - TinderboxPrint: I/O write bytes / time
706,919,424 / 2,054 21:56:40 INFO - TinderboxPrint: CPU idle
2,197.1 (45.4%) 21:56:40 INFO - TinderboxPrint: CPU system
982.0 (20.3%) 21:56:40 INFO - TinderboxPrint: CPU user
1,662.6 (34.3%) 21:56:40 INFO - TinderboxPrint: Swap in / out
405,295,104 / 0 21:56:40 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 406589952; Write bytes: 407815680; Read time: 30751; Write time: 885 21:56:40 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 241664; Write bytes: 297608704; Read time: 1; Write time: 1159 21:56:41 INFO - Running post-run listener: _upload_blobber_files 21:56:41 INFO - Blob upload gear active. 21:56:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:56:41 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 21:56:41 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:56:41 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:56:41 INFO - (blobuploader) - INFO - Open directory for files ... 21:56:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:56:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:56:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:42 INFO - (blobuploader) - INFO - Done attempting. 21:56:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:56:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:56:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:43 INFO - (blobuploader) - INFO - Done attempting. 21:56:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:56:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:46 INFO - (blobuploader) - INFO - Done attempting. 21:56:46 INFO - (blobuploader) - INFO - Iteration through files over. 21:56:46 INFO - Return code: 0 21:56:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:56:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:56:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14819f9129ce8335d63a7332b33ff330bc5da79b5be8fc3218a96947ca3e921402af92ab966537d7321e83a0acb9c81362a9f5e7828e8eda3639d1eb472eaf00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/336a84e5f64fca79111e743b51c4d2ea7e3c098e33fb52e56d05f8e7ef03a516b1c21327696698510030713e87359573d2e3db4c007259ed9044a541f8a2c3f3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad7ef9dfd8f4c6ae9e303215d8fdb9ddd9aa15a6012d8979710cb7a020d16ae2803b09bd452d49ca8cd18eb5b09a6313666acba7e71cba09b3f5fe3a07d6735"} 21:56:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:56:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:56:46 INFO - Contents: 21:56:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14819f9129ce8335d63a7332b33ff330bc5da79b5be8fc3218a96947ca3e921402af92ab966537d7321e83a0acb9c81362a9f5e7828e8eda3639d1eb472eaf00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/336a84e5f64fca79111e743b51c4d2ea7e3c098e33fb52e56d05f8e7ef03a516b1c21327696698510030713e87359573d2e3db4c007259ed9044a541f8a2c3f3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad7ef9dfd8f4c6ae9e303215d8fdb9ddd9aa15a6012d8979710cb7a020d16ae2803b09bd452d49ca8cd18eb5b09a6313666acba7e71cba09b3f5fe3a07d6735"} 21:56:46 INFO - Running post-run listener: copy_logs_to_upload_dir 21:56:46 INFO - Copying logs to upload dir... 21:56:46 INFO - mkdir: /builds/slave/test/build/upload/logs 21:56:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.491229 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-09-08 21:56:46.186702) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 21:56:46.190466) ========= 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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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/Fx-Team/sha512/14819f9129ce8335d63a7332b33ff330bc5da79b5be8fc3218a96947ca3e921402af92ab966537d7321e83a0acb9c81362a9f5e7828e8eda3639d1eb472eaf00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/336a84e5f64fca79111e743b51c4d2ea7e3c098e33fb52e56d05f8e7ef03a516b1c21327696698510030713e87359573d2e3db4c007259ed9044a541f8a2c3f3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad7ef9dfd8f4c6ae9e303215d8fdb9ddd9aa15a6012d8979710cb7a020d16ae2803b09bd452d49ca8cd18eb5b09a6313666acba7e71cba09b3f5fe3a07d6735"} build_url:https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/PTkseU_YSDauLU060QScQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14819f9129ce8335d63a7332b33ff330bc5da79b5be8fc3218a96947ca3e921402af92ab966537d7321e83a0acb9c81362a9f5e7828e8eda3639d1eb472eaf00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/336a84e5f64fca79111e743b51c4d2ea7e3c098e33fb52e56d05f8e7ef03a516b1c21327696698510030713e87359573d2e3db4c007259ed9044a541f8a2c3f3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad7ef9dfd8f4c6ae9e303215d8fdb9ddd9aa15a6012d8979710cb7a020d16ae2803b09bd452d49ca8cd18eb5b09a6313666acba7e71cba09b3f5fe3a07d6735"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 21:56:46.225801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:56:46.226265) ========= 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.NKv7Uh2lT0/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.fQN2OY24rV/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005226 ========= master_lag: 0.24 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:56:46.472203) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 21:56:46.472507) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 21:56:46.472939) ========= ========= Total master_lag: 0.38 =========