builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0317 starttime: 1472927949.81 results: success (0) buildid: 20160903084649 builduid: 973de5c6e9c34e3987c4c64d0a0ffdc2 revision: 5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:09.810010) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:09.810572) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:09.810867) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:09.869052) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:09.869413) ========= 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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-03 11:39:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2016-09-03 11:39:10 (19.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.857023 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:10.772779) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:10.773093) ========= 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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.026704 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:10.841023) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 11:39:10.841402) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc --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 5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1EqsLMZIkW/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.5jBdonAZ1B/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-03 11:39:10,948 truncating revision to first 12 chars 2016-09-03 11:39:10,948 Setting DEBUG logging. 2016-09-03 11:39:10,948 attempt 1/10 2016-09-03 11:39:10,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5e1165d6f7ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 11:39:12,049 unpacking tar archive at: fx-team-5e1165d6f7ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.383184 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 11:39:12.253591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:12.253878) ========= script_repo_revision: 5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:12.254262) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:12.254570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 11:39:12.273038) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-03 11:39:12.273315) ========= /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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:12 INFO - MultiFileLogger online at 20160903 11:39:12 in /builds/slave/test 11:39:12 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 11:39:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:39:12 INFO - {'append_to_log': False, 11:39:12 INFO - 'base_work_dir': '/builds/slave/test', 11:39:12 INFO - 'blob_upload_branch': 'Fx-Team', 11:39:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:39:12 INFO - 'branch': 'Fx-Team', 11:39:12 INFO - 'buildbot_json_path': 'buildprops.json', 11:39:12 INFO - 'config_files': ('talos/mac_config.py',), 11:39:12 INFO - 'default_actions': ('clobber', 11:39:12 INFO - 'read-buildbot-config', 11:39:12 INFO - 'download-and-extract', 11:39:12 INFO - 'populate-webroot', 11:39:12 INFO - 'create-virtualenv', 11:39:12 INFO - 'install', 11:39:12 INFO - 'run-tests'), 11:39:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:39:12 INFO - 'download_minidump_stackwalk': True, 11:39:12 INFO - 'download_symbols': 'ondemand', 11:39:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:39:12 INFO - 'tooltool.py': '/tools/tooltool.py', 11:39:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:39:12 INFO - '/tools/misc-python/virtualenv.py')}, 11:39:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:39:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:39:12 INFO - 'installer_path': 'installer.exe', 11:39:12 INFO - 'log_level': 'info', 11:39:12 INFO - 'log_name': 'talos', 11:39:12 INFO - 'log_to_console': True, 11:39:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:39:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:39:12 INFO - 'opt_config_files': (), 11:39:12 INFO - 'pip_index': False, 11:39:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:39:12 INFO - 'cmd': ('bash', 11:39:12 INFO - '-c', 11:39:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:39:12 INFO - 'enabled': True, 11:39:12 INFO - 'halt_on_failure': False, 11:39:12 INFO - 'name': 'check_screen_resolution'},), 11:39:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:39:12 INFO - 'cmd': ('bash', 11:39:12 INFO - '-c', 11:39:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:39:12 INFO - 'enabled': True, 11:39:12 INFO - 'halt_on_failure': False, 11:39:12 INFO - 'name': 'check_screen_resolution'},), 11:39:12 INFO - 'run_cmd_checks_enabled': True, 11:39:12 INFO - 'sps_profile': False, 11:39:12 INFO - 'sps_profile_interval': 0, 11:39:12 INFO - 'suite': 'dromaeojs-e10s', 11:39:12 INFO - 'system_bits': '32', 11:39:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:39:12 INFO - 'title': 't-yosemite-r7-0317', 11:39:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:39:12 INFO - 'use_talos_json': True, 11:39:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:39:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:39:12 INFO - 'work_dir': 'build'} 11:39:12 INFO - [mozharness: 2016-09-03 18:39:12.484843Z] Running clobber step. 11:39:12 INFO - Running pre-action listener: _resource_record_pre_action 11:39:12 INFO - Running main action method: clobber 11:39:12 INFO - rmtree: /builds/slave/test/build 11:39:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:39:14 INFO - Running post-action listener: _resource_record_post_action 11:39:14 INFO - [mozharness: 2016-09-03 18:39:14.469093Z] Finished clobber step (success) 11:39:14 INFO - [mozharness: 2016-09-03 18:39:14.469256Z] Running read-buildbot-config step. 11:39:14 INFO - Running pre-action listener: _resource_record_pre_action 11:39:14 INFO - Running main action method: read_buildbot_config 11:39:14 INFO - Using buildbot properties: 11:39:14 INFO - { 11:39:14 INFO - "project": "", 11:39:14 INFO - "product": "firefox", 11:39:14 INFO - "script_repo_revision": "production", 11:39:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 11:39:14 INFO - "repository": "", 11:39:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 11:39:14 INFO - "buildid": "20160903084649", 11:39:14 INFO - "pgo_build": "False", 11:39:14 INFO - "basedir": "/builds/slave/test", 11:39:14 INFO - "buildnumber": 107, 11:39:14 INFO - "slavename": "t-yosemite-r7-0317", 11:39:14 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:39:14 INFO - "platform": "yosemite_r7", 11:39:14 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 11:39:14 INFO - "branch": "fx-team", 11:39:14 INFO - "repo_path": "integration/fx-team", 11:39:14 INFO - "revision": "5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc", 11:39:14 INFO - "stage_platform": "macosx64", 11:39:14 INFO - "builduid": "973de5c6e9c34e3987c4c64d0a0ffdc2", 11:39:14 INFO - "slavebuilddir": "test" 11:39:14 INFO - } 11:39:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:39:14 INFO - Running post-action listener: _resource_record_post_action 11:39:14 INFO - [mozharness: 2016-09-03 18:39:14.472740Z] Finished read-buildbot-config step (success) 11:39:14 INFO - [mozharness: 2016-09-03 18:39:14.472848Z] Running download-and-extract step. 11:39:14 INFO - Running pre-action listener: _resource_record_pre_action 11:39:14 INFO - Running main action method: download_and_extract 11:39:14 INFO - mkdir: /builds/slave/test/build/tests 11:39: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')]} 11:39:14 INFO - trying https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:39:14 INFO - Downloading https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/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 11:39:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/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 11:39:15 INFO - Downloaded 1199 bytes. 11:39:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:39:15 INFO - Using the following test package requirements: 11:39:15 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:39:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:39:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:39:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'jsshell-mac.zip'], 11:39:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:39:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:39:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:39:15 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:39:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:39:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:39:15 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:39:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:39:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:39:15 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:39:22 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 11:39:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:39:23 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:39:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:39:31 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:39:31 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')]} 11:39:31 INFO - trying https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:39:31 INFO - Downloading https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/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 11:39:34 INFO - Downloaded 111495350 bytes. 11:39:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:39:34 INFO - mkdir: /builds/slave/test/properties 11:39:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:39:34 INFO - Writing to file /builds/slave/test/properties/build_url 11:39:34 INFO - Contents: 11:39:34 INFO - build_url:https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:39:35 INFO - Running post-action listener: _resource_record_post_action 11:39:35 INFO - Running post-action listener: set_extra_try_arguments 11:39:35 INFO - [mozharness: 2016-09-03 18:39:35.496670Z] Finished download-and-extract step (success) 11:39:35 INFO - [mozharness: 2016-09-03 18:39:35.496794Z] Running populate-webroot step. 11:39:35 INFO - Running pre-action listener: _resource_record_pre_action 11:39:35 INFO - Running main action method: populate_webroot 11:39:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:39:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:39:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:39:35 INFO - '--tppagecycles', 11:39:35 INFO - '7'], 11:39:35 INFO - 'tests': ['tsvgm']}}, 11:39:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:39:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:39:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:39:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:39:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['damp', 'tps']}, 11:39:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'tests': ['damp', 'tps']}, 11:39:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['dromaeo_dom']}, 11:39:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:39:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['basic_compositor_video']}, 11:39:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:39:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['a11yr', 11:39:35 INFO - 'ts_paint', 11:39:35 INFO - 'tpaint', 11:39:35 INFO - 'sessionrestore', 11:39:35 INFO - 'sessionrestore_no_auto_restore', 11:39:35 INFO - 'tabpaint']}, 11:39:35 INFO - 'other-e10s': {'tests': ['a11yr', 11:39:35 INFO - 'ts_paint', 11:39:35 INFO - 'tpaint', 11:39:35 INFO - 'sessionrestore', 11:39:35 INFO - 'sessionrestore_no_auto_restore', 11:39:35 INFO - 'tabpaint']}, 11:39:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:39:35 INFO - 'ts_paint', 11:39:35 INFO - 'tpaint', 11:39:35 INFO - 'sessionrestore', 11:39:35 INFO - 'sessionrestore_no_auto_restore', 11:39:35 INFO - 'tabpaint']}, 11:39:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:39:35 INFO - 'ts_paint', 11:39:35 INFO - 'tpaint', 11:39:35 INFO - 'sessionrestore', 11:39:35 INFO - 'sessionrestore_no_auto_restore', 11:39:35 INFO - 'tabpaint']}, 11:39:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['a11yr', 11:39:35 INFO - 'ts_paint', 11:39:35 INFO - 'tpaint', 11:39:35 INFO - 'sessionrestore', 11:39:35 INFO - 'sessionrestore_no_auto_restore', 11:39:35 INFO - 'tabpaint']}, 11:39:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['a11yr', 11:39:35 INFO - 'ts_paint', 11:39:35 INFO - 'tpaint', 11:39:35 INFO - 'sessionrestore', 11:39:35 INFO - 'sessionrestore_no_auto_restore', 11:39:35 INFO - 'tabpaint']}, 11:39:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['tsvgx', 11:39:35 INFO - 'tsvgr_opacity', 11:39:35 INFO - 'tart', 11:39:35 INFO - 'tscrollx', 11:39:35 INFO - 'cart']}, 11:39:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:39:35 INFO - 'tsvgr_opacity', 11:39:35 INFO - 'tart', 11:39:35 INFO - 'tscrollx', 11:39:35 INFO - 'cart']}, 11:39:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'talos_options': ['--disable-e10s'], 11:39:35 INFO - 'tests': ['tp5o']}, 11:39:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'tests': ['tp5o']}, 11:39:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'talos_options': ['--disable-e10s', 11:39:35 INFO - '--xperf_path', 11:39:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:35 INFO - 'tests': ['tp5n']}, 11:39:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:35 INFO - 'talos_options': ['--xperf_path', 11:39:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:35 INFO - 'tests': ['tp5n']}}, 11:39:35 INFO - 'talos.zip': {'path': '', 11:39:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:39:35 INFO - Running post-action listener: _resource_record_post_action 11:39:35 INFO - [mozharness: 2016-09-03 18:39:35.515575Z] Finished populate-webroot step (success) 11:39:35 INFO - [mozharness: 2016-09-03 18:39:35.515697Z] Running create-virtualenv step. 11:39:35 INFO - Running pre-action listener: _resource_record_pre_action 11:39:35 INFO - Running main action method: create_virtualenv 11:39:35 INFO - Creating virtualenv /builds/slave/test/build/venv 11:39:35 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 11:39:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:39:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:39:35 INFO - Using real prefix '/tools/python27' 11:39:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:39:36 INFO - Installing distribute.............................................................................................................................................................................................done. 11:39:39 INFO - Installing pip.................done. 11:39:39 INFO - Return code: 0 11:39:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:39:39 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')]} 11:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:39 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')]} 11:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:39 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:39 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 11:39:39 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 11:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:39 INFO - 'HOME': '/Users/cltbld', 11:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:39 INFO - 'LOGNAME': 'cltbld', 11:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:39 INFO - 'MOZ_NO_REMOTE': '1', 11:39:39 INFO - 'NO_EM_RESTART': '1', 11:39:39 INFO - 'PAGER': '/bin/cat', 11:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:39 INFO - 'PWD': '/builds/slave/test', 11:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:39 INFO - 'SHELL': '/bin/bash', 11:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:39 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:39 INFO - 'USER': 'cltbld', 11:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:39 INFO - 'XPC_FLAGS': '0x0', 11:39:39 INFO - 'XPC_SERVICE_NAME': '0', 11:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:39 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 11:39:39 INFO - Cleaning up... 11:39:39 INFO - Return code: 0 11:39:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:39:39 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')]} 11:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:39 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')]} 11:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:39 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:39 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 11:39:39 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 11:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:39 INFO - 'HOME': '/Users/cltbld', 11:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:39 INFO - 'LOGNAME': 'cltbld', 11:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:39 INFO - 'MOZ_NO_REMOTE': '1', 11:39:39 INFO - 'NO_EM_RESTART': '1', 11:39:39 INFO - 'PAGER': '/bin/cat', 11:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:39 INFO - 'PWD': '/builds/slave/test', 11:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:39 INFO - 'SHELL': '/bin/bash', 11:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:39 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:39 INFO - 'USER': 'cltbld', 11:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:39 INFO - 'XPC_FLAGS': '0x0', 11:39:39 INFO - 'XPC_SERVICE_NAME': '0', 11:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:39 INFO - Downloading/unpacking psutil>=3.1.1 11:39:39 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 11:39:39 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 11:39:39 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 11:39:39 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 11:39:39 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 11:39:39 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 11:39:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:39:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:43 INFO - Installing collected packages: psutil 11:39:43 INFO - Running setup.py install for psutil 11:39:43 INFO - building 'psutil._psutil_osx' extension 11:39:43 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 11:39:44 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 11:39:44 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 11:39:44 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 11:39:44 INFO - building 'psutil._psutil_posix' extension 11:39:44 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 11:39:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:39:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:39:44 INFO - ^ 11:39:44 INFO - 1 warning generated. 11:39:44 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 11:39:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:44 INFO - Successfully installed psutil 11:39:44 INFO - Cleaning up... 11:39:44 INFO - Return code: 0 11:39:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:39:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:44 INFO - 'HOME': '/Users/cltbld', 11:39:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:44 INFO - 'LOGNAME': 'cltbld', 11:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:44 INFO - 'MOZ_NO_REMOTE': '1', 11:39:44 INFO - 'NO_EM_RESTART': '1', 11:39:44 INFO - 'PAGER': '/bin/cat', 11:39:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:44 INFO - 'PWD': '/builds/slave/test', 11:39:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:44 INFO - 'SHELL': '/bin/bash', 11:39:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:44 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:44 INFO - 'USER': 'cltbld', 11:39:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:44 INFO - 'XPC_FLAGS': '0x0', 11:39:44 INFO - 'XPC_SERVICE_NAME': '0', 11:39:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:39:47 INFO - Installing collected packages: mozsystemmonitor 11:39:47 INFO - Running setup.py install for mozsystemmonitor 11:39:47 INFO - Successfully installed mozsystemmonitor 11:39:47 INFO - Cleaning up... 11:39:47 INFO - Return code: 0 11:39:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:47 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:47 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 11:39:47 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 11:39:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:47 INFO - 'HOME': '/Users/cltbld', 11:39:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:47 INFO - 'LOGNAME': 'cltbld', 11:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:47 INFO - 'MOZ_NO_REMOTE': '1', 11:39:47 INFO - 'NO_EM_RESTART': '1', 11:39:47 INFO - 'PAGER': '/bin/cat', 11:39:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:47 INFO - 'PWD': '/builds/slave/test', 11:39:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:47 INFO - 'SHELL': '/bin/bash', 11:39:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:47 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:47 INFO - 'USER': 'cltbld', 11:39:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:47 INFO - 'XPC_FLAGS': '0x0', 11:39:47 INFO - 'XPC_SERVICE_NAME': '0', 11:39:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:48 INFO - Downloading/unpacking jsonschema==2.5.1 11:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:39:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:39:50 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 11:39:50 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 11:39:50 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 11:39:50 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 11:39:50 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 11:39:50 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 11:39:50 INFO - Downloading functools32-3.2.3-2.tar.gz 11:39:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:39:50 INFO - warning: no files found matching '*.txt' 11:39:50 INFO - no previously-included directories found matching 'build' 11:39:50 INFO - no previously-included directories found matching 'dist' 11:39:50 INFO - no previously-included directories found matching '.git*' 11:39:50 INFO - Installing collected packages: jsonschema, functools32 11:39:51 INFO - Running setup.py install for functools32 11:39:51 INFO - warning: no files found matching '*.txt' 11:39:51 INFO - no previously-included directories found matching 'build' 11:39:51 INFO - no previously-included directories found matching 'dist' 11:39:51 INFO - no previously-included directories found matching '.git*' 11:39:51 INFO - Successfully installed jsonschema functools32 11:39:51 INFO - Cleaning up... 11:39:51 INFO - Return code: 0 11:39:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:39:51 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')]} 11:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:51 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')]} 11:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:51 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:51 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 11:39:51 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 11:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:51 INFO - 'HOME': '/Users/cltbld', 11:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:51 INFO - 'LOGNAME': 'cltbld', 11:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:51 INFO - 'MOZ_NO_REMOTE': '1', 11:39:51 INFO - 'NO_EM_RESTART': '1', 11:39:51 INFO - 'PAGER': '/bin/cat', 11:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:51 INFO - 'PWD': '/builds/slave/test', 11:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:51 INFO - 'SHELL': '/bin/bash', 11:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:51 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:51 INFO - 'USER': 'cltbld', 11:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:51 INFO - 'XPC_FLAGS': '0x0', 11:39:51 INFO - 'XPC_SERVICE_NAME': '0', 11:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:39:51 INFO - Cleaning up... 11:39:51 INFO - Return code: 0 11:39:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:39:51 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')]} 11:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:51 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')]} 11:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:51 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:51 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 11:39:51 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 11:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:51 INFO - 'HOME': '/Users/cltbld', 11:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:51 INFO - 'LOGNAME': 'cltbld', 11:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:51 INFO - 'MOZ_NO_REMOTE': '1', 11:39:51 INFO - 'NO_EM_RESTART': '1', 11:39:51 INFO - 'PAGER': '/bin/cat', 11:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:51 INFO - 'PWD': '/builds/slave/test', 11:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:51 INFO - 'SHELL': '/bin/bash', 11:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:51 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:51 INFO - 'USER': 'cltbld', 11:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:51 INFO - 'XPC_FLAGS': '0x0', 11:39:51 INFO - 'XPC_SERVICE_NAME': '0', 11:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:51 INFO - Downloading/unpacking blobuploader==1.2.4 11:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:54 INFO - Downloading blobuploader-1.2.4.tar.gz 11:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:39:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:39:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:55 INFO - Downloading docopt-0.6.1.tar.gz 11:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:39:55 INFO - Installing collected packages: blobuploader, requests, docopt 11:39:55 INFO - Running setup.py install for blobuploader 11:39:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:39:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:39:55 INFO - Running setup.py install for requests 11:39:56 INFO - Running setup.py install for docopt 11:39:56 INFO - Successfully installed blobuploader requests docopt 11:39:56 INFO - Cleaning up... 11:39:56 INFO - Return code: 0 11:39:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39:56 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')]} 11:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:56 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')]} 11:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:56 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:39:56 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 11:39:56 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 11:39:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:39:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:56 INFO - 'HOME': '/Users/cltbld', 11:39:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:56 INFO - 'LOGNAME': 'cltbld', 11:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:56 INFO - 'MOZ_NO_REMOTE': '1', 11:39:56 INFO - 'NO_EM_RESTART': '1', 11:39:56 INFO - 'PAGER': '/bin/cat', 11:39:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:56 INFO - 'PWD': '/builds/slave/test', 11:39:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:56 INFO - 'SHELL': '/bin/bash', 11:39:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:39:56 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:39:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:56 INFO - 'USER': 'cltbld', 11:39:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:56 INFO - 'XPC_FLAGS': '0x0', 11:39:56 INFO - 'XPC_SERVICE_NAME': '0', 11:39:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:56 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-1S3qcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:56 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-rRY96_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:56 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-kPJRVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:56 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Cp05BR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-e0sdNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-TNX9un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-92QHGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-9eNZD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-EYaPsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-dqHqzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-3bmW4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Zt6QlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:57 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-1OJhPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:58 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-q0WmcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:58 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-IyTIJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:58 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-OMvi15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:58 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-4RO7xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:39:58 INFO - Running setup.py install for manifestparser 11:39:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:39:58 INFO - Running setup.py install for mozcrash 11:39:58 INFO - Running setup.py install for mozdebug 11:39:58 INFO - Running setup.py install for mozdevice 11:39:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Running setup.py install for mozfile 11:39:59 INFO - Running setup.py install for mozhttpd 11:39:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Running setup.py install for mozinfo 11:39:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Running setup.py install for mozInstall 11:39:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Running setup.py install for mozleak 11:39:59 INFO - Running setup.py install for mozlog 11:39:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:39:59 INFO - Running setup.py install for moznetwork 11:40:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:40:00 INFO - Running setup.py install for mozprocess 11:40:00 INFO - Running setup.py install for mozprofile 11:40:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:40:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:40:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:40:00 INFO - Running setup.py install for mozrunner 11:40:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:40:00 INFO - Running setup.py install for mozscreenshot 11:40:00 INFO - Running setup.py install for moztest 11:40:00 INFO - Running setup.py install for mozversion 11:40:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:40:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:40:00 INFO - Cleaning up... 11:40:00 INFO - Return code: 0 11:40:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:00 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')]} 11:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:00 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')]} 11:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:00 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:40:00 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 11:40:00 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 11:40:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:40:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:00 INFO - 'HOME': '/Users/cltbld', 11:40:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:00 INFO - 'LOGNAME': 'cltbld', 11:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:00 INFO - 'MOZ_NO_REMOTE': '1', 11:40:00 INFO - 'NO_EM_RESTART': '1', 11:40:00 INFO - 'PAGER': '/bin/cat', 11:40:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:00 INFO - 'PWD': '/builds/slave/test', 11:40:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:00 INFO - 'SHELL': '/bin/bash', 11:40:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:40:00 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:40:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:00 INFO - 'USER': 'cltbld', 11:40:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:00 INFO - 'XPC_FLAGS': '0x0', 11:40:00 INFO - 'XPC_SERVICE_NAME': '0', 11:40:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-jkHMM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-igvyq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uFDaH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-K152Dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ZXwVLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-wkcHWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hSOVW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:40:01 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)) 11:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:40:01 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-B1Ndt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-xK8a3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-vNQnHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-JBrONw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-zhdLzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Rw7Jeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-4A_cgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-rl1KHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-b9Uk18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:40:02 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)) 11:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:40:02 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-IAabVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:40:03 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)) 11:40:03 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)) 11:40:03 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)) 11:40:03 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)) 11:40:03 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)) 11:40:03 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)) 11:40:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:40: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 11:40: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 11:40: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 11:40: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 11:40: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 11:40: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 11:40:05 INFO - Downloading blessings-1.6.tar.gz 11:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:40:05 INFO - Installing collected packages: blessings 11:40:05 INFO - Running setup.py install for blessings 11:40:05 INFO - Successfully installed blessings 11:40:05 INFO - Cleaning up... 11:40:05 INFO - Return code: 0 11:40:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:40:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:40:06 INFO - Reading from file tmpfile_stdout 11:40:06 INFO - Current package versions: 11:40:06 INFO - blessings == 1.6 11:40:06 INFO - blobuploader == 1.2.4 11:40:06 INFO - docopt == 0.6.1 11:40:06 INFO - functools32 == 3.2.3-2 11:40:06 INFO - jsonschema == 2.5.1 11:40:06 INFO - manifestparser == 1.1 11:40:06 INFO - mozInstall == 1.12 11:40:06 INFO - mozcrash == 0.17 11:40:06 INFO - mozdebug == 0.1 11:40:06 INFO - mozdevice == 0.48 11:40:06 INFO - mozfile == 1.2 11:40:06 INFO - mozhttpd == 0.7 11:40:06 INFO - mozinfo == 0.9 11:40:06 INFO - mozleak == 0.1 11:40:06 INFO - mozlog == 3.3 11:40:06 INFO - moznetwork == 0.27 11:40:06 INFO - mozprocess == 0.23 11:40:06 INFO - mozprofile == 0.28 11:40:06 INFO - mozrunner == 6.12 11:40:06 INFO - mozscreenshot == 0.1 11:40:06 INFO - mozsystemmonitor == 0.3 11:40:06 INFO - moztest == 0.7 11:40:06 INFO - mozversion == 1.4 11:40:06 INFO - psutil == 3.1.1 11:40:06 INFO - requests == 1.2.3 11:40:06 INFO - wsgiref == 0.1.2 11:40:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:06 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:40:06 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 11:40:06 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 11:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:06 INFO - 'HOME': '/Users/cltbld', 11:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:06 INFO - 'LOGNAME': 'cltbld', 11:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:06 INFO - 'MOZ_NO_REMOTE': '1', 11:40:06 INFO - 'NO_EM_RESTART': '1', 11:40:06 INFO - 'PAGER': '/bin/cat', 11:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:06 INFO - 'PWD': '/builds/slave/test', 11:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:06 INFO - 'SHELL': '/bin/bash', 11:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:40:06 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:06 INFO - 'USER': 'cltbld', 11:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:06 INFO - 'XPC_FLAGS': '0x0', 11:40:06 INFO - 'XPC_SERVICE_NAME': '0', 11:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 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)) 11:40:06 INFO - Cleaning up... 11:40:06 INFO - Return code: 0 11:40:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:06 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 0x10e1c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e257618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5dad72ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, '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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 11:40:06 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 11:40:06 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 11:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:06 INFO - 'HOME': '/Users/cltbld', 11:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:06 INFO - 'LOGNAME': 'cltbld', 11:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:06 INFO - 'MOZ_NO_REMOTE': '1', 11:40:06 INFO - 'NO_EM_RESTART': '1', 11:40:06 INFO - 'PAGER': '/bin/cat', 11:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:06 INFO - 'PWD': '/builds/slave/test', 11:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:06 INFO - 'SHELL': '/bin/bash', 11:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:40:06 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:06 INFO - 'USER': 'cltbld', 11:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:06 INFO - 'XPC_FLAGS': '0x0', 11:40:06 INFO - 'XPC_SERVICE_NAME': '0', 11:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:40:06 INFO - Cleaning up... 11:40:06 INFO - Return code: 0 11:40:06 INFO - Running post-action listener: _resource_record_post_action 11:40:06 INFO - Running post-action listener: _start_resource_monitoring 11:40:06 INFO - Starting resource monitoring. 11:40:06 INFO - [mozharness: 2016-09-03 18:40:06.771554Z] Finished create-virtualenv step (success) 11:40:06 INFO - [mozharness: 2016-09-03 18:40:06.772516Z] Running install step. 11:40:06 INFO - Running pre-action listener: _resource_record_pre_action 11:40:06 INFO - Running main action method: install 11:40:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:40:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:40:07 INFO - Reading from file tmpfile_stdout 11:40:07 INFO - Detecting whether we're running mozinstall >=1.0... 11:40:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:40:07 INFO - Reading from file tmpfile_stdout 11:40:07 INFO - Output received: 11:40:07 INFO - Usage: mozinstall [options] installer 11:40:07 INFO - Options: 11:40:07 INFO - -h, --help show this help message and exit 11:40:07 INFO - -d DEST, --destination=DEST 11:40:07 INFO - Directory to install application into. [default: 11:40:07 INFO - "/builds/slave/test"] 11:40:07 INFO - --app=APP Application being installed. [default: firefox] 11:40:07 INFO - mkdir: /builds/slave/test/build/application 11:40:07 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'] 11:40:07 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 11:40:41 INFO - Reading from file tmpfile_stdout 11:40:41 INFO - Output received: 11:40:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:40:41 INFO - Running post-action listener: _resource_record_post_action 11:40:41 INFO - [mozharness: 2016-09-03 18:40:41.652582Z] Finished install step (success) 11:40:41 INFO - [mozharness: 2016-09-03 18:40:41.652727Z] Running run-tests step. 11:40:41 INFO - Running pre-action listener: _resource_record_pre_action 11:40:41 INFO - Running main action method: run_tests 11:40:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:40:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:40:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:40:41 INFO - 2016-09-03 11:40:41.665 screenresolution[1642:8054] starting screenresolution argv=screenresolution get 11:40:41 INFO - 2016-09-03 11:40:41.668 screenresolution[1642:8054] Display 0: 1600x1200x32@60 11:40:41 INFO - 2016-09-03 11:40:41.673 screenresolution[1643:8056] starting screenresolution argv=screenresolution list 11:40:41 INFO - Available Modes on Display 0 11:40:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:40:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:40:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:40:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:40:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:40:41 INFO - Intel Iris: 11:40:41 INFO - Chipset Model: Intel Iris 11:40:41 INFO - Type: GPU 11:40:41 INFO - Bus: Built-In 11:40:41 INFO - VRAM (Dynamic, Max): 1536 MB 11:40:41 INFO - Vendor: Intel (0x8086) 11:40:41 INFO - Device ID: 0x0a2e 11:40:41 INFO - Revision ID: 0x0009 11:40:41 INFO - Displays: 11:40:41 INFO - Display: 11:40:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:40:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:40:41 INFO - Display Serial Number: 1600x1200 60 11:40:41 INFO - Main Display: Yes 11:40:41 INFO - Mirror: Off 11:40:41 INFO - Online: Yes 11:40:41 INFO - Rotation: Supported 11:40:41 INFO - Return code: 0 11:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:40:41 INFO - Python 2.7.3 11:40:41 INFO - Return code: 0 11:40:41 INFO - grabbing minidump binary from tooltool 11:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:41 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 0x10e126d30>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e11eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:40:41 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 11:40:41 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 11:40:41 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 11:40:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:40:42 INFO - Return code: 0 11:40:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:40:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:40:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:40:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:40:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:40:42 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/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 11:40:42 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/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0317 --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 11:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1EqsLMZIkW/Render', 11:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:42 INFO - 'HOME': '/Users/cltbld', 11:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:42 INFO - 'LOGNAME': 'cltbld', 11:40:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:42 INFO - 'MOZ_NO_REMOTE': '1', 11:40:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:42 INFO - 'NO_EM_RESTART': '1', 11:40:42 INFO - 'PAGER': '/bin/cat', 11:40:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:42 INFO - 'PWD': '/builds/slave/test', 11:40:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:42 INFO - 'SHELL': '/bin/bash', 11:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5jBdonAZ1B/Listeners', 11:40:42 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 11:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:42 INFO - 'USER': 'cltbld', 11:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:42 INFO - 'XPC_FLAGS': '0x0', 11:40:42 INFO - 'XPC_SERVICE_NAME': '0', 11:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:42 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/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 11:40:42 INFO - mozversion application_buildid: 20160903084649 11:40:42 INFO - mozversion application_changeset: 5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc 11:40:42 INFO - mozversion application_display_name: Nightly 11:40:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:40:42 INFO - mozversion application_name: Firefox 11:40:42 INFO - mozversion application_remotingname: firefox 11:40:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:40:42 INFO - mozversion application_vendor: Mozilla 11:40:42 INFO - mozversion application_version: 51.0a1 11:40:42 INFO - mozversion platform_buildid: 20160903084649 11:40:42 INFO - mozversion platform_changeset: 5e1165d6f7ae3e37fed200666f7a4bfe5fa86ccc 11:40:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:40:42 INFO - mozversion platform_version: 51.0a1 11:40:42 INFO - using testdate: 1472928042 11:40:42 INFO - actual date: 1472928042 11:40:42 INFO - starting webserver on 'localhost:49266' 11:40:42 INFO - SUITE-START | Running 2 tests 11:40:42 INFO - TEST-START | dromaeo_css 11:40:42 INFO - operating with platform_type : mac_ 11:40:42 INFO - Initialising browser for dromaeo_css test... 11:40:42 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpkCa3x6/profile http://localhost:49266/getInfo.html) 11:40:45 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 11:40:45 INFO - PROCESS | 1653 | colorDepth:24 11:40:45 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 11:40:45 INFO - PROCESS | 1653 | __metrics 11:40:45 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:40:45 INFO - PROCESS | 1653 | [Parent 1653] WARNING: pipe error: Broken pipe: file /builds/slave/fx-team-m64-000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 687 11:40:46 INFO - TEST-INFO | 1653: exit 0 11:40:46 INFO - Browser initialized. 11:40:46 INFO - Running cycle 1/1 for dromaeo_css test... 11:40:46 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpkCa3x6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:58 INFO - PROCESS | 1657 | RSS: Main: 244891648 11:40:58 INFO - PROCESS | 1657 | 11:41:47 INFO - PROCESS | 1657 | 1472928107747 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] 11:43:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 11:44:00 INFO - PROCESS | 1657 | RSS: Main: 269639680 11:44:00 INFO - PROCESS | 1657 | 11:47:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 11:47:03 INFO - PROCESS | 1657 | RSS: Main: 275025920 11:47:03 INFO - PROCESS | 1657 | 11:50:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 11:50:06 INFO - PROCESS | 1657 | RSS: Main: 278114304 11:50:06 INFO - PROCESS | 1657 | 11:53:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 11:53:08 INFO - PROCESS | 1657 | RSS: Main: 272904192 11:53:08 INFO - PROCESS | 1657 | 11:56:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 11:56:12 INFO - PROCESS | 1657 | RSS: Main: 278294528 11:56:12 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 11:59:15 INFO - PROCESS | 1657 | RSS: Main: 280821760 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | __start_tp_report 11:59:15 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 11:59:15 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 11:59:15 INFO - PROCESS | 1657 | |i|pagename|runs| 11:59:15 INFO - PROCESS | 1657 | |0;dojo.html;3256.576645036145;3315.237495376062;3321.2195134143485;3364.966350336496;3369.81466019369;5441.37424196217;5514.80698175564;5522.779088836447;5561.610687251894;5565;5455.809046683366;5534.612577119603;5585.609007369486;5611.046122159232;5627.521660658849;5006.874828129289;5079.339685840836;5130.948690513084;5150.639455238135;5191.195364718475;5064.670796398241;5254.605904557155;5260.289860868791;5278.3665960084845;5303.867403314929;4124.896877578085;5074.31496747938;5126.154184559543;5142.794288228466;5170.612204084689;5117.437081920985;5130.71781052042;5143.974280128576;5164.406093299281;5200.479952004807;5107.208382700687;5115.20714289282;5116.027954688598;5118.948810511884;5172.6379153459275;5440.836774896759;5524.806631767869;5539.806106786243;5567.944320556783;5585.832425027215;6029.306629737549;6127.988881834492;6134.570580059398;6144.723487443076;6145.631262124242;6158.076288556707;6192.597481163755;6206.379362063803;6231.532635052389;6249.0626406039;5536.4186760389985;5623.128415095667;5639.295088113986;5676.971615141898;5725.997950358671;5228.189630607262;6173.81478555644;6269.843253918689;6282.717277722513;6300;5965.373635768268;5977.1333156692035;5991.370906054889;6000.309964354155;6001.879962400728;5932.7330270137945;6053.758979409211;6113.541484388688;6138.508919286446;6164.106204600397;5338.572914166857;5430.968116057937;5957.076653118774;6055.455009049207;6068.696565171812;6074.787382441594;6083.574149809471;6088.726007329681;6091.116788065706;6105.32841387447;4761.857144285677;4766.976165119152;4815.711057336571;4819.879503012453;4831;3596.4065929121402;4286.207051695446;4329.935050974238;4349.760763158057;4396;5266.183741520071;5540.667559946416;5691.544676425876;5710.942890571124;5741.799037033684;5972.163897054415;6169.348414847904;6211.81364559064;6212.347703491068;6213.596116252519;4727.220008698595;5611.130274807329;5614.9157762633595;5616.241807356037;5626.2967129108865;5264.973675131677;5266.3943646480375;5269.0252303324005;5275.709835959059;5302.549283310885;6263;6271.686415679198;6286.842828929313;6300.936990630126;6314.02132669437;4907.533784290486;6121.940772763084;6127.264728232641;6135.570510064253;6173.722182501888;6242.344553821965;6256.749730010931;6266.498680105672;6282.434580887742;6317.684115794284;6006.339302676614;6030.547708921761;6070.119832624333;6074.665893375906;6102.420270074336;4942.604591632733;5419.864503387289;5526.668399896019;6122.642120310999;6196.659183744938;4838.322634831196;5906.970465147647;5956.553258505716;5993.820185394445;6014.729337179925;4748.335233067304;5796.76812927495;5893.734781934738;5944.5838791284195;5965.821025369246;4670.556297151765;5005.424376196784;5687.976164290302;5797.971010144923;5804.506616937608;5939.970300148644;5941.702914854326;5960.016597261403;5980.401959803985;5980.641161530322;5651.039323315129;5794.768209271582;5805.709714514173;5806.303243610793;5810.82567522975 11:59:15 INFO - PROCESS | 1657 | |1;ext.html;502.1237939795058;514.8378260847835;516.3958168942338;551.1512271102503;553.7093026161265;36703.63296367035;39026.41460378097;39034;39091.0227244319;39174.82475525735;39551.604483955154;40048.998775030566;40108.99727506814;40145.598544014625;40269.39595906064;9590.992945740683;9753.512324383752;9769.706908792748;9806.019398060173;9810.264230182729;9631.903680963205;9633.133018028377;9729.805403891953;9742.853857192147;9791.755206119868;9751.561179746894;9789.510524473784;9792.6082956682;9802;9810.018998100204;9703.320767546275;9758.707238782848;9764.707058788248;9775.51122443875;9780.951095244549;9581.712548623553;9666.565004574812;9687.854682179774;9709.126178643884;9709.902900971041;38535.42196867049;38790.22419551593;38916;38920;39052.804735976424;3215.373002264536;3240.189952511872;3247.918802029968;3328.5340052392685;4101.487314085739;29441.705582944112;29669.516524173705;29747.70252297471;29931.551026734618;31000;3206.246532064963;3231.2406584452633;3232.5151227315855;3237.465818140004;3333.100062983005;494897;496596;499178;500587;502061;55118.72440637771;55405.72297138489;55495.44504554984;55602.443975560534;55651.72174139103;32843.67156328454;33864.49203261953;33947;51695.48304516982;54077;16285.755713664304;16395;16428.6714265714;16430.1784910754;16510.587235318973;12509.812352814715;12663.430145643468;12663.923566496767;12677.556285529961;12678.985681145487;18548.907255463637;18608;18621.90689046546;18630.882147071217;18678.34625788091;6751.696173672197;6815.522913396114;8182.345412366996;8251.422400431911;8264.669413223524;862.7325529086004;997.4015590645555;1033.9534720937577;1060.6870973062964;1094.441834664327;28850.27874303159;29179.270518237216;29208.977685780897;29499.41001180007;29833.955811546493;953.2516974175239;1019.8623185869961;1037.3672060043368;1047.7014050995583;1069.6630561373297;14356.569302920929;14888.404463821325;14956.850431495546;15024.474143405147;15254;496936;621962;679572;680122;686470;53216.20175697368;53700.73149634305;53759;53822.461775381744;53988.73005635025;1312.960611181666;1323.8345206849144;1371.2663724907127;1371.6296599918167;1373.6359864635879;25464.108756193444;26513.86743066272;26518.469630606894;28662.713372866838;29636.110916672533;43501.12997739964;45510.77244613888;46003.76998114988;46070.53929460663;46095;530728;601386;605431;606364;606918;22509.549809004056;25133.727266364396;25267;25379.85790639389;25438;44059.77970110129;44883.77558112189;44948.77525612351;45140.32289515594;45489.090218196114;33949.321013579094;35867.82066089653;37347.439788402655;37910.62089379181;38120.80939595284;37128.81435592805;37197.44203836891;37831.62168378391;38127;38814.6118538811;35591.46612800758;36087;36834.44748328724;36863.44704829376;37081.81459092687;31145.909893153632;32828.835855820565;32944;33017.50473742944;33117.83441082876;22959;24400.87799560991;24549;24677.50644987126;24772.00911963501 11:59:15 INFO - PROCESS | 1657 | |2;jquery.html;294.3054391635738;311.19555947874744;369.9389600715882;379.12233180187843;400.00997531110505;19214.711779323305;19391.903040484794;20099.618810669293;20411.48971275719;20685;18331.45005649829;20568.79431205691;20775.584488310225;20831.687524687142;21043;5919.585629005971;5944.524438044968;6174.444300012998;6233;6271.968640156792;5985.16207730918;6174.259088909337;6183;6187.659678717669;6190.040543715709;5840.912386314188;6040.275166979968;6136.6011209271255;6144.96927515364;6162.075688646716;6084.421979911901;6110.9388906111035;6129.7854575090105;6140.232470941132;6146.016637338027;5053.570446512051;5215.400228973641;5299.49654782795;5303.575713942875;5310.389305229909;25891;26104.95580176791;26139.73860261411;26188.738112619012;26468.86765566179;2007.277380143162;2114.407965769587;2374.1572978132976;2422.406510404962;2462.1628646260356;15515.301811418512;15759.369625214978;16029.0619815715;16131.693002934488;16403.42588009414;1819.8534922998558;1891.4042076745782;1906.20892329683;1924.9037548122537;1939.864209505335;113627;117867.7462887991;121485;124709;125898.99999999908;10073.345232559861;10403.0637242647;10715.089217416607;10914.23600347968;11377.54489820398;11759.47082381295;13451.798223026663;13515;13604.523841665494;13641.795373069412;7452.77029290161;8307.169283071644;8438.409311348269;8610.913890861137;8740.694075707319;7183.604901730455;7243.275672432715;7614;7744.303012728768;8697;5624.959382514248;6618.966905165444;6647.66761661915;6691.59850408977;6692.498062645321;1578.4080969636386;1724.637826056517;1761.8502427293824;1860.6371757507156;1879.7744270687606;335.15206527485503;393.94681717967705;822.9137538449189;927.8097989912052;1020.1481762728057;11279.774404511863;11475.88524114765;12010.459529321202;12085.214461060003;12105.69735756613;426.8804734674296;429.08176502285136;433.8741764888147;865.1953683074676;987.9209663226925;10800.297980631234;11103.111751059896;11210.327380357205;11275.210735248455;11416.200865939305;504324;584488;585598.2961533483;589440;596997.2252081025;124629;125319;128799;130719.34640326738;135144;851.8509260879322;973.5764942250003;1021.0555236406294;1021.371856308365;1026.1636766035658;18589;18843.96358200312;19697.901510492928;19734;19794.802051979295;41324.38013429861;49125;50671.23993140032;54946.72526637341;57223;77627.83558246518;77912.61043694745;77981;78166;78783.21216787759;7084.574925504487;7455.440841936768;7974.16271291506;7991.64037618297;8218.671253150047;12225.266484010988;13159.407826648016;13284.402201901132;13421.731565368833;13523.797143042666;11365.715857103638;11472.024877885473;11565.306081635128;11603.01374383153;11777;10841.783164336512;11406.486708098322;11521.884781152416;11527.193096483501;11776.646700598996;11343.773124537629;11508.769824603629;11697;11793.705157371134;12016.279023258632;7405.259474052552;7408.666610002455;7505.549667019996;7570.394368450621;7645;11383.943080284876;11602;11634.418279086181;11682.591109311134;11796.997255233058 11:59:15 INFO - PROCESS | 1657 | |3;mootools.html;1522.9086254824708;1527.9083255004698;1790.069164034702;1912.7417798597185;1916.2526614620301;7096.680649370778;7931.325837303822;8664.220220180197;9053.818923621524;9054.818903621923;8655.134486551342;8862.113788621135;8867.556622168882;8886.42238254513;8896.644134234622;2365.6924599802;2411.4333131714034;2417.9516409671796;2420.9273721788286;2424.333308340203;2004.8897310647908;2362.7400985891536;2406.518696260755;2409.6024156014323;2413.625887987356;2388.3162370528735;2394.305651361103;2395.02410511328;2409.289259668402;2410.819188560856;2366.810655147584;2374;2375.4061484628755;2387.092904696213;2389.6176611742126;2375.251795684354;2378.5124049569795;2382.868942208178;2385.1890357278435;2389.23544465771;8903.777405564848;8942.508162051085;8997.055309192507;9008.504532250723;9008.684696035607;5243.501867322599;5407.675539467606;5410.48600382963;5420.457954204588;5440.91838622421;3602.189507360849;3636.1273294409416;3651.6348365163535;3693.759905606154;3704.444333349992;1097.5884043483693;1100.2518287564453;1103.3931337764277;1120.6918097523164;1122.15277465513;11412.42937853104;11666.125040621988;11740.823887641607;11743.823842642367;11745.295282283005;4544.727316361029;4715.811367545329;4802.375691160197;4822.107910036654;4837.419509658864;9990.150837178775;10000.79998400028;10008.549615267331;10021.39871607706;10111.443870587189;5369.543588794918;5372;5375.3549574050585;5380.085385484477;5416.322959630046;4416.53626369233;4651.720896746138;4756.7859446325;4937.8271760488215;4943.431505376856;3903.277893589695;3947.190825880745;4009.51885773709;4050.1292222172374;4053.493313335833;1108.3349990005931;1178.6110583507423;1243.9240057350328;1313.1070871807262;1318.920864748099;769.5344316688435;769.8267889724767;887.2886259885619;944.891337496183;962.870012548311;5634.718264086861;5893.438238866704;6004.369541198199;6028.5478589106;6186.072089186568;439.567026478918;439.84385543131725;440.33509400805013;452.5542340794315;452.82339887443925;3961.3661814109605;3963.960360396359;4054.716169868081;4089.488813898263;4095.385692146202;10439.373637581617;10668.786624267472;10774.892251077546;10805.675829725122;10818.350898946088;9149.63401463934;10764.3003204793;10771.83842242367;10780.622678206226;10795.46022698862;7762.262585054411;8210.137935516805;8303.211194936472;8346.707865224687;8355.707550235713;5922.585419020799;6057.36451158176;6099.298580663103;6116.021436570128;6217.658028808459;9751.073648003428;10026.097651211427;10089.041541053588;10203.489825508546;10235.283530153116;8104.635291411782;10199.694009179737;10216.4380959045;10351.741206469898;10377.16982641402;7299.890501642373;7547.132079810892;7610.771676849703;7673.808154796175;7776.377889768692;8485.91514084876;8505.06444291115;8521.403501754818;8587.484750914966;8611.354148438766;7461.104667439942;7561.357284630868;7605.163432022584;7699.653515591924;7738.961305193663;7377.4098072155175;7478.850422991619;7487.513311634726;7493.40052795786;7552.962235188789;6993.055937448583;7048.436125110082;7108.2536333686285;7118.216996130525;7130.144382673905;4973.179425394769;4993.900121997613;5056.266841308064;5075.3148324975655;5104.285400044071;5043;5048.217526283432;5114.309568208244;5177.611679124156;5245.160774276097 11:59:15 INFO - PROCESS | 1657 | |4;prototype.html;265.13037237859817;282.523947149054;286.85943887495125;299.5859542957241;308.52025100967995;5272.551833094182;5570.053090974534;5605.1592261160795;5624.29696287964;5696.686682232481;4577.8626641200735;4845.055214233226;4876.487968763282;5567.860803479915;5586.944130558693;3754.010374501505;3854.4218367244857;4009.759414435136;4127.752334859918;4141.219275711443;4190.97904510477;4221.831126754926;4223.915521689564;4225.281702110633;4229.682773791963;4221.641160501361;4235.538739134993;4237.830486780541;4254.574542545752;4255.829646847111;3483.8083905385192;3932.705047121463;4138.379243113527;4150.522689890656;4171.520275168334;3171;3631.8547258109643;3635.800030998294;3637.672609465161;3638.3632864248916;4646.024334889677;4895.339129217581;4986.252062190664;5389.595780316453;5472.425395333512;4544.409226800528;4623.861284161447;4779.928301075487;4864.489228631013;4879.707217566922;5197.558207552363;5824.805914787458;5827.737751801179;5839.532837373;5860.06239001762;3465.5148279240925;3962.8811135665687;4014.3777714454013;4039.616236457561;4044.959550404488;5905.557083218733;5943.494802941756;6069.211002569682;6111.2055432793895;6204.503639708858;3440.6043305019743;4024.7786371749835;4025.6376926076796;4100.835966561363;4102.466679331667;3858.421236814444;4085.22380747657;4146.481689788777;4159.984011591572;4166;3494.685478306914;4239.660827133822;4647.604953578916;4803.615710743132;4849.2968519565175;5760.74076666551;5851.268591426072;5879.617824841372;5894.233749612523;5920.644761314335;1219.1892391559513;1227.4231111377637;1376.3462355381114;1384.2386687321932;1386.4177045640854;723.3128527898517;769.8267889724879;777.8915046059456;838.8741688746737;857.7383897911197;4917.65576409655;5371.3822910365025;5375.5162035417;5616.775328986795;5645;6115;6124.295705993779;6155;6164.291106522717;6164.845878852975;5315.548178404802;5546.861328466821;5567.220589117527;5611.382747897728;5648.011597970421;2914.7085291471108;3202.7277681396877;3203.727683146912;3206.4709322961917;3213.7107660310216;5868.383819698955;6233.594816336924;6233.688315584202;6253.5622506425025;6320.494360451153;5374.355077390738;6216.689165541705;6247.125402443661;6250.624962502265;6252.343503932022;5063.518965698253;6155.138280640714;6184.752609895554;6247;6276.215473065867;5718.771249150153;6039.214902062704;6057.394260573908;6107.946026986507;6120.693965301628;5012.448630650699;5993.760249589967;5994.152777430685;6002.909956350571;6034.24571928509;5897.498712609477;5961.642301461753;6049.487628092977;6101.542384321105;6276.435120839146;3394.8981530554124;3882.418745879889;3907.9287977372037;3931.823067962006;3961.830535571589;6090.233832188615;6101;6104.542159337979;6133.693315334304;6157.753689852356;5489.698066606375;5572.944270557405;5869.647821130746;5924.822255332347;5933.584649074523;5039.949600503948;5830.82507524758;5885.411458854081;5890.734916928663;5920.8815823684145;5470.507654311131;5782.363939966516;5803.30360356743;5809.390014048464;5848.853778655568;4879.829205977774;5545.916811247916;5563.944360556343;5875.764969401347;5905.202797622438;4855.320255164351;5747.074729965509;5761.711914404347;5790.986577348863;5795.1314099521005 11:59:15 INFO - PROCESS | 1657 | |5;yui.html;608.8660494691168;718.6622287524865;782.3780094824614;790.0361558898143;813.2436833744619;11715.355655438947;13561.779439850423;15693.372265109381;15961.920190399062;16227.75658365124;14392;14713.411463541446;14716.484923027669;14732.779008314883;14751.631209219748;664.8570557330173;838;845.8731190321455;872.3065163195275;872.8210716803072;867.8134201146752;870.9896520036758;874.1608056265992;874.510274246424;875.8467268228067;878.8637761147032;927.837628415028;932.3753085432743;932.6083045121065;933.1881263300937;797.5573556676043;904.5386852705102;928.2759447630858;929.3866048408086;933.0156684697641;652.7845810882397;687.2714922182503;688.3598253624127;689.8447849233933;691.7336825322237;14894.329755160936;15062.096274223471;15072.698546029018;15092.39630414782;15100.773488397683;12180.634580962496;14396.64008399788;14402.495912643111;14426.927865360605;14465.276736163254;11674.533018679327;11909.642710718692;11935.940320298343;11973.161878668498;11979.640610781691;3816.2367526494813;3820.4269359596005;3881.6506514413836;3947.0921688011917;4004.7797371144575;101311.49344253306;119498.40250798778;120492;122568;127103.36448317792;8780.94628644554;9187.310951678652;10536.841947370796;11231.550737970552;11382.829257561143;22841;22947.541049178923;23163;23178.768212318;23208.53582928332;7585.900044493628;7866.7639970800965;7965.6017199139815;8002.7199048033035;8073.192680731997;3569.446735755962;3869.187470631143;4423.60187583119;4476.597106260452;4485.170243504962;2772.5979732938613;3041.844099242318;3513.771604845677;3519.296140771856;3519.5072689823446;584.4301805739454;649.1269242868334;658.9901151482732;667.0361327881166;674.5547938360659;532.5127508329912;538.4236428688706;545.9980934984349;623.2396476298908;633.0693879996398;7633.961830190813;9050.321225908083;9078.865141857268;9205.907940920639;9229.030951750105;498.1431937068237;582.9271341082365;597.4384078965759;628.720219502317;628.9904702951789;4280.422143010716;4393.030164078198;4429.667774916894;4492.528284530077;4976.2286845539;104696;134468;138221;161097.19451402902;164318;104051;115425;119113.40443297902;131618;136590.31704841412;107206.71173153142;109326.30088636736;111350.44324778325;111610;116820;7459.701611935462;7962.014244658253;8398.916010840057;8697.739067827975;9552.331336806357;53681;60685.393146067974;60946.4763380919;63031;63187.36812631815;82890;86344.5682771582;94549.5272523633;97958;98491.50754246183;13251.204927704368;13358.9332053343;13391.866081339062;13433.19400835953;13559.322033898068;14376.568702938928;14715.92642036783;14754.704905901606;15416.46042388511;15611.453599123975;13805.79291310611;13968.720625587228;13989.440422382992;14174.645633859236;14706.705865882837;13825.79261311061;13847.376868040761;13856.4457421702;14004.43982240699;14157.43370265219;13434.39545220487;13754.93122534381;13769.380377882819;13909.095908765898;14033.929830350782;11190.88809111931;11244.493997769956;11373.886261137613;11742.17804753659;11748.412579370826;8914.73255802327;9512.429254244491;10492.370457772558;11346.149038822286;11574.074074074222 11:59:15 INFO - PROCESS | 1657 | __end_tp_report 11:59:15 INFO - PROCESS | 1657 | __start_cc_report 11:59:15 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,381 11:59:15 INFO - PROCESS | 1657 | __end_cc_report 11:59:15 INFO - PROCESS | 1657 | __startTimestamp1472929155452__endTimestamp 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | ------- Summary: start ------- 11:59:15 INFO - PROCESS | 1657 | Number of tests: 6 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5562.91 Median:5676.97 stddev:658.70 (11.6%) stddev-sans-first:635.39 11:59:15 INFO - PROCESS | 1657 | Values: 3256.6 3315.2 3321.2 3365.0 3369.8 5441.4 5514.8 5522.8 5561.6 5565.0 5455.8 5534.6 5585.6 5611.0 5627.5 5006.9 5079.3 5130.9 5150.6 5191.2 5064.7 5254.6 5260.3 5278.4 5303.9 4124.9 5074.3 5126.2 5142.8 5170.6 5117.4 5130.7 5144.0 5164.4 5200.5 5107.2 5115.2 5116.0 5118.9 5172.6 5440.8 5524.8 5539.8 5567.9 5585.8 6029.3 6128.0 6134.6 6144.7 6145.6 6158.1 6192.6 6206.4 6231.5 6249.1 5536.4 5623.1 5639.3 5677.0 5726.0 5228.2 6173.8 6269.8 6282.7 6300.0 5965.4 5977.1 5991.4 6000.3 6001.9 5932.7 6053.8 6113.5 6138.5 6164.1 5338.6 5431.0 5957.1 6055.5 6068.7 6074.8 6083.6 6088.7 6091.1 6105.3 4761.9 4767.0 4815.7 4819.9 4831.0 3596.4 4286.2 4329.9 4349.8 4396.0 5266.2 5540.7 5691.5 5710.9 5741.8 5972.2 6169.3 6211.8 6212.3 6213.6 4727.2 5611.1 5614.9 5616.2 5626.3 5265.0 5266.4 5269.0 5275.7 5302.5 6263.0 6271.7 6286.8 6300.9 6314.0 4907.5 6121.9 6127.3 6135.6 6173.7 6242.3 6256.7 6266.5 6282.4 6317.7 6006.3 6030.5 6070.1 6074.7 6102.4 4942.6 5419.9 5526.7 6122.6 6196.7 4838.3 5907.0 5956.6 5993.8 6014.7 4748.3 5796.8 5893.7 5944.6 5965.8 4670.6 5005.4 5688.0 5798.0 5804.5 5940.0 5941.7 5960.0 5980.4 5980.6 5651.0 5794.8 5805.7 5806.3 5810.8 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:69132.70 Median:38802.42 stddev:154861.44 (399.1%) stddev-sans-first:155210.14 11:59:15 INFO - PROCESS | 1657 | Values: 502.1 514.8 516.4 551.2 553.7 36703.6 39026.4 39034.0 39091.0 39174.8 39551.6 40049.0 40109.0 40145.6 40269.4 9591.0 9753.5 9769.7 9806.0 9810.3 9631.9 9633.1 9729.8 9742.9 9791.8 9751.6 9789.5 9792.6 9802.0 9810.0 9703.3 9758.7 9764.7 9775.5 9781.0 9581.7 9666.6 9687.9 9709.1 9709.9 38535.4 38790.2 38916.0 38920.0 39052.8 3215.4 3240.2 3247.9 3328.5 4101.5 29441.7 29669.5 29747.7 29931.6 31000.0 3206.2 3231.2 3232.5 3237.5 3333.1 494897.0 496596.0 499178.0 500587.0 502061.0 55118.7 55405.7 55495.4 55602.4 55651.7 32843.7 33864.5 33947.0 51695.5 54077.0 16285.8 16395.0 16428.7 16430.2 16510.6 12509.8 12663.4 12663.9 12677.6 12679.0 18548.9 18608.0 18621.9 18630.9 18678.3 6751.7 6815.5 8182.3 8251.4 8264.7 862.7 997.4 1034.0 1060.7 1094.4 28850.3 29179.3 29209.0 29499.4 29834.0 953.3 1019.9 1037.4 1047.7 1069.7 14356.6 14888.4 14956.9 15024.5 15254.0 496936.0 621962.0 679572.0 680122.0 686470.0 53216.2 53700.7 53759.0 53822.5 53988.7 1313.0 1323.8 1371.3 1371.6 1373.6 25464.1 26513.9 26518.5 28662.7 29636.1 43501.1 45510.8 46003.8 46070.5 46095.0 530728.0 601386.0 605431.0 606364.0 606918.0 22509.5 25133.7 25267.0 25379.9 25438.0 44059.8 44883.8 44948.8 45140.3 45489.1 33949.3 35867.8 37347.4 37910.6 38120.8 37128.8 37197.4 37831.6 38127.0 38814.6 35591.5 36087.0 36834.4 36863.4 37081.8 31145.9 32828.8 32944.0 33017.5 33117.8 22959.0 24400.9 24549.0 24677.5 24772.0 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:34291.72 Median:2244.28 stddev:96010.21 (4278.0%) stddev-sans-first:96245.60 11:59:15 INFO - PROCESS | 1657 | Values: 294.3 311.2 369.9 379.1 400.0 19214.7 19391.9 20099.6 20411.5 20685.0 18331.5 20568.8 20775.6 20831.7 21043.0 5919.6 5944.5 6174.4 6233.0 6272.0 5985.2 6174.3 6183.0 6187.7 6190.0 5840.9 6040.3 6136.6 6145.0 6162.1 6084.4 6110.9 6129.8 6140.2 6146.0 5053.6 5215.4 5299.5 5303.6 5310.4 25891.0 26105.0 26139.7 26188.7 26468.9 2007.3 2114.4 2374.2 2422.4 2462.2 15515.3 15759.4 16029.1 16131.7 16403.4 1819.9 1891.4 1906.2 1924.9 1939.9 113627.0 117867.7 121485.0 124709.0 125899.0 10073.3 10403.1 10715.1 10914.2 11377.5 11759.5 13451.8 13515.0 13604.5 13641.8 7452.8 8307.2 8438.4 8610.9 8740.7 7183.6 7243.3 7614.0 7744.3 8697.0 5625.0 6619.0 6647.7 6691.6 6692.5 1578.4 1724.6 1761.9 1860.6 1879.8 335.2 393.9 822.9 927.8 1020.1 11279.8 11475.9 12010.5 12085.2 12105.7 426.9 429.1 433.9 865.2 987.9 10800.3 11103.1 11210.3 11275.2 11416.2 504324.0 584488.0 585598.3 589440.0 596997.2 124629.0 125319.0 128799.0 130719.3 135144.0 851.9 973.6 1021.1 1021.4 1026.2 18589.0 18844.0 19697.9 19734.0 19794.8 41324.4 49125.0 50671.2 54946.7 57223.0 77627.8 77912.6 77981.0 78166.0 78783.2 7084.6 7455.4 7974.2 7991.6 8218.7 12225.3 13159.4 13284.4 13421.7 13523.8 11365.7 11472.0 11565.3 11603.0 11777.0 10841.8 11406.5 11521.9 11527.2 11776.6 11343.8 11508.8 11697.0 11793.7 12016.3 7405.3 7408.7 7505.5 7570.4 7645.0 11383.9 11602.0 11634.4 11682.6 11797.0 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5744.97 Median:4779.58 stddev:3215.38 (67.3%) stddev-sans-first:3208.74 11:59:15 INFO - PROCESS | 1657 | Values: 1522.9 1527.9 1790.1 1912.7 1916.3 7096.7 7931.3 8664.2 9053.8 9054.8 8655.1 8862.1 8867.6 8886.4 8896.6 2365.7 2411.4 2418.0 2420.9 2424.3 2004.9 2362.7 2406.5 2409.6 2413.6 2388.3 2394.3 2395.0 2409.3 2410.8 2366.8 2374.0 2375.4 2387.1 2389.6 2375.3 2378.5 2382.9 2385.2 2389.2 8903.8 8942.5 8997.1 9008.5 9008.7 5243.5 5407.7 5410.5 5420.5 5440.9 3602.2 3636.1 3651.6 3693.8 3704.4 1097.6 1100.3 1103.4 1120.7 1122.2 11412.4 11666.1 11740.8 11743.8 11745.3 4544.7 4715.8 4802.4 4822.1 4837.4 9990.2 10000.8 10008.5 10021.4 10111.4 5369.5 5372.0 5375.4 5380.1 5416.3 4416.5 4651.7 4756.8 4937.8 4943.4 3903.3 3947.2 4009.5 4050.1 4053.5 1108.3 1178.6 1243.9 1313.1 1318.9 769.5 769.8 887.3 944.9 962.9 5634.7 5893.4 6004.4 6028.5 6186.1 439.6 439.8 440.3 452.6 452.8 3961.4 3964.0 4054.7 4089.5 4095.4 10439.4 10668.8 10774.9 10805.7 10818.4 9149.6 10764.3 10771.8 10780.6 10795.5 7762.3 8210.1 8303.2 8346.7 8355.7 5922.6 6057.4 6099.3 6116.0 6217.7 9751.1 10026.1 10089.0 10203.5 10235.3 8104.6 10199.7 10216.4 10351.7 10377.2 7299.9 7547.1 7610.8 7673.8 7776.4 8485.9 8505.1 8521.4 8587.5 8611.4 7461.1 7561.4 7605.2 7699.7 7739.0 7377.4 7478.9 7487.5 7493.4 7553.0 6993.1 7048.4 7108.3 7118.2 7130.1 4973.2 4993.9 5056.3 5075.3 5104.3 5043.0 5048.2 5114.3 5177.6 5245.2 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4760.74 Median:5481.06 stddev:1508.73 (27.5%) stddev-sans-first:1474.75 11:59:15 INFO - PROCESS | 1657 | Values: 265.1 282.5 286.9 299.6 308.5 5272.6 5570.1 5605.2 5624.3 5696.7 4577.9 4845.1 4876.5 5567.9 5586.9 3754.0 3854.4 4009.8 4127.8 4141.2 4191.0 4221.8 4223.9 4225.3 4229.7 4221.6 4235.5 4237.8 4254.6 4255.8 3483.8 3932.7 4138.4 4150.5 4171.5 3171.0 3631.9 3635.8 3637.7 3638.4 4646.0 4895.3 4986.3 5389.6 5472.4 4544.4 4623.9 4779.9 4864.5 4879.7 5197.6 5824.8 5827.7 5839.5 5860.1 3465.5 3962.9 4014.4 4039.6 4045.0 5905.6 5943.5 6069.2 6111.2 6204.5 3440.6 4024.8 4025.6 4100.8 4102.5 3858.4 4085.2 4146.5 4160.0 4166.0 3494.7 4239.7 4647.6 4803.6 4849.3 5760.7 5851.3 5879.6 5894.2 5920.6 1219.2 1227.4 1376.3 1384.2 1386.4 723.3 769.8 777.9 838.9 857.7 4917.7 5371.4 5375.5 5616.8 5645.0 6115.0 6124.3 6155.0 6164.3 6164.8 5315.5 5546.9 5567.2 5611.4 5648.0 2914.7 3202.7 3203.7 3206.5 3213.7 5868.4 6233.6 6233.7 6253.6 6320.5 5374.4 6216.7 6247.1 6250.6 6252.3 5063.5 6155.1 6184.8 6247.0 6276.2 5718.8 6039.2 6057.4 6107.9 6120.7 5012.4 5993.8 5994.2 6002.9 6034.2 5897.5 5961.6 6049.5 6101.5 6276.4 3394.9 3882.4 3907.9 3931.8 3961.8 6090.2 6101.0 6104.5 6133.7 6157.8 5489.7 5572.9 5869.6 5924.8 5933.6 5039.9 5830.8 5885.4 5890.7 5920.9 5470.5 5782.4 5803.3 5809.4 5848.9 4879.8 5545.9 5563.9 5875.8 5905.2 4855.3 5747.1 5761.7 5791.0 5795.1 11:59:15 INFO - PROCESS | 1657 | 11:59:15 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24639.48 Median:3544.48 stddev:39185.13 (1105.5%) stddev-sans-first:39253.52 11:59:15 INFO - PROCESS | 1657 | Values: 608.9 718.7 782.4 790.0 813.2 11715.4 13561.8 15693.4 15961.9 16227.8 14392.0 14713.4 14716.5 14732.8 14751.6 664.9 838.0 845.9 872.3 872.8 867.8 871.0 874.2 874.5 875.8 878.9 927.8 932.4 932.6 933.2 797.6 904.5 928.3 929.4 933.0 652.8 687.3 688.4 689.8 691.7 14894.3 15062.1 15072.7 15092.4 15100.8 12180.6 14396.6 14402.5 14426.9 14465.3 11674.5 11909.6 11935.9 11973.2 11979.6 3816.2 3820.4 3881.7 3947.1 4004.8 101311.5 119498.4 120492.0 122568.0 127103.4 8780.9 9187.3 10536.8 11231.6 11382.8 22841.0 22947.5 23163.0 23178.8 23208.5 7585.9 7866.8 7965.6 8002.7 8073.2 3569.4 3869.2 4423.6 4476.6 4485.2 2772.6 3041.8 3513.8 3519.3 3519.5 584.4 649.1 659.0 667.0 674.6 532.5 538.4 546.0 623.2 633.1 7634.0 9050.3 9078.9 9205.9 9229.0 498.1 582.9 597.4 628.7 629.0 4280.4 4393.0 4429.7 4492.5 4976.2 104696.0 134468.0 138221.0 161097.2 164318.0 104051.0 115425.0 119113.4 131618.0 136590.3 107206.7 109326.3 111350.4 111610.0 116820.0 7459.7 7962.0 8398.9 8697.7 9552.3 53681.0 60685.4 60946.5 63031.0 63187.4 82890.0 86344.6 94549.5 97958.0 98491.5 13251.2 13358.9 13391.9 13433.2 13559.3 14376.6 14715.9 14754.7 15416.5 15611.5 13805.8 13968.7 13989.4 14174.6 14706.7 13825.8 13847.4 13856.4 14004.4 14157.4 13434.4 13754.9 13769.4 13909.1 14033.9 11190.9 11244.5 11373.9 11742.2 11748.4 8914.7 9512.4 10492.4 11346.1 11574.1 11:59:15 INFO - PROCESS | 1657 | -------- Summary: end -------- 11:59:15 INFO - PROCESS | 1657 | 11:59:16 INFO - TEST-INFO | 1657: exit 0 11:59:16 INFO - TEST-OK | dromaeo_css | took 1113820ms 11:59:16 INFO - TEST-START | kraken 11:59:16 INFO - operating with platform_type : mac_ 11:59:16 INFO - Initialising browser for kraken test... 11:59:16 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp9RAx0Y/profile http://localhost:49266/getInfo.html) 11:59:18 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 11:59:18 INFO - PROCESS | 1664 | colorDepth:24 11:59:18 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 11:59:18 INFO - PROCESS | 1664 | __metrics 11:59:18 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:59:19 INFO - TEST-INFO | 1664: exit 0 11:59:19 INFO - Browser initialized. 11:59:19 INFO - Running cycle 1/1 for kraken test... 11:59:19 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp9RAx0Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:31 INFO - PROCESS | 1666 | RSS: Main: 244981760 11:59:31 INFO - PROCESS | 1666 | 11:59:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 11:59:35 INFO - PROCESS | 1666 | RSS: Main: 257863680 11:59:35 INFO - PROCESS | 1666 | 11:59:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 11:59:38 INFO - PROCESS | 1666 | RSS: Main: 266670080 11:59:38 INFO - PROCESS | 1666 | 11:59:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 11:59:42 INFO - PROCESS | 1666 | RSS: Main: 265269248 11:59:42 INFO - PROCESS | 1666 | 11:59:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 11:59:45 INFO - PROCESS | 1666 | RSS: Main: 267874304 11:59:45 INFO - PROCESS | 1666 | 11:59:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:59:47 INFO - PROCESS | 1666 | RSS: Main: 268218368 11:59:47 INFO - PROCESS | 1666 | 11:59:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 11:59:51 INFO - PROCESS | 1666 | RSS: Main: 268763136 11:59:51 INFO - PROCESS | 1666 | 11:59:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 11:59:55 INFO - PROCESS | 1666 | RSS: Main: 268976128 11:59:55 INFO - PROCESS | 1666 | 11:59:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 11:59:59 INFO - PROCESS | 1666 | RSS: Main: 269467648 11:59:59 INFO - PROCESS | 1666 | 12:00:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:00:01 INFO - PROCESS | 1666 | RSS: Main: 268324864 12:00:01 INFO - PROCESS | 1666 | 12:00:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:00:03 INFO - PROCESS | 1666 | RSS: Main: 268365824 12:00:03 INFO - PROCESS | 1666 | 12:00:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:00:06 INFO - PROCESS | 1666 | RSS: Main: 268734464 12:00:06 INFO - PROCESS | 1666 | 12:00:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:00:10 INFO - PROCESS | 1666 | RSS: Main: 269004800 12:00:10 INFO - PROCESS | 1666 | 12:00:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:00:13 INFO - PROCESS | 1666 | RSS: Main: 269094912 12:00:13 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 12:00:15 INFO - PROCESS | 1666 | RSS: Main: 270905344 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | __start_tp_report 12:00:15 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 12:00:15 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 12:00:15 INFO - PROCESS | 1666 | |i|pagename|runs| 12:00:15 INFO - PROCESS | 1666 | |0;ai-astar;117;157;95;174;176;146;120;118;119;118 12:00:15 INFO - PROCESS | 1666 | |1;audio-beat-detection;148;115;135;159;112;136;136;142;164;146 12:00:15 INFO - PROCESS | 1666 | |2;audio-dft;196;206;209;208;196;194;196;192;199;198 12:00:15 INFO - PROCESS | 1666 | |3;audio-fft;78;101;72;147;52;71;51;51;130;51 12:00:15 INFO - PROCESS | 1666 | |4;audio-oscillator;70;75;127;66;61;85;99;72;99;147 12:00:15 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;90;87;137;122;89;85;137;134;91;86 12:00:15 INFO - PROCESS | 1666 | |6;imaging-darkroom;96;96;95;96;93;151;152;96;95;97 12:00:15 INFO - PROCESS | 1666 | |7;imaging-desaturate;153;153;90;94;120;80;147;152;113;91 12:00:15 INFO - PROCESS | 1666 | |8;json-parse-financial;58;60;59;65;60;58;59;57;59;57 12:00:15 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;92;72;73;71;75;74;70;71;68;71 12:00:15 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;86;84;89;88;88;88;95;79;106;109 12:00:15 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;138;137;138;138;139;134;171;159;193;156 12:00:15 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;164;190;185;152;151;224;160;172;233;188 12:00:15 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;64;61;62;72;89;69;70;86;88;91 12:00:15 INFO - PROCESS | 1666 | __end_tp_report 12:00:15 INFO - PROCESS | 1666 | __start_cc_report 12:00:15 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,955 12:00:15 INFO - PROCESS | 1666 | __end_cc_report 12:00:15 INFO - PROCESS | 1666 | __startTimestamp1472929215945__endTimestamp 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | ------- Summary: start ------- 12:00:15 INFO - PROCESS | 1666 | Number of tests: 14 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:134.00 Median:151.50 stddev:27.45 (18.1%) stddev-sans-first:28.41 12:00:15 INFO - PROCESS | 1666 | Values: 117.0 157.0 95.0 174.0 176.0 146.0 120.0 118.0 119.0 118.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:144.00 stddev:16.67 (11.6%) stddev-sans-first:17.39 12:00:15 INFO - PROCESS | 1666 | Values: 148.0 115.0 135.0 159.0 112.0 136.0 136.0 142.0 164.0 146.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:199.40 Median:198.50 stddev:6.06 (3.1%) stddev-sans-first:6.30 12:00:15 INFO - PROCESS | 1666 | Values: 196.0 206.0 209.0 208.0 196.0 194.0 196.0 192.0 199.0 198.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:80.40 Median:51.50 stddev:34.78 (67.5%) stddev-sans-first:36.87 12:00:15 INFO - PROCESS | 1666 | Values: 78.0 101.0 72.0 147.0 52.0 71.0 51.0 51.0 130.0 51.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:73.50 stddev:28.23 (38.4%) stddev-sans-first:28.99 12:00:15 INFO - PROCESS | 1666 | Values: 70.0 75.0 127.0 66.0 61.0 85.0 99.0 72.0 99.0 147.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:105.80 Median:86.50 stddev:23.41 (27.1%) stddev-sans-first:24.13 12:00:15 INFO - PROCESS | 1666 | Values: 90.0 87.0 137.0 122.0 89.0 85.0 137.0 134.0 91.0 86.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:96.00 stddev:23.64 (24.6%) stddev-sans-first:24.75 12:00:15 INFO - PROCESS | 1666 | Values: 96.0 96.0 95.0 96.0 93.0 151.0 152.0 96.0 95.0 97.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:119.30 Median:116.50 stddev:29.80 (25.6%) stddev-sans-first:29.00 12:00:15 INFO - PROCESS | 1666 | Values: 153.0 153.0 90.0 94.0 120.0 80.0 147.0 152.0 113.0 91.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.00 stddev:2.30 (3.9%) stddev-sans-first:2.40 12:00:15 INFO - PROCESS | 1666 | Values: 58.0 60.0 59.0 65.0 60.0 58.0 59.0 57.0 59.0 57.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:72.50 stddev:6.73 (9.3%) stddev-sans-first:2.12 12:00:15 INFO - PROCESS | 1666 | Values: 92.0 72.0 73.0 71.0 75.0 74.0 70.0 71.0 68.0 71.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:88.00 stddev:9.51 (10.8%) stddev-sans-first:9.90 12:00:15 INFO - PROCESS | 1666 | Values: 86.0 84.0 89.0 88.0 88.0 88.0 95.0 79.0 106.0 109.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:147.50 stddev:19.39 (13.1%) stddev-sans-first:20.05 12:00:15 INFO - PROCESS | 1666 | Values: 138.0 137.0 138.0 138.0 139.0 134.0 171.0 159.0 193.0 156.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:186.50 stddev:28.37 (15.2%) stddev-sans-first:29.34 12:00:15 INFO - PROCESS | 1666 | Values: 164.0 190.0 185.0 152.0 151.0 224.0 160.0 172.0 233.0 188.0 12:00:15 INFO - PROCESS | 1666 | 12:00:15 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:79.00 stddev:12.01 (15.2%) stddev-sans-first:12.03 12:00:15 INFO - PROCESS | 1666 | Values: 64.0 61.0 62.0 72.0 89.0 69.0 70.0 86.0 88.0 91.0 12:00:15 INFO - PROCESS | 1666 | -------- Summary: end -------- 12:00:15 INFO - PROCESS | 1666 | 12:00:16 INFO - TEST-INFO | 1666: exit 0 12:00:16 INFO - TEST-OK | kraken | took 60704ms 12:00:16 INFO - SUITE-END | took 1174s 12:00:17 INFO - Completed test suite (00:19:34) 12:00:17 INFO - javascript benchmark 12:00:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7288.640821265056, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3256.576645036145, 3315.237495376062, 3321.2195134143485, 3364.966350336496, 3369.81466019369, 5441.37424196217, 5514.80698175564, 5522.779088836447, 5561.610687251894, 5565.0, 5455.809046683366, 5534.612577119603, 5585.609007369486, 5611.046122159232, 5627.521660658849, 5006.874828129289, 5079.339685840836, 5130.948690513084, 5150.639455238135, 5191.195364718475, 5064.670796398241, 5254.605904557155, 5260.289860868791, 5278.3665960084845, 5303.867403314929, 4124.896877578085, 5074.31496747938, 5126.154184559543, 5142.794288228466, 5170.612204084689, 5117.437081920985, 5130.71781052042, 5143.974280128576, 5164.406093299281, 5200.479952004807, 5107.208382700687, 5115.20714289282, 5116.027954688598, 5118.948810511884, 5172.6379153459275, 5440.836774896759, 5524.806631767869, 5539.806106786243, 5567.944320556783, 5585.832425027215, 6029.306629737549, 6127.988881834492, 6134.570580059398, 6144.723487443076, 6145.631262124242, 6158.076288556707, 6192.597481163755, 6206.379362063803, 6231.532635052389, 6249.0626406039, 5536.4186760389985, 5623.128415095667, 5639.295088113986, 5676.971615141898, 5725.997950358671, 5228.189630607262, 6173.81478555644, 6269.843253918689, 6282.717277722513, 6300.0, 5965.373635768268, 5977.1333156692035, 5991.370906054889, 6000.309964354155, 6001.879962400728, 5932.7330270137945, 6053.758979409211, 6113.541484388688, 6138.508919286446, 6164.106204600397, 5338.572914166857, 5430.968116057937, 5957.076653118774, 6055.455009049207, 6068.696565171812, 6074.787382441594, 6083.574149809471, 6088.726007329681, 6091.116788065706, 6105.32841387447, 4761.857144285677, 4766.976165119152, 4815.711057336571, 4819.879503012453, 4831.0, 3596.4065929121402, 4286.207051695446, 4329.935050974238, 4349.760763158057, 4396.0, 5266.183741520071, 5540.667559946416, 5691.544676425876, 5710.942890571124, 5741.799037033684, 5972.163897054415, 6169.348414847904, 6211.81364559064, 6212.347703491068, 6213.596116252519, 4727.220008698595, 5611.130274807329, 5614.9157762633595, 5616.241807356037, 5626.2967129108865, 5264.973675131677, 5266.3943646480375, 5269.0252303324005, 5275.709835959059, 5302.549283310885, 6263.0, 6271.686415679198, 6286.842828929313, 6300.936990630126, 6314.02132669437, 4907.533784290486, 6121.940772763084, 6127.264728232641, 6135.570510064253, 6173.722182501888, 6242.344553821965, 6256.749730010931, 6266.498680105672, 6282.434580887742, 6317.684115794284, 6006.339302676614, 6030.547708921761, 6070.119832624333, 6074.665893375906, 6102.420270074336, 4942.604591632733, 5419.864503387289, 5526.668399896019, 6122.642120310999, 6196.659183744938, 4838.322634831196, 5906.970465147647, 5956.553258505716, 5993.820185394445, 6014.729337179925, 4748.335233067304, 5796.76812927495, 5893.734781934738, 5944.5838791284195, 5965.821025369246, 4670.556297151765, 5005.424376196784, 5687.976164290302, 5797.971010144923, 5804.506616937608, 5939.970300148644, 5941.702914854326, 5960.016597261403, 5980.401959803985, 5980.641161530322, 5651.039323315129, 5794.768209271582, 5805.709714514173, 5806.303243610793, 5810.82567522975], "value": 5523.242128155088, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.1237939795058, 514.8378260847835, 516.3958168942338, 551.1512271102503, 553.7093026161265, 36703.63296367035, 39026.41460378097, 39034.0, 39091.0227244319, 39174.82475525735, 39551.604483955154, 40048.998775030566, 40108.99727506814, 40145.598544014625, 40269.39595906064, 9590.992945740683, 9753.512324383752, 9769.706908792748, 9806.019398060173, 9810.264230182729, 9631.903680963205, 9633.133018028377, 9729.805403891953, 9742.853857192147, 9791.755206119868, 9751.561179746894, 9789.510524473784, 9792.6082956682, 9802.0, 9810.018998100204, 9703.320767546275, 9758.707238782848, 9764.707058788248, 9775.51122443875, 9780.951095244549, 9581.712548623553, 9666.565004574812, 9687.854682179774, 9709.126178643884, 9709.902900971041, 38535.42196867049, 38790.22419551593, 38916.0, 38920.0, 39052.804735976424, 3215.373002264536, 3240.189952511872, 3247.918802029968, 3328.5340052392685, 4101.487314085739, 29441.705582944112, 29669.516524173705, 29747.70252297471, 29931.551026734618, 31000.0, 3206.246532064963, 3231.2406584452633, 3232.5151227315855, 3237.465818140004, 3333.100062983005, 494897.0, 496596.0, 499178.0, 500587.0, 502061.0, 55118.72440637771, 55405.72297138489, 55495.44504554984, 55602.443975560534, 55651.72174139103, 32843.67156328454, 33864.49203261953, 33947.0, 51695.48304516982, 54077.0, 16285.755713664304, 16395.0, 16428.6714265714, 16430.1784910754, 16510.587235318973, 12509.812352814715, 12663.430145643468, 12663.923566496767, 12677.556285529961, 12678.985681145487, 18548.907255463637, 18608.0, 18621.90689046546, 18630.882147071217, 18678.34625788091, 6751.696173672197, 6815.522913396114, 8182.345412366996, 8251.422400431911, 8264.669413223524, 862.7325529086004, 997.4015590645555, 1033.9534720937577, 1060.6870973062964, 1094.441834664327, 28850.27874303159, 29179.270518237216, 29208.977685780897, 29499.41001180007, 29833.955811546493, 953.2516974175239, 1019.8623185869961, 1037.3672060043368, 1047.7014050995583, 1069.6630561373297, 14356.569302920929, 14888.404463821325, 14956.850431495546, 15024.474143405147, 15254.0, 496936.0, 621962.0, 679572.0, 680122.0, 686470.0, 53216.20175697368, 53700.73149634305, 53759.0, 53822.461775381744, 53988.73005635025, 1312.960611181666, 1323.8345206849144, 1371.2663724907127, 1371.6296599918167, 1373.6359864635879, 25464.108756193444, 26513.86743066272, 26518.469630606894, 28662.713372866838, 29636.110916672533, 43501.12997739964, 45510.77244613888, 46003.76998114988, 46070.53929460663, 46095.0, 530728.0, 601386.0, 605431.0, 606364.0, 606918.0, 22509.549809004056, 25133.727266364396, 25267.0, 25379.85790639389, 25438.0, 44059.77970110129, 44883.77558112189, 44948.77525612351, 45140.32289515594, 45489.090218196114, 33949.321013579094, 35867.82066089653, 37347.439788402655, 37910.62089379181, 38120.80939595284, 37128.81435592805, 37197.44203836891, 37831.62168378391, 38127.0, 38814.6118538811, 35591.46612800758, 36087.0, 36834.44748328724, 36863.44704829376, 37081.81459092687, 31145.909893153632, 32828.835855820565, 32944.0, 33017.50473742944, 33117.83441082876, 22959.0, 24400.87799560991, 24549.0, 24677.50644987126, 24772.00911963501], "value": 19356.897402210067, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.3054391635738, 311.19555947874744, 369.9389600715882, 379.12233180187843, 400.00997531110505, 19214.711779323305, 19391.903040484794, 20099.618810669293, 20411.48971275719, 20685.0, 18331.45005649829, 20568.79431205691, 20775.584488310225, 20831.687524687142, 21043.0, 5919.585629005971, 5944.524438044968, 6174.444300012998, 6233.0, 6271.968640156792, 5985.16207730918, 6174.259088909337, 6183.0, 6187.659678717669, 6190.040543715709, 5840.912386314188, 6040.275166979968, 6136.6011209271255, 6144.96927515364, 6162.075688646716, 6084.421979911901, 6110.9388906111035, 6129.7854575090105, 6140.232470941132, 6146.016637338027, 5053.570446512051, 5215.400228973641, 5299.49654782795, 5303.575713942875, 5310.389305229909, 25891.0, 26104.95580176791, 26139.73860261411, 26188.738112619012, 26468.86765566179, 2007.277380143162, 2114.407965769587, 2374.1572978132976, 2422.406510404962, 2462.1628646260356, 15515.301811418512, 15759.369625214978, 16029.0619815715, 16131.693002934488, 16403.42588009414, 1819.8534922998558, 1891.4042076745782, 1906.20892329683, 1924.9037548122537, 1939.864209505335, 113627.0, 117867.7462887991, 121485.0, 124709.0, 125898.99999999908, 10073.345232559861, 10403.0637242647, 10715.089217416607, 10914.23600347968, 11377.54489820398, 11759.47082381295, 13451.798223026663, 13515.0, 13604.523841665494, 13641.795373069412, 7452.77029290161, 8307.169283071644, 8438.409311348269, 8610.913890861137, 8740.694075707319, 7183.604901730455, 7243.275672432715, 7614.0, 7744.303012728768, 8697.0, 5624.959382514248, 6618.966905165444, 6647.66761661915, 6691.59850408977, 6692.498062645321, 1578.4080969636386, 1724.637826056517, 1761.8502427293824, 1860.6371757507156, 1879.7744270687606, 335.15206527485503, 393.94681717967705, 822.9137538449189, 927.8097989912052, 1020.1481762728057, 11279.774404511863, 11475.88524114765, 12010.459529321202, 12085.214461060003, 12105.69735756613, 426.8804734674296, 429.08176502285136, 433.8741764888147, 865.1953683074676, 987.9209663226925, 10800.297980631234, 11103.111751059896, 11210.327380357205, 11275.210735248455, 11416.200865939305, 504324.0, 584488.0, 585598.2961533483, 589440.0, 596997.2252081025, 124629.0, 125319.0, 128799.0, 130719.34640326738, 135144.0, 851.8509260879322, 973.5764942250003, 1021.0555236406294, 1021.371856308365, 1026.1636766035658, 18589.0, 18843.96358200312, 19697.901510492928, 19734.0, 19794.802051979295, 41324.38013429861, 49125.0, 50671.23993140032, 54946.72526637341, 57223.0, 77627.83558246518, 77912.61043694745, 77981.0, 78166.0, 78783.21216787759, 7084.574925504487, 7455.440841936768, 7974.16271291506, 7991.64037618297, 8218.671253150047, 12225.266484010988, 13159.407826648016, 13284.402201901132, 13421.731565368833, 13523.797143042666, 11365.715857103638, 11472.024877885473, 11565.306081635128, 11603.01374383153, 11777.0, 10841.783164336512, 11406.486708098322, 11521.884781152416, 11527.193096483501, 11776.646700598996, 11343.773124537629, 11508.769824603629, 11697.0, 11793.705157371134, 12016.279023258632, 7405.259474052552, 7408.666610002455, 7505.549667019996, 7570.394368450621, 7645.0, 11383.943080284876, 11602.0, 11634.418279086181, 11682.591109311134, 11796.997255233058], "value": 9385.418189187758, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.9086254824708, 1527.9083255004698, 1790.069164034702, 1912.7417798597185, 1916.2526614620301, 7096.680649370778, 7931.325837303822, 8664.220220180197, 9053.818923621524, 9054.818903621923, 8655.134486551342, 8862.113788621135, 8867.556622168882, 8886.42238254513, 8896.644134234622, 2365.6924599802, 2411.4333131714034, 2417.9516409671796, 2420.9273721788286, 2424.333308340203, 2004.8897310647908, 2362.7400985891536, 2406.518696260755, 2409.6024156014323, 2413.625887987356, 2388.3162370528735, 2394.305651361103, 2395.02410511328, 2409.289259668402, 2410.819188560856, 2366.810655147584, 2374.0, 2375.4061484628755, 2387.092904696213, 2389.6176611742126, 2375.251795684354, 2378.5124049569795, 2382.868942208178, 2385.1890357278435, 2389.23544465771, 8903.777405564848, 8942.508162051085, 8997.055309192507, 9008.504532250723, 9008.684696035607, 5243.501867322599, 5407.675539467606, 5410.48600382963, 5420.457954204588, 5440.91838622421, 3602.189507360849, 3636.1273294409416, 3651.6348365163535, 3693.759905606154, 3704.444333349992, 1097.5884043483693, 1100.2518287564453, 1103.3931337764277, 1120.6918097523164, 1122.15277465513, 11412.42937853104, 11666.125040621988, 11740.823887641607, 11743.823842642367, 11745.295282283005, 4544.727316361029, 4715.811367545329, 4802.375691160197, 4822.107910036654, 4837.419509658864, 9990.150837178775, 10000.79998400028, 10008.549615267331, 10021.39871607706, 10111.443870587189, 5369.543588794918, 5372.0, 5375.3549574050585, 5380.085385484477, 5416.322959630046, 4416.53626369233, 4651.720896746138, 4756.7859446325, 4937.8271760488215, 4943.431505376856, 3903.277893589695, 3947.190825880745, 4009.51885773709, 4050.1292222172374, 4053.493313335833, 1108.3349990005931, 1178.6110583507423, 1243.9240057350328, 1313.1070871807262, 1318.920864748099, 769.5344316688435, 769.8267889724767, 887.2886259885619, 944.891337496183, 962.870012548311, 5634.718264086861, 5893.438238866704, 6004.369541198199, 6028.5478589106, 6186.072089186568, 439.567026478918, 439.84385543131725, 440.33509400805013, 452.5542340794315, 452.82339887443925, 3961.3661814109605, 3963.960360396359, 4054.716169868081, 4089.488813898263, 4095.385692146202, 10439.373637581617, 10668.786624267472, 10774.892251077546, 10805.675829725122, 10818.350898946088, 9149.63401463934, 10764.3003204793, 10771.83842242367, 10780.622678206226, 10795.46022698862, 7762.262585054411, 8210.137935516805, 8303.211194936472, 8346.707865224687, 8355.707550235713, 5922.585419020799, 6057.36451158176, 6099.298580663103, 6116.021436570128, 6217.658028808459, 9751.073648003428, 10026.097651211427, 10089.041541053588, 10203.489825508546, 10235.283530153116, 8104.635291411782, 10199.694009179737, 10216.4380959045, 10351.741206469898, 10377.16982641402, 7299.890501642373, 7547.132079810892, 7610.771676849703, 7673.808154796175, 7776.377889768692, 8485.91514084876, 8505.06444291115, 8521.403501754818, 8587.484750914966, 8611.354148438766, 7461.104667439942, 7561.357284630868, 7605.163432022584, 7699.653515591924, 7738.961305193663, 7377.4098072155175, 7478.850422991619, 7487.513311634726, 7493.40052795786, 7552.962235188789, 6993.055937448583, 7048.436125110082, 7108.2536333686285, 7118.216996130525, 7130.144382673905, 4973.179425394769, 4993.900121997613, 5056.266841308064, 5075.3148324975655, 5104.285400044071, 5043.0, 5048.217526283432, 5114.309568208244, 5177.611679124156, 5245.160774276097], "value": 4542.469854150181, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.13037237859817, 282.523947149054, 286.85943887495125, 299.5859542957241, 308.52025100967995, 5272.551833094182, 5570.053090974534, 5605.1592261160795, 5624.29696287964, 5696.686682232481, 4577.8626641200735, 4845.055214233226, 4876.487968763282, 5567.860803479915, 5586.944130558693, 3754.010374501505, 3854.4218367244857, 4009.759414435136, 4127.752334859918, 4141.219275711443, 4190.97904510477, 4221.831126754926, 4223.915521689564, 4225.281702110633, 4229.682773791963, 4221.641160501361, 4235.538739134993, 4237.830486780541, 4254.574542545752, 4255.829646847111, 3483.8083905385192, 3932.705047121463, 4138.379243113527, 4150.522689890656, 4171.520275168334, 3171.0, 3631.8547258109643, 3635.800030998294, 3637.672609465161, 3638.3632864248916, 4646.024334889677, 4895.339129217581, 4986.252062190664, 5389.595780316453, 5472.425395333512, 4544.409226800528, 4623.861284161447, 4779.928301075487, 4864.489228631013, 4879.707217566922, 5197.558207552363, 5824.805914787458, 5827.737751801179, 5839.532837373, 5860.06239001762, 3465.5148279240925, 3962.8811135665687, 4014.3777714454013, 4039.616236457561, 4044.959550404488, 5905.557083218733, 5943.494802941756, 6069.211002569682, 6111.2055432793895, 6204.503639708858, 3440.6043305019743, 4024.7786371749835, 4025.6376926076796, 4100.835966561363, 4102.466679331667, 3858.421236814444, 4085.22380747657, 4146.481689788777, 4159.984011591572, 4166.0, 3494.685478306914, 4239.660827133822, 4647.604953578916, 4803.615710743132, 4849.2968519565175, 5760.74076666551, 5851.268591426072, 5879.617824841372, 5894.233749612523, 5920.644761314335, 1219.1892391559513, 1227.4231111377637, 1376.3462355381114, 1384.2386687321932, 1386.4177045640854, 723.3128527898517, 769.8267889724879, 777.8915046059456, 838.8741688746737, 857.7383897911197, 4917.65576409655, 5371.3822910365025, 5375.5162035417, 5616.775328986795, 5645.0, 6115.0, 6124.295705993779, 6155.0, 6164.291106522717, 6164.845878852975, 5315.548178404802, 5546.861328466821, 5567.220589117527, 5611.382747897728, 5648.011597970421, 2914.7085291471108, 3202.7277681396877, 3203.727683146912, 3206.4709322961917, 3213.7107660310216, 5868.383819698955, 6233.594816336924, 6233.688315584202, 6253.5622506425025, 6320.494360451153, 5374.355077390738, 6216.689165541705, 6247.125402443661, 6250.624962502265, 6252.343503932022, 5063.518965698253, 6155.138280640714, 6184.752609895554, 6247.0, 6276.215473065867, 5718.771249150153, 6039.214902062704, 6057.394260573908, 6107.946026986507, 6120.693965301628, 5012.448630650699, 5993.760249589967, 5994.152777430685, 6002.909956350571, 6034.24571928509, 5897.498712609477, 5961.642301461753, 6049.487628092977, 6101.542384321105, 6276.435120839146, 3394.8981530554124, 3882.418745879889, 3907.9287977372037, 3931.823067962006, 3961.830535571589, 6090.233832188615, 6101.0, 6104.542159337979, 6133.693315334304, 6157.753689852356, 5489.698066606375, 5572.944270557405, 5869.647821130746, 5924.822255332347, 5933.584649074523, 5039.949600503948, 5830.82507524758, 5885.411458854081, 5890.734916928663, 5920.8815823684145, 5470.507654311131, 5782.363939966516, 5803.30360356743, 5809.390014048464, 5848.853778655568, 4879.829205977774, 5545.916811247916, 5563.944360556343, 5875.764969401347, 5905.202797622438, 4855.320255164351, 5747.074729965509, 5761.711914404347, 5790.986577348863, 5795.1314099521005], "value": 4254.204995951427, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.8660494691168, 718.6622287524865, 782.3780094824614, 790.0361558898143, 813.2436833744619, 11715.355655438947, 13561.779439850423, 15693.372265109381, 15961.920190399062, 16227.75658365124, 14392.0, 14713.411463541446, 14716.484923027669, 14732.779008314883, 14751.631209219748, 664.8570557330173, 838.0, 845.8731190321455, 872.3065163195275, 872.8210716803072, 867.8134201146752, 870.9896520036758, 874.1608056265992, 874.510274246424, 875.8467268228067, 878.8637761147032, 927.837628415028, 932.3753085432743, 932.6083045121065, 933.1881263300937, 797.5573556676043, 904.5386852705102, 928.2759447630858, 929.3866048408086, 933.0156684697641, 652.7845810882397, 687.2714922182503, 688.3598253624127, 689.8447849233933, 691.7336825322237, 14894.329755160936, 15062.096274223471, 15072.698546029018, 15092.39630414782, 15100.773488397683, 12180.634580962496, 14396.64008399788, 14402.495912643111, 14426.927865360605, 14465.276736163254, 11674.533018679327, 11909.642710718692, 11935.940320298343, 11973.161878668498, 11979.640610781691, 3816.2367526494813, 3820.4269359596005, 3881.6506514413836, 3947.0921688011917, 4004.7797371144575, 101311.49344253306, 119498.40250798778, 120492.0, 122568.0, 127103.36448317792, 8780.94628644554, 9187.310951678652, 10536.841947370796, 11231.550737970552, 11382.829257561143, 22841.0, 22947.541049178923, 23163.0, 23178.768212318, 23208.53582928332, 7585.900044493628, 7866.7639970800965, 7965.6017199139815, 8002.7199048033035, 8073.192680731997, 3569.446735755962, 3869.187470631143, 4423.60187583119, 4476.597106260452, 4485.170243504962, 2772.5979732938613, 3041.844099242318, 3513.771604845677, 3519.296140771856, 3519.5072689823446, 584.4301805739454, 649.1269242868334, 658.9901151482732, 667.0361327881166, 674.5547938360659, 532.5127508329912, 538.4236428688706, 545.9980934984349, 623.2396476298908, 633.0693879996398, 7633.961830190813, 9050.321225908083, 9078.865141857268, 9205.907940920639, 9229.030951750105, 498.1431937068237, 582.9271341082365, 597.4384078965759, 628.720219502317, 628.9904702951789, 4280.422143010716, 4393.030164078198, 4429.667774916894, 4492.528284530077, 4976.2286845539, 104696.0, 134468.0, 138221.0, 161097.19451402902, 164318.0, 104051.0, 115425.0, 119113.40443297902, 131618.0, 136590.31704841412, 107206.71173153142, 109326.30088636736, 111350.44324778325, 111610.0, 116820.0, 7459.701611935462, 7962.014244658253, 8398.916010840057, 8697.739067827975, 9552.331336806357, 53681.0, 60685.393146067974, 60946.4763380919, 63031.0, 63187.36812631815, 82890.0, 86344.5682771582, 94549.5272523633, 97958.0, 98491.50754246183, 13251.204927704368, 13358.9332053343, 13391.866081339062, 13433.19400835953, 13559.322033898068, 14376.568702938928, 14715.92642036783, 14754.704905901606, 15416.46042388511, 15611.453599123975, 13805.79291310611, 13968.720625587228, 13989.440422382992, 14174.645633859236, 14706.705865882837, 13825.79261311061, 13847.376868040761, 13856.4457421702, 14004.43982240699, 14157.43370265219, 13434.39545220487, 13754.93122534381, 13769.380377882819, 13909.095908765898, 14033.929830350782, 11190.88809111931, 11244.493997769956, 11373.886261137613, 11742.17804753659, 11748.412579370826, 8914.73255802327, 9512.429254244491, 10492.370457772558, 11346.149038822286, 11574.074074074222], "value": 7731.106974169356, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 157.0, 95.0, 174.0, 176.0, 146.0, 120.0, 118.0, 119.0, 118.0], "value": 134.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 135.0, 159.0, 112.0, 136.0, 136.0, 142.0, 164.0, 146.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 206.0, 209.0, 208.0, 196.0, 194.0, 196.0, 192.0, 199.0, 198.0], "value": 199.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 101.0, 72.0, 147.0, 52.0, 71.0, 51.0, 51.0, 130.0, 51.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 127.0, 66.0, 61.0, 85.0, 99.0, 72.0, 99.0, 147.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 137.0, 122.0, 89.0, 85.0, 137.0, 134.0, 91.0, 86.0], "value": 105.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 93.0, 151.0, 152.0, 96.0, 95.0, 97.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 90.0, 94.0, 120.0, 80.0, 147.0, 152.0, 113.0, 91.0], "value": 119.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 65.0, 60.0, 58.0, 59.0, 57.0, 59.0, 57.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 73.0, 71.0, 75.0, 74.0, 70.0, 71.0, 68.0, 71.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 89.0, 88.0, 88.0, 88.0, 95.0, 79.0, 106.0, 109.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 138.0, 138.0, 139.0, 134.0, 171.0, 159.0, 193.0, 156.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 190.0, 185.0, 152.0, 151.0, 224.0, 160.0, 172.0, 233.0, 188.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 72.0, 89.0, 69.0, 70.0, 86.0, 88.0, 91.0], "value": 75.2, "unit": "score"}]}]} 12:00:17 INFO - Return code: 0 12:00:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:00:17 ERROR - # TBPL SUCCESS # 12:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:00:17 INFO - 2016-09-03 12:00:17.247 screenresolution[1672:11009] starting screenresolution argv=screenresolution get 12:00:17 INFO - 2016-09-03 12:00:17.250 screenresolution[1672:11009] Display 0: 1600x1200x32@60 12:00:17 INFO - 2016-09-03 12:00:17.256 screenresolution[1673:11011] starting screenresolution argv=screenresolution list 12:00:17 INFO - Available Modes on Display 0 12:00:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:00:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:00:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:00:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:00:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:00:17 INFO - Intel Iris: 12:00:17 INFO - Chipset Model: Intel Iris 12:00:17 INFO - Type: GPU 12:00:17 INFO - Bus: Built-In 12:00:17 INFO - VRAM (Dynamic, Max): 1536 MB 12:00:17 INFO - Vendor: Intel (0x8086) 12:00:17 INFO - Device ID: 0x0a2e 12:00:17 INFO - Revision ID: 0x0009 12:00:17 INFO - Displays: 12:00:17 INFO - Display: 12:00:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:00:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:00:17 INFO - Display Serial Number: 1600x1200 60 12:00:17 INFO - Main Display: Yes 12:00:17 INFO - Mirror: Off 12:00:17 INFO - Online: Yes 12:00:17 INFO - Rotation: Supported 12:00:17 INFO - Return code: 0 12:00:17 INFO - Running post-action listener: _resource_record_post_action 12:00:17 INFO - [mozharness: 2016-09-03 19:00:17.489097Z] Finished run-tests step (success) 12:00:17 INFO - Running post-run listener: _resource_record_post_run 12:00:17 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 31968256; Write bytes: 706425344; Read time: 198; Write time: 2040 12:00:17 INFO - TinderboxPrint: CPU usage
54.6% 12:00:17 INFO - TinderboxPrint: I/O read bytes / time
31,968,256 / 198 12:00:17 INFO - TinderboxPrint: I/O write bytes / time
706,425,344 / 2,040 12:00:17 INFO - TinderboxPrint: CPU idle
2,199.1 (45.4%) 12:00:17 INFO - TinderboxPrint: CPU system
981.9 (20.3%) 12:00:17 INFO - TinderboxPrint: CPU user
1,660.8 (34.3%) 12:00:17 INFO - TinderboxPrint: Swap in / out
433,397,760 / 0 12:00:17 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387768832; Write bytes: 380950016; Read time: 29104; Write time: 793 12:00:17 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 31030272; Write bytes: 299219968; Read time: 183; Write time: 1186 12:00:17 INFO - Running post-run listener: _upload_blobber_files 12:00:17 INFO - Blob upload gear active. 12:00:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:00:17 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 12:00:17 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'] 12:00:17 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 12:00:18 INFO - (blobuploader) - INFO - Open directory for files ... 12:00:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:00:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:00:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:19 INFO - (blobuploader) - INFO - Done attempting. 12:00:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:00:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:00:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:20 INFO - (blobuploader) - INFO - Done attempting. 12:00:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:00:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:00:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:24 INFO - (blobuploader) - INFO - Done attempting. 12:00:24 INFO - (blobuploader) - INFO - Iteration through files over. 12:00:24 INFO - Return code: 0 12:00:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:00:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:00:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/80d567d5570fc187f9ea45820da1312a104b79016c584e2686f5fb65763b13295bd0676ae5babde901e2d49e993e4259d0ea3f51f6f7ab2dee76de835424c0fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533180ed0e219db6bb6dfc8ed303085200a8d1a954ef8ba12495b862edc63829a6ab7e25ec955fb08f41baa7dfee57ced5578bb1c37dd935423b69965a6f5d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eefeb0a9ed7942bb34a93e1a91fb3ad180612c544f6815e43f4ab58614b885f6f2e46c7ccd2123ee6917c5ef640493c654d4331d42dca449257295262d66bebc"} 12:00:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:00:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:00:24 INFO - Contents: 12:00:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/80d567d5570fc187f9ea45820da1312a104b79016c584e2686f5fb65763b13295bd0676ae5babde901e2d49e993e4259d0ea3f51f6f7ab2dee76de835424c0fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533180ed0e219db6bb6dfc8ed303085200a8d1a954ef8ba12495b862edc63829a6ab7e25ec955fb08f41baa7dfee57ced5578bb1c37dd935423b69965a6f5d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eefeb0a9ed7942bb34a93e1a91fb3ad180612c544f6815e43f4ab58614b885f6f2e46c7ccd2123ee6917c5ef640493c654d4331d42dca449257295262d66bebc"} 12:00:24 INFO - Running post-run listener: copy_logs_to_upload_dir 12:00:24 INFO - Copying logs to upload dir... 12:00:24 INFO - mkdir: /builds/slave/test/build/upload/logs 12:00:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.592686 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-03 12:00:24.904309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 12:00:24.907760) ========= 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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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/80d567d5570fc187f9ea45820da1312a104b79016c584e2686f5fb65763b13295bd0676ae5babde901e2d49e993e4259d0ea3f51f6f7ab2dee76de835424c0fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533180ed0e219db6bb6dfc8ed303085200a8d1a954ef8ba12495b862edc63829a6ab7e25ec955fb08f41baa7dfee57ced5578bb1c37dd935423b69965a6f5d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eefeb0a9ed7942bb34a93e1a91fb3ad180612c544f6815e43f4ab58614b885f6f2e46c7ccd2123ee6917c5ef640493c654d4331d42dca449257295262d66bebc"} build_url:https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/atHKmEgmSVeuipPgmXCk7w/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/80d567d5570fc187f9ea45820da1312a104b79016c584e2686f5fb65763b13295bd0676ae5babde901e2d49e993e4259d0ea3f51f6f7ab2dee76de835424c0fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533180ed0e219db6bb6dfc8ed303085200a8d1a954ef8ba12495b862edc63829a6ab7e25ec955fb08f41baa7dfee57ced5578bb1c37dd935423b69965a6f5d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eefeb0a9ed7942bb34a93e1a91fb3ad180612c544f6815e43f4ab58614b885f6f2e46c7ccd2123ee6917c5ef640493c654d4331d42dca449257295262d66bebc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 12:00:24.940879) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 12:00:24.941162) ========= 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.1EqsLMZIkW/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.5jBdonAZ1B/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.005080 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 12:00:24.988866) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 12:00:24.989138) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 12:00:24.989444) ========= ========= Total master_lag: 0.22 =========