builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0274 starttime: 1473114371.6 results: success (0) buildid: 20160905130714 builduid: 4b234818b55343fb98507e2aba794221 revision: abb69c6bc4d47529ec40758e705f039fc9b68d2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.600993) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.601477) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.601750) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.626536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.626812) ========= 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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-05 15:26:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 136M=0s 2016-09-05 15:26:11 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.799315) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.799626) ========= 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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.026001 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:11.848124) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 15:26:11.848494) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev abb69c6bc4d47529ec40758e705f039fc9b68d2b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev abb69c6bc4d47529ec40758e705f039fc9b68d2b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ROX8UjlUUH/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.c9WLx8RVTr/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-05 15:26:11,936 truncating revision to first 12 chars 2016-09-05 15:26:11,936 Setting DEBUG logging. 2016-09-05 15:26:11,936 attempt 1/10 2016-09-05 15:26:11,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/abb69c6bc4d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-05 15:26:12,946 unpacking tar archive at: mozilla-central-abb69c6bc4d4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.279627 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 15:26:13.235830) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:13.236191) ========= script_repo_revision: abb69c6bc4d47529ec40758e705f039fc9b68d2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:13.236594) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:13.236857) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 15:26:13.255763) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-05 15:26:13.257205) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ROX8UjlUUH/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.c9WLx8RVTr/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:13 INFO - MultiFileLogger online at 20160905 15:26:13 in /builds/slave/test 15:26:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:26:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:26:13 INFO - {'append_to_log': False, 15:26:13 INFO - 'base_work_dir': '/builds/slave/test', 15:26:13 INFO - 'blob_upload_branch': 'Firefox', 15:26:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:26:13 INFO - 'branch': 'Firefox', 15:26:13 INFO - 'buildbot_json_path': 'buildprops.json', 15:26:13 INFO - 'config_files': ('talos/mac_config.py',), 15:26:13 INFO - 'default_actions': ('clobber', 15:26:13 INFO - 'read-buildbot-config', 15:26:13 INFO - 'download-and-extract', 15:26:13 INFO - 'populate-webroot', 15:26:13 INFO - 'create-virtualenv', 15:26:13 INFO - 'install', 15:26:13 INFO - 'run-tests'), 15:26:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:26:13 INFO - 'download_minidump_stackwalk': True, 15:26:13 INFO - 'download_symbols': 'ondemand', 15:26:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:26:13 INFO - 'tooltool.py': '/tools/tooltool.py', 15:26:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:26:13 INFO - '/tools/misc-python/virtualenv.py')}, 15:26:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:26:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:26:13 INFO - 'installer_path': 'installer.exe', 15:26:13 INFO - 'log_level': 'info', 15:26:13 INFO - 'log_name': 'talos', 15:26:13 INFO - 'log_to_console': True, 15:26:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:26:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:26:13 INFO - 'opt_config_files': (), 15:26:13 INFO - 'pip_index': False, 15:26:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:26:13 INFO - 'cmd': ('bash', 15:26:13 INFO - '-c', 15:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:26:13 INFO - 'enabled': True, 15:26:13 INFO - 'halt_on_failure': False, 15:26:13 INFO - 'name': 'check_screen_resolution'},), 15:26:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:26:13 INFO - 'cmd': ('bash', 15:26:13 INFO - '-c', 15:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:26:13 INFO - 'enabled': True, 15:26:13 INFO - 'halt_on_failure': False, 15:26:13 INFO - 'name': 'check_screen_resolution'},), 15:26:13 INFO - 'run_cmd_checks_enabled': True, 15:26:13 INFO - 'sps_profile': False, 15:26:13 INFO - 'sps_profile_interval': 0, 15:26:13 INFO - 'suite': 'dromaeojs-e10s', 15:26:13 INFO - 'system_bits': '32', 15:26:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:26:13 INFO - 'title': 't-yosemite-r7-0274', 15:26:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:26:13 INFO - 'use_talos_json': True, 15:26:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:26:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:26:13 INFO - 'work_dir': 'build'} 15:26:13 INFO - [mozharness: 2016-09-05 22:26:13.454528Z] Running clobber step. 15:26:13 INFO - Running pre-action listener: _resource_record_pre_action 15:26:13 INFO - Running main action method: clobber 15:26:13 INFO - rmtree: /builds/slave/test/build 15:26:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:26:15 INFO - Running post-action listener: _resource_record_post_action 15:26:15 INFO - [mozharness: 2016-09-05 22:26:15.673011Z] Finished clobber step (success) 15:26:15 INFO - [mozharness: 2016-09-05 22:26:15.673254Z] Running read-buildbot-config step. 15:26:15 INFO - Running pre-action listener: _resource_record_pre_action 15:26:15 INFO - Running main action method: read_buildbot_config 15:26:15 INFO - Using buildbot properties: 15:26:15 INFO - { 15:26:15 INFO - "project": "", 15:26:15 INFO - "product": "firefox", 15:26:15 INFO - "script_repo_revision": "production", 15:26:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:26:15 INFO - "repository": "", 15:26:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:26:15 INFO - "buildid": "20160905130714", 15:26:15 INFO - "pgo_build": "False", 15:26:15 INFO - "basedir": "/builds/slave/test", 15:26:15 INFO - "buildnumber": 28, 15:26:15 INFO - "slavename": "t-yosemite-r7-0274", 15:26:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:26:15 INFO - "platform": "yosemite_r7", 15:26:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:26:15 INFO - "branch": "mozilla-central", 15:26:15 INFO - "repo_path": "mozilla-central", 15:26:15 INFO - "revision": "abb69c6bc4d47529ec40758e705f039fc9b68d2b", 15:26:15 INFO - "stage_platform": "macosx64", 15:26:15 INFO - "builduid": "4b234818b55343fb98507e2aba794221", 15:26:15 INFO - "slavebuilddir": "test" 15:26:15 INFO - } 15:26:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:26:15 INFO - Running post-action listener: _resource_record_post_action 15:26:15 INFO - [mozharness: 2016-09-05 22:26:15.679100Z] Finished read-buildbot-config step (success) 15:26:15 INFO - [mozharness: 2016-09-05 22:26:15.679319Z] Running download-and-extract step. 15:26:15 INFO - Running pre-action listener: _resource_record_pre_action 15:26:15 INFO - Running main action method: download_and_extract 15:26:15 INFO - mkdir: /builds/slave/test/build/tests 15:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:15 INFO - trying https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:26:15 INFO - Downloading https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/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 15:26:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/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 15:26:17 INFO - Downloaded 1199 bytes. 15:26:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:26:17 INFO - Using the following test package requirements: 15:26:17 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:26:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:26:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:26:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'jsshell-mac.zip'], 15:26:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:26:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:26:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:26:17 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:26:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:26:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:17 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:26:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:26:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:26:17 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:26:24 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 15:26:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:26:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:26:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:26:32 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:26:33 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')]} 15:26:33 INFO - trying https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:26:33 INFO - Downloading https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/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 15:26:36 INFO - Downloaded 111522735 bytes. 15:26:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:26:36 INFO - mkdir: /builds/slave/test/properties 15:26:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:26:36 INFO - Writing to file /builds/slave/test/properties/build_url 15:26:36 INFO - Contents: 15:26:36 INFO - build_url:https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:26:36 INFO - Running post-action listener: _resource_record_post_action 15:26:36 INFO - Running post-action listener: set_extra_try_arguments 15:26:36 INFO - [mozharness: 2016-09-05 22:26:36.937185Z] Finished download-and-extract step (success) 15:26:36 INFO - [mozharness: 2016-09-05 22:26:36.937305Z] Running populate-webroot step. 15:26:36 INFO - Running pre-action listener: _resource_record_pre_action 15:26:36 INFO - Running main action method: populate_webroot 15:26:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:26:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:26:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:26:36 INFO - '--tppagecycles', 15:26:36 INFO - '7'], 15:26:36 INFO - 'tests': ['tsvgm']}}, 15:26:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:26:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:26:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:26:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:26:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['damp', 'tps']}, 15:26:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'tests': ['damp', 'tps']}, 15:26:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['dromaeo_dom']}, 15:26:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:26:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['basic_compositor_video']}, 15:26:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:26:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['a11yr', 15:26:36 INFO - 'ts_paint', 15:26:36 INFO - 'tpaint', 15:26:36 INFO - 'sessionrestore', 15:26:36 INFO - 'sessionrestore_no_auto_restore', 15:26:36 INFO - 'tabpaint']}, 15:26:36 INFO - 'other-e10s': {'tests': ['a11yr', 15:26:36 INFO - 'ts_paint', 15:26:36 INFO - 'tpaint', 15:26:36 INFO - 'sessionrestore', 15:26:36 INFO - 'sessionrestore_no_auto_restore', 15:26:36 INFO - 'tabpaint']}, 15:26:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:26:36 INFO - 'ts_paint', 15:26:36 INFO - 'tpaint', 15:26:36 INFO - 'sessionrestore', 15:26:36 INFO - 'sessionrestore_no_auto_restore', 15:26:36 INFO - 'tabpaint']}, 15:26:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:26:36 INFO - 'ts_paint', 15:26:36 INFO - 'tpaint', 15:26:36 INFO - 'sessionrestore', 15:26:36 INFO - 'sessionrestore_no_auto_restore', 15:26:36 INFO - 'tabpaint']}, 15:26:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['a11yr', 15:26:36 INFO - 'ts_paint', 15:26:36 INFO - 'tpaint', 15:26:36 INFO - 'sessionrestore', 15:26:36 INFO - 'sessionrestore_no_auto_restore', 15:26:36 INFO - 'tabpaint']}, 15:26:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['a11yr', 15:26:36 INFO - 'ts_paint', 15:26:36 INFO - 'tpaint', 15:26:36 INFO - 'sessionrestore', 15:26:36 INFO - 'sessionrestore_no_auto_restore', 15:26:36 INFO - 'tabpaint']}, 15:26:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['tsvgx', 15:26:36 INFO - 'tsvgr_opacity', 15:26:36 INFO - 'tart', 15:26:36 INFO - 'tscrollx', 15:26:36 INFO - 'cart']}, 15:26:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:26:36 INFO - 'tsvgr_opacity', 15:26:36 INFO - 'tart', 15:26:36 INFO - 'tscrollx', 15:26:36 INFO - 'cart']}, 15:26:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'talos_options': ['--disable-e10s'], 15:26:36 INFO - 'tests': ['tp5o']}, 15:26:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'tests': ['tp5o']}, 15:26:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'talos_options': ['--disable-e10s', 15:26:36 INFO - '--xperf_path', 15:26:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:36 INFO - 'tests': ['tp5n']}, 15:26:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:36 INFO - 'talos_options': ['--xperf_path', 15:26:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:36 INFO - 'tests': ['tp5n']}}, 15:26:36 INFO - 'talos.zip': {'path': '', 15:26:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:26:36 INFO - Running post-action listener: _resource_record_post_action 15:26:36 INFO - [mozharness: 2016-09-05 22:26:36.956455Z] Finished populate-webroot step (success) 15:26:36 INFO - [mozharness: 2016-09-05 22:26:36.956565Z] Running create-virtualenv step. 15:26:36 INFO - Running pre-action listener: _resource_record_pre_action 15:26:36 INFO - Running main action method: create_virtualenv 15:26:36 INFO - Creating virtualenv /builds/slave/test/build/venv 15:26:36 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 15:26:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:26:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:26:37 INFO - Using real prefix '/tools/python27' 15:26:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:26:37 INFO - Installing distribute.............................................................................................................................................................................................done. 15:26:40 INFO - Installing pip.................done. 15:26:40 INFO - Return code: 0 15:26:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:26:40 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')]} 15:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:40 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')]} 15:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:40 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:40 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 15:26:40 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 15:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:40 INFO - 'HOME': '/Users/cltbld', 15:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:40 INFO - 'LOGNAME': 'cltbld', 15:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:40 INFO - 'MOZ_NO_REMOTE': '1', 15:26:40 INFO - 'NO_EM_RESTART': '1', 15:26:40 INFO - 'PAGER': '/bin/cat', 15:26:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:40 INFO - 'PWD': '/builds/slave/test', 15:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:40 INFO - 'SHELL': '/bin/bash', 15:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:40 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:40 INFO - 'USER': 'cltbld', 15:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:40 INFO - 'XPC_FLAGS': '0x0', 15:26:40 INFO - 'XPC_SERVICE_NAME': '0', 15:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:41 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 15:26:41 INFO - Cleaning up... 15:26:41 INFO - Return code: 0 15:26:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:26: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')]} 15:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:26:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:41 INFO - 'HOME': '/Users/cltbld', 15:26:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:41 INFO - 'LOGNAME': 'cltbld', 15:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:41 INFO - 'MOZ_NO_REMOTE': '1', 15:26:41 INFO - 'NO_EM_RESTART': '1', 15:26:41 INFO - 'PAGER': '/bin/cat', 15:26:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:41 INFO - 'PWD': '/builds/slave/test', 15:26:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:41 INFO - 'SHELL': '/bin/bash', 15:26:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:41 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:41 INFO - 'USER': 'cltbld', 15:26:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:41 INFO - 'XPC_FLAGS': '0x0', 15:26:41 INFO - 'XPC_SERVICE_NAME': '0', 15:26:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:41 INFO - Downloading/unpacking psutil>=3.1.1 15:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:26:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:45 INFO - Installing collected packages: psutil 15:26:45 INFO - Running setup.py install for psutil 15:26:45 INFO - building 'psutil._psutil_osx' extension 15:26:45 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 15:26:45 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 15:26:46 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 15:26:46 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 15:26:46 INFO - building 'psutil._psutil_posix' extension 15:26:46 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 15:26:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:26:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:26:46 INFO - ^ 15:26:46 INFO - 1 warning generated. 15:26:46 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 15:26:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:46 INFO - Successfully installed psutil 15:26:46 INFO - Cleaning up... 15:26:46 INFO - Return code: 0 15:26:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:26:46 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')]} 15:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:46 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')]} 15:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:46 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:46 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 15:26:46 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 15:26:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:46 INFO - 'HOME': '/Users/cltbld', 15:26:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:46 INFO - 'LOGNAME': 'cltbld', 15:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:46 INFO - 'MOZ_NO_REMOTE': '1', 15:26:46 INFO - 'NO_EM_RESTART': '1', 15:26:46 INFO - 'PAGER': '/bin/cat', 15:26:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:46 INFO - 'PWD': '/builds/slave/test', 15:26:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:46 INFO - 'SHELL': '/bin/bash', 15:26:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:46 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:46 INFO - 'USER': 'cltbld', 15:26:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:46 INFO - 'XPC_FLAGS': '0x0', 15:26:46 INFO - 'XPC_SERVICE_NAME': '0', 15:26:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:26:49 INFO - Installing collected packages: mozsystemmonitor 15:26:49 INFO - Running setup.py install for mozsystemmonitor 15:26:49 INFO - Successfully installed mozsystemmonitor 15:26:49 INFO - Cleaning up... 15:26:49 INFO - Return code: 0 15:26:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:26:49 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')]} 15:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:49 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')]} 15:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:49 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:49 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 15:26:49 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 15:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:49 INFO - 'HOME': '/Users/cltbld', 15:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:49 INFO - 'LOGNAME': 'cltbld', 15:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:49 INFO - 'MOZ_NO_REMOTE': '1', 15:26:49 INFO - 'NO_EM_RESTART': '1', 15:26:49 INFO - 'PAGER': '/bin/cat', 15:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:49 INFO - 'PWD': '/builds/slave/test', 15:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:49 INFO - 'SHELL': '/bin/bash', 15:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:49 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:49 INFO - 'USER': 'cltbld', 15:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:49 INFO - 'XPC_FLAGS': '0x0', 15:26:49 INFO - 'XPC_SERVICE_NAME': '0', 15:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:49 INFO - Downloading/unpacking jsonschema==2.5.1 15:26:49 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 15:26:49 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 15:26:49 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 15:26:49 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 15:26:49 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 15:26:49 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 15:26:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:26:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:26:52 INFO - warning: no files found matching '*.txt' 15:26:52 INFO - no previously-included directories found matching 'build' 15:26:52 INFO - no previously-included directories found matching 'dist' 15:26:52 INFO - no previously-included directories found matching '.git*' 15:26:52 INFO - Installing collected packages: jsonschema, functools32 15:26:52 INFO - Running setup.py install for functools32 15:26:53 INFO - warning: no files found matching '*.txt' 15:26:53 INFO - no previously-included directories found matching 'build' 15:26:53 INFO - no previously-included directories found matching 'dist' 15:26:53 INFO - no previously-included directories found matching '.git*' 15:26:53 INFO - Successfully installed jsonschema functools32 15:26:53 INFO - Cleaning up... 15:26:53 INFO - Return code: 0 15:26:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:53 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:53 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 15:26:53 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 15:26:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:53 INFO - 'HOME': '/Users/cltbld', 15:26:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:53 INFO - 'LOGNAME': 'cltbld', 15:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:53 INFO - 'MOZ_NO_REMOTE': '1', 15:26:53 INFO - 'NO_EM_RESTART': '1', 15:26:53 INFO - 'PAGER': '/bin/cat', 15:26:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:53 INFO - 'PWD': '/builds/slave/test', 15:26:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:53 INFO - 'SHELL': '/bin/bash', 15:26:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:53 INFO - 'USER': 'cltbld', 15:26:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:53 INFO - 'XPC_FLAGS': '0x0', 15:26:53 INFO - 'XPC_SERVICE_NAME': '0', 15:26:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:26:53 INFO - Cleaning up... 15:26:53 INFO - Return code: 0 15:26:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:53 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:53 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 15:26:53 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 15:26:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:53 INFO - 'HOME': '/Users/cltbld', 15:26:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:53 INFO - 'LOGNAME': 'cltbld', 15:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:53 INFO - 'MOZ_NO_REMOTE': '1', 15:26:53 INFO - 'NO_EM_RESTART': '1', 15:26:53 INFO - 'PAGER': '/bin/cat', 15:26:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:53 INFO - 'PWD': '/builds/slave/test', 15:26:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:53 INFO - 'SHELL': '/bin/bash', 15:26:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:53 INFO - 'USER': 'cltbld', 15:26:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:53 INFO - 'XPC_FLAGS': '0x0', 15:26:53 INFO - 'XPC_SERVICE_NAME': '0', 15:26:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:53 INFO - Downloading/unpacking blobuploader==1.2.4 15:26:53 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 15:26:53 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 15:26:53 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 15:26:53 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 15:26:53 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 15:26:53 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 15:26:56 INFO - Downloading blobuploader-1.2.4.tar.gz 15:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:26:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:26:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:57 INFO - Downloading docopt-0.6.1.tar.gz 15:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:26:57 INFO - Installing collected packages: blobuploader, requests, docopt 15:26:57 INFO - Running setup.py install for blobuploader 15:26:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:26:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:26:57 INFO - Running setup.py install for requests 15:26:58 INFO - Running setup.py install for docopt 15:26:58 INFO - Successfully installed blobuploader requests docopt 15:26:58 INFO - Cleaning up... 15:26:58 INFO - Return code: 0 15:26:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:58 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:26:58 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 15:26:58 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 15:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:58 INFO - 'HOME': '/Users/cltbld', 15:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:58 INFO - 'LOGNAME': 'cltbld', 15:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:58 INFO - 'MOZ_NO_REMOTE': '1', 15:26:58 INFO - 'NO_EM_RESTART': '1', 15:26:58 INFO - 'PAGER': '/bin/cat', 15:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:58 INFO - 'PWD': '/builds/slave/test', 15:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:58 INFO - 'SHELL': '/bin/bash', 15:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:26:58 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:58 INFO - 'USER': 'cltbld', 15:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:58 INFO - 'XPC_FLAGS': '0x0', 15:26:58 INFO - 'XPC_SERVICE_NAME': '0', 15:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:58 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-HRy28o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:58 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-vLP4IU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:58 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-jrzviy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:58 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-pE7sVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ySCgZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-yRaBG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ZPyScc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-tmM2LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ZPvIcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-5IQ8tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-2P45nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SD7yEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-uWKLkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Ak427B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-0f2rKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-584GKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Tasp0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:27:00 INFO - Running setup.py install for manifestparser 15:27:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:27:00 INFO - Running setup.py install for mozcrash 15:27:00 INFO - Running setup.py install for mozdebug 15:27:00 INFO - Running setup.py install for mozdevice 15:27:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:27:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:27:00 INFO - Running setup.py install for mozfile 15:27:01 INFO - Running setup.py install for mozhttpd 15:27:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Running setup.py install for mozinfo 15:27:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Running setup.py install for mozInstall 15:27:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Running setup.py install for mozleak 15:27:01 INFO - Running setup.py install for mozlog 15:27:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Running setup.py install for moznetwork 15:27:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Running setup.py install for mozprocess 15:27:02 INFO - Running setup.py install for mozprofile 15:27:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozrunner 15:27:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozscreenshot 15:27:02 INFO - Running setup.py install for moztest 15:27:02 INFO - Running setup.py install for mozversion 15:27:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:27:02 INFO - Cleaning up... 15:27:02 INFO - Return code: 0 15:27:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:02 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:27:02 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 15:27:02 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 15:27:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:27:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:02 INFO - 'HOME': '/Users/cltbld', 15:27:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:02 INFO - 'LOGNAME': 'cltbld', 15:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:02 INFO - 'MOZ_NO_REMOTE': '1', 15:27:02 INFO - 'NO_EM_RESTART': '1', 15:27:02 INFO - 'PAGER': '/bin/cat', 15:27:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:02 INFO - 'PWD': '/builds/slave/test', 15:27:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:02 INFO - 'SHELL': '/bin/bash', 15:27:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:27:02 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:27:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:02 INFO - 'USER': 'cltbld', 15:27:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:02 INFO - 'XPC_FLAGS': '0x0', 15:27:02 INFO - 'XPC_SERVICE_NAME': '0', 15:27:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-PsnZnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-KiY2vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-sVe0K5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SRfv5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-LKy8LU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-KvDX3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-WMP7IV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-s09lVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-5L9h7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ckT8Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-a2SRGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-NWJqUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-10gX5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ay5aB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-OhMa3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-npHjl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-8x8PXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:07 INFO - Downloading blessings-1.6.tar.gz 15:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:27:07 INFO - Installing collected packages: blessings 15:27:07 INFO - Running setup.py install for blessings 15:27:07 INFO - Successfully installed blessings 15:27:07 INFO - Cleaning up... 15:27:07 INFO - Return code: 0 15:27:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:08 INFO - Reading from file tmpfile_stdout 15:27:08 INFO - Current package versions: 15:27:08 INFO - blessings == 1.6 15:27:08 INFO - blobuploader == 1.2.4 15:27:08 INFO - docopt == 0.6.1 15:27:08 INFO - functools32 == 3.2.3-2 15:27:08 INFO - jsonschema == 2.5.1 15:27:08 INFO - manifestparser == 1.1 15:27:08 INFO - mozInstall == 1.12 15:27:08 INFO - mozcrash == 0.17 15:27:08 INFO - mozdebug == 0.1 15:27:08 INFO - mozdevice == 0.48 15:27:08 INFO - mozfile == 1.2 15:27:08 INFO - mozhttpd == 0.7 15:27:08 INFO - mozinfo == 0.9 15:27:08 INFO - mozleak == 0.1 15:27:08 INFO - mozlog == 3.3 15:27:08 INFO - moznetwork == 0.27 15:27:08 INFO - mozprocess == 0.23 15:27:08 INFO - mozprofile == 0.28 15:27:08 INFO - mozrunner == 6.12 15:27:08 INFO - mozscreenshot == 0.1 15:27:08 INFO - mozsystemmonitor == 0.3 15:27:08 INFO - moztest == 0.7 15:27:08 INFO - mozversion == 1.4 15:27:08 INFO - psutil == 3.1.1 15:27:08 INFO - requests == 1.2.3 15:27:08 INFO - wsgiref == 0.1.2 15:27:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:08 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:27:08 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 15:27:08 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 15:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:08 INFO - 'HOME': '/Users/cltbld', 15:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:08 INFO - 'LOGNAME': 'cltbld', 15:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:08 INFO - 'MOZ_NO_REMOTE': '1', 15:27:08 INFO - 'NO_EM_RESTART': '1', 15:27:08 INFO - 'PAGER': '/bin/cat', 15:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:08 INFO - 'PWD': '/builds/slave/test', 15:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:08 INFO - 'SHELL': '/bin/bash', 15:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:27:08 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:08 INFO - 'USER': 'cltbld', 15:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:08 INFO - 'XPC_FLAGS': '0x0', 15:27:08 INFO - 'XPC_SERVICE_NAME': '0', 15:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 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)) 15:27:09 INFO - Cleaning up... 15:27:09 INFO - Return code: 0 15:27:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:27:09 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')]} 15:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:09 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')]} 15:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:09 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 0x100be9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9e9da6980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, '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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 15:27:09 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 15:27:09 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 15:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:09 INFO - 'HOME': '/Users/cltbld', 15:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:09 INFO - 'LOGNAME': 'cltbld', 15:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:09 INFO - 'MOZ_NO_REMOTE': '1', 15:27:09 INFO - 'NO_EM_RESTART': '1', 15:27:09 INFO - 'PAGER': '/bin/cat', 15:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:09 INFO - 'PWD': '/builds/slave/test', 15:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:09 INFO - 'SHELL': '/bin/bash', 15:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:27:09 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:09 INFO - 'USER': 'cltbld', 15:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:09 INFO - 'XPC_FLAGS': '0x0', 15:27:09 INFO - 'XPC_SERVICE_NAME': '0', 15:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:27:09 INFO - Cleaning up... 15:27:09 INFO - Return code: 0 15:27:09 INFO - Running post-action listener: _resource_record_post_action 15:27:09 INFO - Running post-action listener: _start_resource_monitoring 15:27:09 INFO - Starting resource monitoring. 15:27:09 INFO - [mozharness: 2016-09-05 22:27:09.498936Z] Finished create-virtualenv step (success) 15:27:09 INFO - [mozharness: 2016-09-05 22:27:09.499947Z] Running install step. 15:27:09 INFO - Running pre-action listener: _resource_record_pre_action 15:27:09 INFO - Running main action method: install 15:27:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:09 INFO - Reading from file tmpfile_stdout 15:27:09 INFO - Detecting whether we're running mozinstall >=1.0... 15:27:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:27:09 INFO - Reading from file tmpfile_stdout 15:27:09 INFO - Output received: 15:27:09 INFO - Usage: mozinstall [options] installer 15:27:09 INFO - Options: 15:27:09 INFO - -h, --help show this help message and exit 15:27:09 INFO - -d DEST, --destination=DEST 15:27:09 INFO - Directory to install application into. [default: 15:27:09 INFO - "/builds/slave/test"] 15:27:09 INFO - --app=APP Application being installed. [default: firefox] 15:27:09 INFO - mkdir: /builds/slave/test/build/application 15:27:09 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'] 15:27:09 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 15:27:44 INFO - Reading from file tmpfile_stdout 15:27:44 INFO - Output received: 15:27:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:27:44 INFO - Running post-action listener: _resource_record_post_action 15:27:44 INFO - [mozharness: 2016-09-05 22:27:44.051446Z] Finished install step (success) 15:27:44 INFO - [mozharness: 2016-09-05 22:27:44.051575Z] Running run-tests step. 15:27:44 INFO - Running pre-action listener: _resource_record_pre_action 15:27:44 INFO - Running main action method: run_tests 15:27:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:27:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:27:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:27:44 INFO - 2016-09-05 15:27:44.066 screenresolution[1636:7360] starting screenresolution argv=screenresolution get 15:27:44 INFO - 2016-09-05 15:27:44.068 screenresolution[1636:7360] Display 0: 1600x1200x32@60 15:27:44 INFO - 2016-09-05 15:27:44.074 screenresolution[1637:7362] starting screenresolution argv=screenresolution list 15:27:44 INFO - Available Modes on Display 0 15:27:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:27:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:27:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:27:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:27:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:27:44 INFO - Intel Iris: 15:27:44 INFO - Chipset Model: Intel Iris 15:27:44 INFO - Type: GPU 15:27:44 INFO - Bus: Built-In 15:27:44 INFO - VRAM (Dynamic, Max): 1536 MB 15:27:44 INFO - Vendor: Intel (0x8086) 15:27:44 INFO - Device ID: 0x0a2e 15:27:44 INFO - Revision ID: 0x0009 15:27:44 INFO - Displays: 15:27:44 INFO - Display: 15:27:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:27:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:27:44 INFO - Display Serial Number: 1600x1200 60 15:27:44 INFO - Main Display: Yes 15:27:44 INFO - Mirror: Off 15:27:44 INFO - Online: Yes 15:27:44 INFO - Rotation: Supported 15:27:44 INFO - Return code: 0 15:27:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:27:44 INFO - Python 2.7.3 15:27:44 INFO - Return code: 0 15:27:44 INFO - grabbing minidump binary from tooltool 15:27: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')]} 15:27:44 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 0x100b4dd30>, '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 0x1008b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b45b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:27:44 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 15:27:44 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 15:27:44 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 15:27:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:27:44 INFO - Return code: 0 15:27:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:27:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:27:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:27:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:27:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:27:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 15:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 15:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ROX8UjlUUH/Render', 15:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:44 INFO - 'HOME': '/Users/cltbld', 15:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:44 INFO - 'LOGNAME': 'cltbld', 15:27:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:27:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:44 INFO - 'MOZ_NO_REMOTE': '1', 15:27:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:27:44 INFO - 'NO_EM_RESTART': '1', 15:27:44 INFO - 'PAGER': '/bin/cat', 15:27:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:44 INFO - 'PWD': '/builds/slave/test', 15:27:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:44 INFO - 'SHELL': '/bin/bash', 15:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c9WLx8RVTr/Listeners', 15:27:44 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 15:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:44 INFO - 'USER': 'cltbld', 15:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:44 INFO - 'XPC_FLAGS': '0x0', 15:27:44 INFO - 'XPC_SERVICE_NAME': '0', 15:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 15:27:44 INFO - mozversion application_buildid: 20160905130714 15:27:44 INFO - mozversion application_changeset: abb69c6bc4d47529ec40758e705f039fc9b68d2b 15:27:44 INFO - mozversion application_display_name: Nightly 15:27:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:27:44 INFO - mozversion application_name: Firefox 15:27:44 INFO - mozversion application_remotingname: firefox 15:27:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:27:44 INFO - mozversion application_vendor: Mozilla 15:27:44 INFO - mozversion application_version: 51.0a1 15:27:44 INFO - mozversion platform_buildid: 20160905130714 15:27:44 INFO - mozversion platform_changeset: abb69c6bc4d47529ec40758e705f039fc9b68d2b 15:27:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:27:44 INFO - mozversion platform_version: 51.0a1 15:27:44 INFO - using testdate: 1473114464 15:27:44 INFO - actual date: 1473114464 15:27:44 INFO - starting webserver on 'localhost:49272' 15:27:44 INFO - SUITE-START | Running 2 tests 15:27:44 INFO - TEST-START | dromaeo_css 15:27:44 INFO - operating with platform_type : mac_ 15:27:44 INFO - Initialising browser for dromaeo_css test... 15:27:44 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpoq8KTY/profile http://localhost:49272/getInfo.html) 15:27:47 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 15:27:47 INFO - PROCESS | 1647 | colorDepth:24 15:27:47 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 15:27:47 INFO - PROCESS | 1647 | __metrics 15:27:47 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 15:27:48 INFO - TEST-INFO | 1647: exit 0 15:27:48 INFO - Browser initialized. 15:27:48 INFO - Running cycle 1/1 for dromaeo_css test... 15:27:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpoq8KTY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:28:00 INFO - PROCESS | 1650 | RSS: Main: 243957760 15:28:00 INFO - PROCESS | 1650 | 15:28:49 INFO - PROCESS | 1650 | 1473114529793 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] 15:31:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 15:31:03 INFO - PROCESS | 1650 | RSS: Main: 261992448 15:31:03 INFO - PROCESS | 1650 | 15:34:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 15:34:06 INFO - PROCESS | 1650 | RSS: Main: 268881920 15:34:06 INFO - PROCESS | 1650 | 15:37:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 15:37:08 INFO - PROCESS | 1650 | RSS: Main: 274485248 15:37:08 INFO - PROCESS | 1650 | 15:40:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 15:40:11 INFO - PROCESS | 1650 | RSS: Main: 271552512 15:40:11 INFO - PROCESS | 1650 | 15:43:13 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 15:43:14 INFO - PROCESS | 1650 | RSS: Main: 276135936 15:43:14 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 15:46:17 INFO - PROCESS | 1650 | RSS: Main: 279650304 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | __start_tp_report 15:46:17 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 15:46:17 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 15:46:17 INFO - PROCESS | 1650 | |i|pagename|runs| 15:46:17 INFO - PROCESS | 1650 | |0;dojo.html;2710.6205131281617;3446.483027545868;3459.135216195951;3463.5843698756144;3471.1322169457635;4864.781084851182;5678.261825962619;5834.9999999999945;5882.705864706763;5924.58527903047;5774.711264436783;5842.678652674102;5877.118432235166;5925.140854576084;5938.495227905633;4169.437125987998;5247.422783493813;5267.920981185285;5331.813386531472;5346.973265133669;4611.492735799059;5367.785288588471;5382.703951282698;5429.348478182624;5473.397926228112;4935.333729946447;5062.924056139179;5220.843374698765;5388;5390.51485366317;5252.868678283074;5262.921056184122;5266.499682530153;5276.498732620434;5365.570754339644;5251.00230956121;5263.6578622389425;5301.840944771625;5306.204069389584;5387.1111266640955;5859.798741258074;5864.442877926633;5868.354481007129;5942.821715348547;5957.165996760457;6177.104319873638;6194.721237544321;6213.254409470847;6222.471089957359;6231.532635052344;6238.220222472191;6254.530910181755;6259.843503912393;6270.933941230001;6273.8745225095245;5751.971240143773;5766.135079737989;5855.502282306052;5894.941050589525;5981.192539007222;5596.552275817925;5974.551908606785;6291.245050593958;6327.367263273728;6328.398802113792;5576;6210.161628180228;6210.596311239755;6217.875642487125;6236.18929539166;6142.191841058483;6184.81445556634;6185.072239164161;6187.690615469209;6220.035894436279;6176.042713379344;6179.81460556184;6216.564840461125;6225.688715564203;6239.469645080219;6155.6614386209185;6200.3179650238435;6210;6224.782132625336;6240.781572644935;4877.170880950247;4921.827736029221;4932.975335123301;4949;4995.425526064477;4454.175977444183;4454.77726113693;4459.2865141577195;4475.992901597179;4506.324051392317;5502.752376143083;5866.208061911588;5879.323877754027;5891.49922256613;5896.557758168069;6124.441964285689;6153.753849846045;6158.7228574714245;6173.876522469615;6208.751649934043;5897.233359663216;5907.232059832194;5918.467337939606;5919.875223707481;5931.377205393372;5482.369527504388;5496.31296087989;5496.780128794883;5500.092484740053;5505.421930697299;6181.258249010058;6257.874842503215;6330.746770129143;6347.460465860454;6387;6256.124142619946;6261.123442718114;6267.561270711007;6284.528660350401;6285.779997700059;5538.944610553843;6320.051992201023;6356.555041147075;6390.169277993831;6405.967970160119;5900.64596124234;5906.084556893688;6190.0405437157315;6192.721327540181;6238.906416403848;5338.572914166935;6041.640630858022;6224.968875155595;6237.157983672328;6260.215838486191;6224.408681175281;6239.875202496016;6245.96877015612;6246.344133865879;6275.843103922439;6058.818235452944;6111.04482360895;6123.526657168292;6146.9550176471;6235.064740288993;5990.850228744316;6036.396360363929;6116.305337585956;6122.663253521099;6146.907796383147;6148.600286304995;6205.472534834409;6225.968870155621;6237.937620623918;6241.78153764616;5877.706114694162;6032.155498230163;6064.241969753781;6100.481459076028;6111.54163437735 15:46:17 INFO - PROCESS | 1650 | |1;ext.html;487.5368400019982;504.6643981752135;506.26085914564743;515.4150039704933;521.1713375732585;38463.230735385274;41471.79264103679;41565;41585.58414415855;41586.376204356886;42211.788941055325;42702;42707.23452333934;42801.35797963033;42993.14013719732;9818.754531136707;9951.353162044445;9994.250431217652;9999.700008999742;10021.146087973711;8898.332625053115;9573.707549480836;9819.6072157114;9878.209743220561;9891.499367787676;9767.023297670246;9869.161121304698;9884.456354900514;9885.75285617858;9896.50517474127;9831.606735730562;9882.9505852471;9884.851727224097;9885.308028438012;9917.355371900803;9614.326997110205;9716.659916902874;9718.076782705702;9718.465484398355;9750.756231094208;40275.194496109914;40464;40727;40749;40839;3363.5795525559306;3399.490076488522;3399.711024562915;3403.268297316089;3424.400729872262;30805.845970770228;30916.845415773;30939.22651933697;30944.69055309463;31463.213419664462;3197.2965947491516;3234.9191270218435;3254.332861763333;3283.8029718216985;3348.995301409567;421571.8921405373;423111;425597.87201064103;425916;427788;60405;60663.39336606577;61030.99999999911;61119.6944015286;61231.693841530505;29196.416071678446;34957.65042349544;35184.47223291652;35790.82104589512;64601.353986460475;16333.754993675104;16411.851170418195;16469.423570174986;16535.83464165343;16552;11654.825177622342;12585.559505417266;12662.620121396372;12690.302033388067;12728.61814145577;17403.868748531306;19366.70949935752;19410.387928887947;19490.189251697127;19503.609927801648;6630.668466576652;6690.933090669128;7801.375889928906;7816.257455541714;7858.73067412641;829.5561874397168;929.6420877961898;961.8653388525612;1000.9399436033862;1044.3472829481575;28452;29452;29503;30168.698313017027;30668.846655766578;913.4838816069033;1021.2238698589126;1037.3672060043368;1079.0827796373178;1138.2146319039837;12802.871971280169;15470.226488675522;15582.9220853895;15698.134875064196;15703;635350;643378;646088;647347;649262;57447.138292925636;57669.8466030677;58101;58131.4186858126;58253.4174658248;1326.290434617475;1329.4217015598247;1379.1173648864735;1381.8065470834092;1384.4738999180247;25601.23196304114;28227.71772282333;29068.27329316784;29389.971351002612;29488;50415.991680165454;50605.24092138695;50737.74631126821;50769.49230507648;50912.49087509078;428519;438911;439742;439768;441408;26488.470230595667;26562.86718566395;27063.72936270691;27426.451470970867;27993.580096298978;39431.60568394279;47289;47665.76167119142;47886.76056619694;48551.5144848547;38632.034199145244;38787.35969883191;38961.025974350865;39984;40028;29849.806007759445;38475.807620961714;38731.8380448587;39959.2008159841;40064.799676001436;27901.720982790725;36576.634233658384;36631.26737465289;37924.62075379321;40472.19055618812;30121.849390752905;32909.633465458304;33705.98882033543;34992.47511287282;35242;20895.582088358453;25471.745282546937;25534.616980744933;25909.35226619274;26350.209493715218 15:46:17 INFO - PROCESS | 1650 | |2;jquery.html;374.9156439801047;375.76702444484414;377.7884384744543;386.72542494828673;402.9012891841499;18958.90520547397;20495;21345.679814802792;21520.139194432242;21610.56778864424;19970.600587988232;20170;20249.898750506225;20303.59392812143;20493.87283699396;5186.7147306898105;5808.099744539603;6061.030235162375;6098.756049758026;6158.291796443422;3848.7690738555634;5844.941550584483;6101.267847858241;6128.96935515324;6144.6006009609455;6049.546284028694;6065.909011364833;6165.784197553086;6169.907451388211;6181.598196117238;5871.677057761822;6074.544409169308;6117.66352850593;6125.754969801203;6148.907766383485;5848.122781582754;5861.4724674779045;5914.142449344864;5924.14099955504;5941.584089113764;11894.52421903123;12884.904783093449;23657.408564785845;23743.694096824842;24813.25560233196;2065.9690104648444;2240.977590224093;2821.915342539727;2894.0160345482427;2948.557716342544;9908.603655853758;10180.490975451197;10313.123384512326;10399;12006.819897701542;1859.7024476083761;1861.5718384771578;1867.2624313396268;1888.7166924961227;1910.5319196796734;110838.01228944717;126135.36932315372;126999;128332;129018;9700.90299097;10845.078168355778;11507.76984460306;11618.825717614243;11766.764664706658;14456.783148252785;14471;14475.927620362041;14480.637984050272;14509.984301099033;7164;8195.34437245019;8457.957710211409;8477.364197685198;8589.828203435896;8408.873866892001;8412;8427.705030323908;8544.530050847263;8563.015253245956;4448.310511870665;4494.775261236925;4507.774611269489;4548.863534093983;4629.259318509023;1578.179346739712;1652.1573997261482;1882.8870267783977;1936.1771247219874;1939.0789375047023;807.2734538915023;878.2271600991214;890.8663700444985;969.3505351414428;970.5341436110706;20463.693044604344;20760.58478830445;20855;20980.685289720666;21064.684029739565;864.6843901975734;872.1104473437185;883.337496877342;894.4365050018447;923.2337160157188;10843.83734243987;10956.945215273872;11198.552057917756;11315;11348.546058157746;504101;508630;509055;534617;558490;114359;123709;136412;136612.58468538168;141270.29364853314;956.7225504603726;973.8198433289864;996.1980605612551;1004.231762701534;1007.2244371833793;19709.70435443441;19880.90059549693;19975.626188966417;20030.99845007773;20067.89626570494;38950.80524597359;54821.45178548323;55078.44921550893;58896.705516473856;59249.81500369882;76571.61714191617;76600;82279.17720822715;85445.14554854372;85631.57184214039;5346.946530534645;5776.191333213437;7045.436365090678;7909.841803164019;8112.83774324522;9965.651202207888;13123.934380328037;13494.852937500029;13552.390142443812;13652.24912629815;11051.778964420828;11380.260283081574;11592;11635.243709158878;11649.475773590379;10967.780644386909;11200.943995279971;11239.434931164678;11467.139964502863;11609.3614851184;11296.717582060513;11391.715207119889;11604.30374177552;11705.824412633647;11929.880701192877;7252.891206631799;7345.3021962913435;7476.5887876167335;7478.887816682863;7566.394688425023;10826.188035897496;10977;11443.084553235554;11470.4264786762;11507.424628768695 15:46:17 INFO - PROCESS | 1650 | |3;mootools.html;1491.753860612999;1532.3104602928681;1589.3801417447198;1626.5689592258054;1822.5534743987707;7979.162187970267;8603;8767.95616021919;9123.270138388929;9165.770855728602;8719.694810681627;8914.687985920495;8932;8933.285337173027;8979.595918183697;2377.63146712259;2402.411409204747;2420.249722586004;2431.8662473563945;2436.1717016214484;2372.50177462733;2400.6879105716234;2418.298693378918;2422.8667423291713;2430.756924307564;2221.9666704999436;2232.363776323748;2234.7765223477604;2240.3838944290287;2256.650219216016;2377.5244951009868;2384.3800611840875;2384.4754154086245;2396.760323967598;2402.2913240594066;2033.6644453665128;2365.432296248905;2384.8211384146257;2393.234165067179;2398.5202959408016;8933.240674542672;8985.730428087169;9012.324075694349;9068.954655226682;9099.317551183622;5281.445448227958;5408.080626293539;5440.129579267298;5459.672419654794;5460.180972854104;3616.963830361689;3625.6193099724414;3670.376036073874;3679.6872265857432;3688.0226739913946;1090.1442367741286;1093.7156339351745;1093.7429704019628;1097.5884043483693;1114.0419239454063;11636.301821890798;11729.472173752201;11861.5848445304;11875.52497900083;11905.523779048828;4581.335706322631;4668.789904454307;4852.320675105488;4868.41579010521;4877.707337559757;9889.258305626963;9922.950385248028;9955.25335599833;9956.303058785816;9975.102240798364;5303.28405665238;5362.678239305654;5382.569394448435;5402.621816472809;5410.621256512007;4144.544100148981;4393.1872603568445;4728.976355118271;4791.808327666924;4935.654504184673;3208.631007434175;3356.983215083958;3511.438169892856;4023.939640905389;4052.452918855917;1288.954886578984;1293.489071816627;1296.6628676544167;1308.5681725030718;1310.8492523359935;773.1456740301998;867.3711559119588;896.9057749545544;905.805251870851;944.8393773058459;4901.828436004794;5615.578831587647;5852.385499522574;5958.940410595838;6160.907586386208;350.60381768601826;386.8297948902474;392.958739332366;402.3060221118547;424.30414120841846;3916.823742931575;3967.385055316373;4067.7355971861734;4092.877213683594;4114.465119534441;10664.946675266574;10869.40218287986;10988.835167472495;11031.393273369882;11051.336919784839;10876.891231087746;10945.343279403261;10969.506372213269;10980.780384392427;10993.780124397626;7677.61611919413;7979.965108897194;8201.974753155855;8282.875756863532;8327.833443331221;5965.522758179249;5967.910481342697;6086.695665216633;6152.538559607958;6173.81478555644;10137.695869123643;10182.134518566005;10217.59129634836;10339.22455815832;10341.534630941474;9898.059684329977;10286.177105831366;10313.587456501657;10437.634682786065;10446.947765261126;6276.905846412216;6966.12923384577;6993.020977063317;7218.566885986857;7691.230876912264;8218.753437396888;8449.493030418194;8489.74530764078;8495.490270583785;8499.91500085016;7385.7045718170675;7488.625568721651;7525.059367579052;7572.621368931642;7623.847523049619;7345;7389.224131466119;7416.221296763762;7428.1086269647985;7537.472376933562;6911.723531058701;6932.688029038604;6993.545419547713;7015.228324884157;7028.226895041644;4989.501049894981;5044.924326135184;5053.191489361684;5092.108880945893;5124.333836601218;4209.9368509473;5025.698458092526;5171.92242116361;5180.352455943007;5186.559142472781 15:46:17 INFO - PROCESS | 1650 | |4;prototype.html;299.9875420455961;307.58168890309184;315.2214031342586;315.43064768093586;318.1727508477958;4848.951510484899;5320.866978325535;5355.678659280446;5455.636090977256;5498.945010549888;5479.315085614298;5500.1749737539385;5518.613697041209;5522.944770552303;5570.3315602127805;4221.957780422187;4246.129543443602;4252.425922500469;4252.893677658068;4284.742915425085;3843.8654647087355;3957.663598594123;3989.960100398988;4254.808533615979;4276;3875.670568001723;4041.7574945503216;4130.586941305875;4142.544320124784;4189.622933935945;3523.577170739502;3721.348763966295;3913.25648126855;4219.092895027568;4231.4076029355765;3090.443720130376;3683.208110256281;3684.44733290006;3694.9445758313645;3696.5748938872107;5131.61512886535;5265.236540701615;5295.4969277918535;5299.655522391032;5376.946230537684;4143.958560414418;4511.6165125964335;4838.322634831126;4859.246816743411;4865.051314993578;5455.4760993693535;5773.509251713609;5778.884422311572;5791.885961624019;5812.796552120655;3985.2228815381013;4006.1787333596544;4021.2560676274984;4025.879223623296;4032.1734044520804;4920.8031678732805;5834.620749651302;6084.421979911901;6100.72546735398;6117.235345581802;3430.811305378178;3925.3326934421216;4062.7968601569805;4078.3882417636996;4095.5494895561465;3238.7085162335034;3958.1687845552756;4113.609207125318;4169.60388763067;4182.2681030820095;4358.8038538265855;4451.955480445219;4502.851772797915;4599.103174880866;4796.026463592595;4924.212126059885;5470.480304371078;5780.075187969905;5813.941860581424;5894.646321220741;1374.1411617738913;1406.3249640172774;1415.9929200353931;1424.9572512824632;1433.3406632948752;849.9957500212583;869.2654706772767;885.3581153663671;891.157855826251;896.6592694775943;4225.830966761295;4456.5766252205985;4558.293464513005;4561.498235194126;4563.863084107482;4214.431051808028;4254.617084462413;4902.28916807061;5020.849374518771;5024.924626130611;4028.154087641628;4460.394258067094;4481.5070342262325;4492.887677808081;4502.527234640315;2300.482391461908;2315.397996520917;2429.9514009719705;2509.736477669818;2562.93592660185;4287.614114729627;4712.489195549338;4913.624185228142;5034.533078791439;5207.192885102815;4395.362672412482;4697.228635105304;4733.573978341897;5101.438841727407;5111.980767596779;4347.434833471755;4741.6917900335675;4766.976165119152;4922.827701030446;5167.974160129175;4702.623790096853;4875.68308059975;4966.478519755374;4981.4022317320705;5035.212499562749;4467.463904331501;4805.951940480551;4915.557599816034;4945.530174633404;5008.599312055099;4370.890727731705;4397.670174736843;4683.414573178353;4980.551750342486;5053.924191137209;3110.548970399325;3221.935561288808;3388.186835159495;3390.3727810355163;3584.7849129052343;4967.696481839536;4981.975090124672;5000.374953130859;5018.021485810232;5560.666360018412;4664.953350466452;5115.897682046412;5384.461553844584;5577.581681373832;5677.772889084556;5191.299174611379;5250.97374513125;5531.2532808072;5616.213730077874;5724.627899186706;5274.947250527445;5542.307211598551;5556.833295001156;5639.503507825175;5683.516901063456;4951.752412379438;5093.490650934877;5701.287339082614;5713.571607098225;5744.310682717934;4979.377577802775;5211.5049070338255;5565.248691426606;5654.2649455572155;5658.151277308272 15:46:17 INFO - PROCESS | 1650 | |5;yui.html;590.6845166945574;612.2163630552891;677.945794289879;729.485712572636;761.7752762934933;15393.538193854174;16116.27476763547;16342.019478831278;16349.754753678704;16360;14774.335154918028;14872.776908346383;15078.86749266059;15771.21143942804;15884.52346429606;844.3793811548492;858.7509622209552;868.6438560190323;873.1094283830489;873.9038705742363;682.8600136971934;846.6444093480723;849.9915000850006;852.4033176776281;867.5315329721942;907.9682211122612;908.9181973622373;912.3203213605873;913.1234015345276;914.6570036236411;819.7499762572443;865.709987154304;898.2275243290732;903.7740564858785;903.9728808135767;664.8537321789198;670.8188789026984;670.8859493886051;672.5393105722595;675.0212192321154;15407.845921540753;15453.613659658598;15476;15478.5356439307;15529.068255904682;13433.395497202551;14020.369083391271;14068.929655351658;14130.505432309923;14365.353559089866;11632.53469861213;11974.760504789943;12052.819207711891;12076;12480.50077996879;3737.8878633641034;3767.4537192107264;3804.6956243500454;3814.351560234739;3964.306246406867;123985;124233;124791;125524.37237813843;126399.73600263972;10571.260011799253;10857.945710271399;10909.290896091887;10960.725981850359;11282.266652667551;22325;22462.550748985257;22497.100115995505;22513;22570.322890313317;7591.848163036709;7655.617219139132;7736.690532378754;7816.16538726451;7885.172056934054;4140.192662430797;4154.1068670236045;4169.061961058798;4186.591003789775;4218.261804184255;3582.9283414331567;3688.8340024698955;3689.6679298863232;3695.131644063643;3705.0552109212517;694.2814187316104;698.1308271202386;711.8825393809964;714.7033980897869;730.0691618186748;537.0949949335383;539.9055165346049;638.8243233110932;658.5159907468008;659.2385794407467;8084.7574572762915;8113.584190038973;8907.97558280806;8967;9263.212626926786;513.3172880069499;544.4881811097556;642.3218746282881;646;674.2010717300016;4154.854580089743;4226.964393723558;4390.165868484977;4431.8448854289945;4492.505824359318;101780;149374.25312873584;152759;154185;154805.2259738694;115368;115451;116234;117777;119823.40088299676;81754;110629.44685276684;111189.44405277922;114491.42754286341;117109.82890171159;8884;8928.821423571362;8934.4192627479;8965.955170224108;8998.460092394478;49439.50560494349;52170;53387.733061334446;53976;66895.33104669086;69194.61610767656;83583.5820820892;88936.555317223;90190.54904725435;90668.54665726628;11493.597724079616;13707.657308566966;13787.931060344634;13802;14207.857921420653;14380.209088499814;14641.194734289717;14751;14811.629709256924;15231.847681523486;13643.454261829813;13652.590422287347;13875.236861972284;13875.86124138786;13923.164610123426;12691.93654031724;13388.389264093408;13505.594832155051;13609.319534023458;14130.364133613806;13451.46194152223;13483.325833708079;13563;13695.589132326046;14093.224872632103;10734.843906353546;11256.155788315746;11418.885811141783;11545.191836571359;12015.519379225083;10570.735731606772;11400.94299528497;11496;11724.824127638263;11742.706432338919 15:46:17 INFO - PROCESS | 1650 | __end_tp_report 15:46:17 INFO - PROCESS | 1650 | __start_cc_report 15:46:17 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,382 15:46:17 INFO - PROCESS | 1650 | __end_cc_report 15:46:17 INFO - PROCESS | 1650 | __startTimestamp1473115577574__endTimestamp 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | ------- Summary: start ------- 15:46:17 INFO - PROCESS | 1650 | Number of tests: 6 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5767.26 Median:5981.19 stddev:656.50 (11.0%) stddev-sans-first:613.16 15:46:17 INFO - PROCESS | 1650 | Values: 2710.6 3446.5 3459.1 3463.6 3471.1 4864.8 5678.3 5835.0 5882.7 5924.6 5774.7 5842.7 5877.1 5925.1 5938.5 4169.4 5247.4 5267.9 5331.8 5347.0 4611.5 5367.8 5382.7 5429.3 5473.4 4935.3 5062.9 5220.8 5388.0 5390.5 5252.9 5262.9 5266.5 5276.5 5365.6 5251.0 5263.7 5301.8 5306.2 5387.1 5859.8 5864.4 5868.4 5942.8 5957.2 6177.1 6194.7 6213.3 6222.5 6231.5 6238.2 6254.5 6259.8 6270.9 6273.9 5752.0 5766.1 5855.5 5894.9 5981.2 5596.6 5974.6 6291.2 6327.4 6328.4 5576.0 6210.2 6210.6 6217.9 6236.2 6142.2 6184.8 6185.1 6187.7 6220.0 6176.0 6179.8 6216.6 6225.7 6239.5 6155.7 6200.3 6210.0 6224.8 6240.8 4877.2 4921.8 4933.0 4949.0 4995.4 4454.2 4454.8 4459.3 4476.0 4506.3 5502.8 5866.2 5879.3 5891.5 5896.6 6124.4 6153.8 6158.7 6173.9 6208.8 5897.2 5907.2 5918.5 5919.9 5931.4 5482.4 5496.3 5496.8 5500.1 5505.4 6181.3 6257.9 6330.7 6347.5 6387.0 6256.1 6261.1 6267.6 6284.5 6285.8 5538.9 6320.1 6356.6 6390.2 6406.0 5900.6 5906.1 6190.0 6192.7 6238.9 5338.6 6041.6 6225.0 6237.2 6260.2 6224.4 6239.9 6246.0 6246.3 6275.8 6058.8 6111.0 6123.5 6147.0 6235.1 5990.9 6036.4 6116.3 6122.7 6146.9 6148.6 6205.5 6226.0 6237.9 6241.8 5877.7 6032.2 6064.2 6100.5 6111.5 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:63984.52 Median:40046.40 stddev:136743.60 (341.5%) stddev-sans-first:137044.09 15:46:17 INFO - PROCESS | 1650 | Values: 487.5 504.7 506.3 515.4 521.2 38463.2 41471.8 41565.0 41585.6 41586.4 42211.8 42702.0 42707.2 42801.4 42993.1 9818.8 9951.4 9994.3 9999.7 10021.1 8898.3 9573.7 9819.6 9878.2 9891.5 9767.0 9869.2 9884.5 9885.8 9896.5 9831.6 9883.0 9884.9 9885.3 9917.4 9614.3 9716.7 9718.1 9718.5 9750.8 40275.2 40464.0 40727.0 40749.0 40839.0 3363.6 3399.5 3399.7 3403.3 3424.4 30805.8 30916.8 30939.2 30944.7 31463.2 3197.3 3234.9 3254.3 3283.8 3349.0 421571.9 423111.0 425597.9 425916.0 427788.0 60405.0 60663.4 61031.0 61119.7 61231.7 29196.4 34957.7 35184.5 35790.8 64601.4 16333.8 16411.9 16469.4 16535.8 16552.0 11654.8 12585.6 12662.6 12690.3 12728.6 17403.9 19366.7 19410.4 19490.2 19503.6 6630.7 6690.9 7801.4 7816.3 7858.7 829.6 929.6 961.9 1000.9 1044.3 28452.0 29452.0 29503.0 30168.7 30668.8 913.5 1021.2 1037.4 1079.1 1138.2 12802.9 15470.2 15582.9 15698.1 15703.0 635350.0 643378.0 646088.0 647347.0 649262.0 57447.1 57669.8 58101.0 58131.4 58253.4 1326.3 1329.4 1379.1 1381.8 1384.5 25601.2 28227.7 29068.3 29390.0 29488.0 50416.0 50605.2 50737.7 50769.5 50912.5 428519.0 438911.0 439742.0 439768.0 441408.0 26488.5 26562.9 27063.7 27426.5 27993.6 39431.6 47289.0 47665.8 47886.8 48551.5 38632.0 38787.4 38961.0 39984.0 40028.0 29849.8 38475.8 38731.8 39959.2 40064.8 27901.7 36576.6 36631.3 37924.6 40472.2 30121.8 32909.6 33706.0 34992.5 35242.0 20895.6 25471.7 25534.6 25909.4 26350.2 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:33156.81 Median:13817.59 stddev:88484.32 (640.4%) stddev-sans-first:88698.31 15:46:17 INFO - PROCESS | 1650 | Values: 374.9 375.8 377.8 386.7 402.9 18958.9 20495.0 21345.7 21520.1 21610.6 19970.6 20170.0 20249.9 20303.6 20493.9 5186.7 5808.1 6061.0 6098.8 6158.3 3848.8 5844.9 6101.3 6129.0 6144.6 6049.5 6065.9 6165.8 6169.9 6181.6 5871.7 6074.5 6117.7 6125.8 6148.9 5848.1 5861.5 5914.1 5924.1 5941.6 11894.5 12884.9 23657.4 23743.7 24813.3 2066.0 2241.0 2821.9 2894.0 2948.6 9908.6 10180.5 10313.1 10399.0 12006.8 1859.7 1861.6 1867.3 1888.7 1910.5 110838.0 126135.4 126999.0 128332.0 129018.0 9700.9 10845.1 11507.8 11618.8 11766.8 14456.8 14471.0 14475.9 14480.6 14510.0 7164.0 8195.3 8458.0 8477.4 8589.8 8408.9 8412.0 8427.7 8544.5 8563.0 4448.3 4494.8 4507.8 4548.9 4629.3 1578.2 1652.2 1882.9 1936.2 1939.1 807.3 878.2 890.9 969.4 970.5 20463.7 20760.6 20855.0 20980.7 21064.7 864.7 872.1 883.3 894.4 923.2 10843.8 10956.9 11198.6 11315.0 11348.5 504101.0 508630.0 509055.0 534617.0 558490.0 114359.0 123709.0 136412.0 136612.6 141270.3 956.7 973.8 996.2 1004.2 1007.2 19709.7 19880.9 19975.6 20031.0 20067.9 38950.8 54821.5 55078.4 58896.7 59249.8 76571.6 76600.0 82279.2 85445.1 85631.6 5346.9 5776.2 7045.4 7909.8 8112.8 9965.7 13123.9 13494.9 13552.4 13652.2 11051.8 11380.3 11592.0 11635.2 11649.5 10967.8 11200.9 11239.4 11467.1 11609.4 11296.7 11391.7 11604.3 11705.8 11929.9 7252.9 7345.3 7476.6 7478.9 7566.4 10826.2 10977.0 11443.1 11470.4 11507.4 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5737.44 Median:4822.06 stddev:3281.00 (68.0%) stddev-sans-first:3274.69 15:46:17 INFO - PROCESS | 1650 | Values: 1491.8 1532.3 1589.4 1626.6 1822.6 7979.2 8603.0 8768.0 9123.3 9165.8 8719.7 8914.7 8932.0 8933.3 8979.6 2377.6 2402.4 2420.2 2431.9 2436.2 2372.5 2400.7 2418.3 2422.9 2430.8 2222.0 2232.4 2234.8 2240.4 2256.7 2377.5 2384.4 2384.5 2396.8 2402.3 2033.7 2365.4 2384.8 2393.2 2398.5 8933.2 8985.7 9012.3 9069.0 9099.3 5281.4 5408.1 5440.1 5459.7 5460.2 3617.0 3625.6 3670.4 3679.7 3688.0 1090.1 1093.7 1093.7 1097.6 1114.0 11636.3 11729.5 11861.6 11875.5 11905.5 4581.3 4668.8 4852.3 4868.4 4877.7 9889.3 9923.0 9955.3 9956.3 9975.1 5303.3 5362.7 5382.6 5402.6 5410.6 4144.5 4393.2 4729.0 4791.8 4935.7 3208.6 3357.0 3511.4 4023.9 4052.5 1289.0 1293.5 1296.7 1308.6 1310.8 773.1 867.4 896.9 905.8 944.8 4901.8 5615.6 5852.4 5958.9 6160.9 350.6 386.8 393.0 402.3 424.3 3916.8 3967.4 4067.7 4092.9 4114.5 10664.9 10869.4 10988.8 11031.4 11051.3 10876.9 10945.3 10969.5 10980.8 10993.8 7677.6 7980.0 8202.0 8282.9 8327.8 5965.5 5967.9 6086.7 6152.5 6173.8 10137.7 10182.1 10217.6 10339.2 10341.5 9898.1 10286.2 10313.6 10437.6 10446.9 6276.9 6966.1 6993.0 7218.6 7691.2 8218.8 8449.5 8489.7 8495.5 8499.9 7385.7 7488.6 7525.1 7572.6 7623.8 7345.0 7389.2 7416.2 7428.1 7537.5 6911.7 6932.7 6993.5 7015.2 7028.2 4989.5 5044.9 5053.2 5092.1 5124.3 4209.9 5025.7 5171.9 5180.4 5186.6 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4365.55 Median:4754.33 stddev:1312.69 (27.6%) stddev-sans-first:1280.42 15:46:17 INFO - PROCESS | 1650 | Values: 300.0 307.6 315.2 315.4 318.2 4849.0 5320.9 5355.7 5455.6 5498.9 5479.3 5500.2 5518.6 5522.9 5570.3 4222.0 4246.1 4252.4 4252.9 4284.7 3843.9 3957.7 3990.0 4254.8 4276.0 3875.7 4041.8 4130.6 4142.5 4189.6 3523.6 3721.3 3913.3 4219.1 4231.4 3090.4 3683.2 3684.4 3694.9 3696.6 5131.6 5265.2 5295.5 5299.7 5376.9 4144.0 4511.6 4838.3 4859.2 4865.1 5455.5 5773.5 5778.9 5791.9 5812.8 3985.2 4006.2 4021.3 4025.9 4032.2 4920.8 5834.6 6084.4 6100.7 6117.2 3430.8 3925.3 4062.8 4078.4 4095.5 3238.7 3958.2 4113.6 4169.6 4182.3 4358.8 4452.0 4502.9 4599.1 4796.0 4924.2 5470.5 5780.1 5813.9 5894.6 1374.1 1406.3 1416.0 1425.0 1433.3 850.0 869.3 885.4 891.2 896.7 4225.8 4456.6 4558.3 4561.5 4563.9 4214.4 4254.6 4902.3 5020.8 5024.9 4028.2 4460.4 4481.5 4492.9 4502.5 2300.5 2315.4 2430.0 2509.7 2562.9 4287.6 4712.5 4913.6 5034.5 5207.2 4395.4 4697.2 4733.6 5101.4 5112.0 4347.4 4741.7 4767.0 4922.8 5168.0 4702.6 4875.7 4966.5 4981.4 5035.2 4467.5 4806.0 4915.6 4945.5 5008.6 4370.9 4397.7 4683.4 4980.6 5053.9 3110.5 3221.9 3388.2 3390.4 3584.8 4967.7 4982.0 5000.4 5018.0 5560.7 4665.0 5115.9 5384.5 5577.6 5677.8 5191.3 5251.0 5531.3 5616.2 5724.6 5274.9 5542.3 5556.8 5639.5 5683.5 4951.8 5093.5 5701.3 5713.6 5744.3 4979.4 5211.5 5565.2 5654.3 5658.2 15:46:17 INFO - PROCESS | 1650 | 15:46:17 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24374.04 Median:3689.25 stddev:38754.99 (1050.5%) stddev-sans-first:38822.56 15:46:17 INFO - PROCESS | 1650 | Values: 590.7 612.2 677.9 729.5 761.8 15393.5 16116.3 16342.0 16349.8 16360.0 14774.3 14872.8 15078.9 15771.2 15884.5 844.4 858.8 868.6 873.1 873.9 682.9 846.6 850.0 852.4 867.5 908.0 908.9 912.3 913.1 914.7 819.7 865.7 898.2 903.8 904.0 664.9 670.8 670.9 672.5 675.0 15407.8 15453.6 15476.0 15478.5 15529.1 13433.4 14020.4 14068.9 14130.5 14365.4 11632.5 11974.8 12052.8 12076.0 12480.5 3737.9 3767.5 3804.7 3814.4 3964.3 123985.0 124233.0 124791.0 125524.4 126399.7 10571.3 10857.9 10909.3 10960.7 11282.3 22325.0 22462.6 22497.1 22513.0 22570.3 7591.8 7655.6 7736.7 7816.2 7885.2 4140.2 4154.1 4169.1 4186.6 4218.3 3582.9 3688.8 3689.7 3695.1 3705.1 694.3 698.1 711.9 714.7 730.1 537.1 539.9 638.8 658.5 659.2 8084.8 8113.6 8908.0 8967.0 9263.2 513.3 544.5 642.3 646.0 674.2 4154.9 4227.0 4390.2 4431.8 4492.5 101780.0 149374.3 152759.0 154185.0 154805.2 115368.0 115451.0 116234.0 117777.0 119823.4 81754.0 110629.4 111189.4 114491.4 117109.8 8884.0 8928.8 8934.4 8966.0 8998.5 49439.5 52170.0 53387.7 53976.0 66895.3 69194.6 83583.6 88936.6 90190.5 90668.5 11493.6 13707.7 13787.9 13802.0 14207.9 14380.2 14641.2 14751.0 14811.6 15231.8 13643.5 13652.6 13875.2 13875.9 13923.2 12691.9 13388.4 13505.6 13609.3 14130.4 13451.5 13483.3 13563.0 13695.6 14093.2 10734.8 11256.2 11418.9 11545.2 12015.5 10570.7 11400.9 11496.0 11724.8 11742.7 15:46:17 INFO - PROCESS | 1650 | -------- Summary: end -------- 15:46:17 INFO - PROCESS | 1650 | 15:46:18 INFO - TEST-INFO | 1650: exit 0 15:46:18 INFO - TEST-OK | dromaeo_css | took 1113654ms 15:46:18 INFO - TEST-START | kraken 15:46:18 INFO - operating with platform_type : mac_ 15:46:18 INFO - Initialising browser for kraken test... 15:46:18 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpBZDn1K/profile http://localhost:49272/getInfo.html) 15:46:20 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 15:46:20 INFO - PROCESS | 1662 | colorDepth:24 15:46:20 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 15:46:20 INFO - PROCESS | 1662 | __metrics 15:46:21 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 15:46:21 INFO - TEST-INFO | 1662: exit 0 15:46:21 INFO - Browser initialized. 15:46:21 INFO - Running cycle 1/1 for kraken test... 15:46:21 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpBZDn1K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:33 INFO - PROCESS | 1664 | RSS: Main: 243945472 15:46:33 INFO - PROCESS | 1664 | 15:46:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 15:46:37 INFO - PROCESS | 1664 | RSS: Main: 256675840 15:46:37 INFO - PROCESS | 1664 | 15:46:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 15:46:40 INFO - PROCESS | 1664 | RSS: Main: 265433088 15:46:40 INFO - PROCESS | 1664 | 15:46:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 15:46:45 INFO - PROCESS | 1664 | RSS: Main: 263217152 15:46:45 INFO - PROCESS | 1664 | 15:46:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 15:46:47 INFO - PROCESS | 1664 | RSS: Main: 265596928 15:46:47 INFO - PROCESS | 1664 | 15:46:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:46:50 INFO - PROCESS | 1664 | RSS: Main: 265973760 15:46:50 INFO - PROCESS | 1664 | 15:46:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 15:46:53 INFO - PROCESS | 1664 | RSS: Main: 266539008 15:46:53 INFO - PROCESS | 1664 | 15:46:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 15:46:57 INFO - PROCESS | 1664 | RSS: Main: 266625024 15:46:57 INFO - PROCESS | 1664 | 15:47:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 15:47:01 INFO - PROCESS | 1664 | RSS: Main: 266874880 15:47:01 INFO - PROCESS | 1664 | 15:47:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:47:03 INFO - PROCESS | 1664 | RSS: Main: 266297344 15:47:03 INFO - PROCESS | 1664 | 15:47:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:47:06 INFO - PROCESS | 1664 | RSS: Main: 266309632 15:47:06 INFO - PROCESS | 1664 | 15:47:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:47:09 INFO - PROCESS | 1664 | RSS: Main: 266944512 15:47:09 INFO - PROCESS | 1664 | 15:47:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:47:12 INFO - PROCESS | 1664 | RSS: Main: 267403264 15:47:12 INFO - PROCESS | 1664 | 15:47:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:47:16 INFO - PROCESS | 1664 | RSS: Main: 267173888 15:47:16 INFO - PROCESS | 1664 | 15:47:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 15:47:18 INFO - PROCESS | 1664 | RSS: Main: 269082624 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | __start_tp_report 15:47:18 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:47:18 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:47:18 INFO - PROCESS | 1664 | |i|pagename|runs| 15:47:18 INFO - PROCESS | 1664 | |0;ai-astar;109;119;116;112;138;119;118;117;117;135 15:47:18 INFO - PROCESS | 1664 | |1;audio-beat-detection;144;112;136;189;139;131;127;116;214;123 15:47:18 INFO - PROCESS | 1664 | |2;audio-dft;193;224;239;207;270;277;244;271;188;220 15:47:18 INFO - PROCESS | 1664 | |3;audio-fft;69;80;74;144;61;59;59;61;138;71 15:47:18 INFO - PROCESS | 1664 | |4;audio-oscillator;69;71;115;71;70;77;110;67;73;113 15:47:18 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;112;121;86;97;87;89;87;90;86 15:47:18 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;98;98;96;95;94;150;95;94;99 15:47:18 INFO - PROCESS | 1664 | |7;imaging-desaturate;134;123;84;145;141;145;92;93;78;145 15:47:18 INFO - PROCESS | 1664 | |8;json-parse-financial;55;59;58;59;57;57;58;57;56;57 15:47:18 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;69;67;74;67;74;75;75;95;66 15:47:18 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;89;87;80;72;92;107;107;106;77;113 15:47:18 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;159;137;145;160;152;156;156;158;184;144 15:47:18 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;172;172;168;153;159;214;227;197;169;171 15:47:18 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;71;87;71;66;67;68;68;70;67 15:47:18 INFO - PROCESS | 1664 | __end_tp_report 15:47:18 INFO - PROCESS | 1664 | __start_cc_report 15:47:18 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,957 15:47:18 INFO - PROCESS | 1664 | __end_cc_report 15:47:18 INFO - PROCESS | 1664 | __startTimestamp1473115638200__endTimestamp 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:47:18 INFO - PROCESS | 1664 | Number of tests: 14 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:120.00 Median:118.50 stddev:9.27 (7.8%) stddev-sans-first:8.94 15:47:18 INFO - PROCESS | 1664 | Values: 109.0 119.0 116.0 112.0 138.0 119.0 118.0 117.0 117.0 135.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:137.50 stddev:32.85 (23.9%) stddev-sans-first:34.84 15:47:18 INFO - PROCESS | 1664 | Values: 144.0 112.0 136.0 189.0 139.0 131.0 127.0 116.0 214.0 123.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:233.30 Median:241.50 stddev:32.41 (13.4%) stddev-sans-first:30.93 15:47:18 INFO - PROCESS | 1664 | Values: 193.0 224.0 239.0 207.0 270.0 277.0 244.0 271.0 188.0 220.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:81.60 Median:65.00 stddev:32.10 (49.4%) stddev-sans-first:33.73 15:47:18 INFO - PROCESS | 1664 | Values: 69.0 80.0 74.0 144.0 61.0 59.0 59.0 61.0 138.0 71.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:70.50 stddev:20.26 (28.7%) stddev-sans-first:20.79 15:47:18 INFO - PROCESS | 1664 | Values: 69.0 71.0 115.0 71.0 70.0 77.0 110.0 67.0 73.0 113.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:88.00 stddev:12.26 (13.9%) stddev-sans-first:12.85 15:47:18 INFO - PROCESS | 1664 | Values: 89.0 112.0 121.0 86.0 97.0 87.0 89.0 87.0 90.0 86.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:95.50 stddev:17.21 (18.0%) stddev-sans-first:18.05 15:47:18 INFO - PROCESS | 1664 | Values: 94.0 98.0 98.0 96.0 95.0 94.0 150.0 95.0 94.0 99.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:118.00 Median:111.50 stddev:28.00 (25.1%) stddev-sans-first:29.09 15:47:18 INFO - PROCESS | 1664 | Values: 134.0 123.0 84.0 145.0 141.0 145.0 92.0 93.0 78.0 145.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:1.25 (2.2%) stddev-sans-first:1.01 15:47:18 INFO - PROCESS | 1664 | Values: 55.0 59.0 58.0 59.0 57.0 57.0 58.0 57.0 56.0 57.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:74.00 stddev:8.69 (11.7%) stddev-sans-first:8.86 15:47:18 INFO - PROCESS | 1664 | Values: 66.0 69.0 67.0 74.0 67.0 74.0 75.0 75.0 95.0 66.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:78.50 stddev:14.45 (18.4%) stddev-sans-first:15.26 15:47:18 INFO - PROCESS | 1664 | Values: 89.0 87.0 80.0 72.0 92.0 107.0 107.0 106.0 77.0 113.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:157.00 stddev:12.68 (8.1%) stddev-sans-first:13.37 15:47:18 INFO - PROCESS | 1664 | Values: 159.0 137.0 145.0 160.0 152.0 156.0 156.0 158.0 184.0 144.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:172.00 stddev:24.26 (14.1%) stddev-sans-first:25.55 15:47:18 INFO - PROCESS | 1664 | Values: 172.0 172.0 168.0 153.0 159.0 214.0 227.0 197.0 169.0 171.0 15:47:18 INFO - PROCESS | 1664 | 15:47:18 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:69.00 stddev:6.16 (8.9%) stddev-sans-first:6.42 15:47:18 INFO - PROCESS | 1664 | Values: 67.0 71.0 87.0 71.0 66.0 67.0 68.0 68.0 70.0 67.0 15:47:18 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:47:18 INFO - PROCESS | 1664 | 15:47:19 INFO - TEST-INFO | 1664: exit 0 15:47:19 INFO - TEST-OK | kraken | took 60932ms 15:47:19 INFO - SUITE-END | took 1174s 15:47:19 INFO - Completed test suite (00:19:34) 15:47:19 INFO - javascript benchmark 15:47:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7258.2242313207025, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2710.6205131281617, 3446.483027545868, 3459.135216195951, 3463.5843698756144, 3471.1322169457635, 4864.781084851182, 5678.261825962619, 5834.9999999999945, 5882.705864706763, 5924.58527903047, 5774.711264436783, 5842.678652674102, 5877.118432235166, 5925.140854576084, 5938.495227905633, 4169.437125987998, 5247.422783493813, 5267.920981185285, 5331.813386531472, 5346.973265133669, 4611.492735799059, 5367.785288588471, 5382.703951282698, 5429.348478182624, 5473.397926228112, 4935.333729946447, 5062.924056139179, 5220.843374698765, 5388.0, 5390.51485366317, 5252.868678283074, 5262.921056184122, 5266.499682530153, 5276.498732620434, 5365.570754339644, 5251.00230956121, 5263.6578622389425, 5301.840944771625, 5306.204069389584, 5387.1111266640955, 5859.798741258074, 5864.442877926633, 5868.354481007129, 5942.821715348547, 5957.165996760457, 6177.104319873638, 6194.721237544321, 6213.254409470847, 6222.471089957359, 6231.532635052344, 6238.220222472191, 6254.530910181755, 6259.843503912393, 6270.933941230001, 6273.8745225095245, 5751.971240143773, 5766.135079737989, 5855.502282306052, 5894.941050589525, 5981.192539007222, 5596.552275817925, 5974.551908606785, 6291.245050593958, 6327.367263273728, 6328.398802113792, 5576.0, 6210.161628180228, 6210.596311239755, 6217.875642487125, 6236.18929539166, 6142.191841058483, 6184.81445556634, 6185.072239164161, 6187.690615469209, 6220.035894436279, 6176.042713379344, 6179.81460556184, 6216.564840461125, 6225.688715564203, 6239.469645080219, 6155.6614386209185, 6200.3179650238435, 6210.0, 6224.782132625336, 6240.781572644935, 4877.170880950247, 4921.827736029221, 4932.975335123301, 4949.0, 4995.425526064477, 4454.175977444183, 4454.77726113693, 4459.2865141577195, 4475.992901597179, 4506.324051392317, 5502.752376143083, 5866.208061911588, 5879.323877754027, 5891.49922256613, 5896.557758168069, 6124.441964285689, 6153.753849846045, 6158.7228574714245, 6173.876522469615, 6208.751649934043, 5897.233359663216, 5907.232059832194, 5918.467337939606, 5919.875223707481, 5931.377205393372, 5482.369527504388, 5496.31296087989, 5496.780128794883, 5500.092484740053, 5505.421930697299, 6181.258249010058, 6257.874842503215, 6330.746770129143, 6347.460465860454, 6387.0, 6256.124142619946, 6261.123442718114, 6267.561270711007, 6284.528660350401, 6285.779997700059, 5538.944610553843, 6320.051992201023, 6356.555041147075, 6390.169277993831, 6405.967970160119, 5900.64596124234, 5906.084556893688, 6190.0405437157315, 6192.721327540181, 6238.906416403848, 5338.572914166935, 6041.640630858022, 6224.968875155595, 6237.157983672328, 6260.215838486191, 6224.408681175281, 6239.875202496016, 6245.96877015612, 6246.344133865879, 6275.843103922439, 6058.818235452944, 6111.04482360895, 6123.526657168292, 6146.9550176471, 6235.064740288993, 5990.850228744316, 6036.396360363929, 6116.305337585956, 6122.663253521099, 6146.907796383147, 6148.600286304995, 6205.472534834409, 6225.968870155621, 6237.937620623918, 6241.78153764616, 5877.706114694162, 6032.155498230163, 6064.241969753781, 6100.481459076028, 6111.54163437735], "value": 5725.436980363771, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.5368400019982, 504.6643981752135, 506.26085914564743, 515.4150039704933, 521.1713375732585, 38463.230735385274, 41471.79264103679, 41565.0, 41585.58414415855, 41586.376204356886, 42211.788941055325, 42702.0, 42707.23452333934, 42801.35797963033, 42993.14013719732, 9818.754531136707, 9951.353162044445, 9994.250431217652, 9999.700008999742, 10021.146087973711, 8898.332625053115, 9573.707549480836, 9819.6072157114, 9878.209743220561, 9891.499367787676, 9767.023297670246, 9869.161121304698, 9884.456354900514, 9885.75285617858, 9896.50517474127, 9831.606735730562, 9882.9505852471, 9884.851727224097, 9885.308028438012, 9917.355371900803, 9614.326997110205, 9716.659916902874, 9718.076782705702, 9718.465484398355, 9750.756231094208, 40275.194496109914, 40464.0, 40727.0, 40749.0, 40839.0, 3363.5795525559306, 3399.490076488522, 3399.711024562915, 3403.268297316089, 3424.400729872262, 30805.845970770228, 30916.845415773, 30939.22651933697, 30944.69055309463, 31463.213419664462, 3197.2965947491516, 3234.9191270218435, 3254.332861763333, 3283.8029718216985, 3348.995301409567, 421571.8921405373, 423111.0, 425597.87201064103, 425916.0, 427788.0, 60405.0, 60663.39336606577, 61030.99999999911, 61119.6944015286, 61231.693841530505, 29196.416071678446, 34957.65042349544, 35184.47223291652, 35790.82104589512, 64601.353986460475, 16333.754993675104, 16411.851170418195, 16469.423570174986, 16535.83464165343, 16552.0, 11654.825177622342, 12585.559505417266, 12662.620121396372, 12690.302033388067, 12728.61814145577, 17403.868748531306, 19366.70949935752, 19410.387928887947, 19490.189251697127, 19503.609927801648, 6630.668466576652, 6690.933090669128, 7801.375889928906, 7816.257455541714, 7858.73067412641, 829.5561874397168, 929.6420877961898, 961.8653388525612, 1000.9399436033862, 1044.3472829481575, 28452.0, 29452.0, 29503.0, 30168.698313017027, 30668.846655766578, 913.4838816069033, 1021.2238698589126, 1037.3672060043368, 1079.0827796373178, 1138.2146319039837, 12802.871971280169, 15470.226488675522, 15582.9220853895, 15698.134875064196, 15703.0, 635350.0, 643378.0, 646088.0, 647347.0, 649262.0, 57447.138292925636, 57669.8466030677, 58101.0, 58131.4186858126, 58253.4174658248, 1326.290434617475, 1329.4217015598247, 1379.1173648864735, 1381.8065470834092, 1384.4738999180247, 25601.23196304114, 28227.71772282333, 29068.27329316784, 29389.971351002612, 29488.0, 50415.991680165454, 50605.24092138695, 50737.74631126821, 50769.49230507648, 50912.49087509078, 428519.0, 438911.0, 439742.0, 439768.0, 441408.0, 26488.470230595667, 26562.86718566395, 27063.72936270691, 27426.451470970867, 27993.580096298978, 39431.60568394279, 47289.0, 47665.76167119142, 47886.76056619694, 48551.5144848547, 38632.034199145244, 38787.35969883191, 38961.025974350865, 39984.0, 40028.0, 29849.806007759445, 38475.807620961714, 38731.8380448587, 39959.2008159841, 40064.799676001436, 27901.720982790725, 36576.634233658384, 36631.26737465289, 37924.62075379321, 40472.19055618812, 30121.849390752905, 32909.633465458304, 33705.98882033543, 34992.47511287282, 35242.0, 20895.582088358453, 25471.745282546937, 25534.616980744933, 25909.35226619274, 26350.209493715218], "value": 19462.916689811416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.9156439801047, 375.76702444484414, 377.7884384744543, 386.72542494828673, 402.9012891841499, 18958.90520547397, 20495.0, 21345.679814802792, 21520.139194432242, 21610.56778864424, 19970.600587988232, 20170.0, 20249.898750506225, 20303.59392812143, 20493.87283699396, 5186.7147306898105, 5808.099744539603, 6061.030235162375, 6098.756049758026, 6158.291796443422, 3848.7690738555634, 5844.941550584483, 6101.267847858241, 6128.96935515324, 6144.6006009609455, 6049.546284028694, 6065.909011364833, 6165.784197553086, 6169.907451388211, 6181.598196117238, 5871.677057761822, 6074.544409169308, 6117.66352850593, 6125.754969801203, 6148.907766383485, 5848.122781582754, 5861.4724674779045, 5914.142449344864, 5924.14099955504, 5941.584089113764, 11894.52421903123, 12884.904783093449, 23657.408564785845, 23743.694096824842, 24813.25560233196, 2065.9690104648444, 2240.977590224093, 2821.915342539727, 2894.0160345482427, 2948.557716342544, 9908.603655853758, 10180.490975451197, 10313.123384512326, 10399.0, 12006.819897701542, 1859.7024476083761, 1861.5718384771578, 1867.2624313396268, 1888.7166924961227, 1910.5319196796734, 110838.01228944717, 126135.36932315372, 126999.0, 128332.0, 129018.0, 9700.90299097, 10845.078168355778, 11507.76984460306, 11618.825717614243, 11766.764664706658, 14456.783148252785, 14471.0, 14475.927620362041, 14480.637984050272, 14509.984301099033, 7164.0, 8195.34437245019, 8457.957710211409, 8477.364197685198, 8589.828203435896, 8408.873866892001, 8412.0, 8427.705030323908, 8544.530050847263, 8563.015253245956, 4448.310511870665, 4494.775261236925, 4507.774611269489, 4548.863534093983, 4629.259318509023, 1578.179346739712, 1652.1573997261482, 1882.8870267783977, 1936.1771247219874, 1939.0789375047023, 807.2734538915023, 878.2271600991214, 890.8663700444985, 969.3505351414428, 970.5341436110706, 20463.693044604344, 20760.58478830445, 20855.0, 20980.685289720666, 21064.684029739565, 864.6843901975734, 872.1104473437185, 883.337496877342, 894.4365050018447, 923.2337160157188, 10843.83734243987, 10956.945215273872, 11198.552057917756, 11315.0, 11348.546058157746, 504101.0, 508630.0, 509055.0, 534617.0, 558490.0, 114359.0, 123709.0, 136412.0, 136612.58468538168, 141270.29364853314, 956.7225504603726, 973.8198433289864, 996.1980605612551, 1004.231762701534, 1007.2244371833793, 19709.70435443441, 19880.90059549693, 19975.626188966417, 20030.99845007773, 20067.89626570494, 38950.80524597359, 54821.45178548323, 55078.44921550893, 58896.705516473856, 59249.81500369882, 76571.61714191617, 76600.0, 82279.17720822715, 85445.14554854372, 85631.57184214039, 5346.946530534645, 5776.191333213437, 7045.436365090678, 7909.841803164019, 8112.83774324522, 9965.651202207888, 13123.934380328037, 13494.852937500029, 13552.390142443812, 13652.24912629815, 11051.778964420828, 11380.260283081574, 11592.0, 11635.243709158878, 11649.475773590379, 10967.780644386909, 11200.943995279971, 11239.434931164678, 11467.139964502863, 11609.3614851184, 11296.717582060513, 11391.715207119889, 11604.30374177552, 11705.824412633647, 11929.880701192877, 7252.891206631799, 7345.3021962913435, 7476.5887876167335, 7478.887816682863, 7566.394688425023, 10826.188035897496, 10977.0, 11443.084553235554, 11470.4264786762, 11507.424628768695], "value": 9496.47614038239, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.753860612999, 1532.3104602928681, 1589.3801417447198, 1626.5689592258054, 1822.5534743987707, 7979.162187970267, 8603.0, 8767.95616021919, 9123.270138388929, 9165.770855728602, 8719.694810681627, 8914.687985920495, 8932.0, 8933.285337173027, 8979.595918183697, 2377.63146712259, 2402.411409204747, 2420.249722586004, 2431.8662473563945, 2436.1717016214484, 2372.50177462733, 2400.6879105716234, 2418.298693378918, 2422.8667423291713, 2430.756924307564, 2221.9666704999436, 2232.363776323748, 2234.7765223477604, 2240.3838944290287, 2256.650219216016, 2377.5244951009868, 2384.3800611840875, 2384.4754154086245, 2396.760323967598, 2402.2913240594066, 2033.6644453665128, 2365.432296248905, 2384.8211384146257, 2393.234165067179, 2398.5202959408016, 8933.240674542672, 8985.730428087169, 9012.324075694349, 9068.954655226682, 9099.317551183622, 5281.445448227958, 5408.080626293539, 5440.129579267298, 5459.672419654794, 5460.180972854104, 3616.963830361689, 3625.6193099724414, 3670.376036073874, 3679.6872265857432, 3688.0226739913946, 1090.1442367741286, 1093.7156339351745, 1093.7429704019628, 1097.5884043483693, 1114.0419239454063, 11636.301821890798, 11729.472173752201, 11861.5848445304, 11875.52497900083, 11905.523779048828, 4581.335706322631, 4668.789904454307, 4852.320675105488, 4868.41579010521, 4877.707337559757, 9889.258305626963, 9922.950385248028, 9955.25335599833, 9956.303058785816, 9975.102240798364, 5303.28405665238, 5362.678239305654, 5382.569394448435, 5402.621816472809, 5410.621256512007, 4144.544100148981, 4393.1872603568445, 4728.976355118271, 4791.808327666924, 4935.654504184673, 3208.631007434175, 3356.983215083958, 3511.438169892856, 4023.939640905389, 4052.452918855917, 1288.954886578984, 1293.489071816627, 1296.6628676544167, 1308.5681725030718, 1310.8492523359935, 773.1456740301998, 867.3711559119588, 896.9057749545544, 905.805251870851, 944.8393773058459, 4901.828436004794, 5615.578831587647, 5852.385499522574, 5958.940410595838, 6160.907586386208, 350.60381768601826, 386.8297948902474, 392.958739332366, 402.3060221118547, 424.30414120841846, 3916.823742931575, 3967.385055316373, 4067.7355971861734, 4092.877213683594, 4114.465119534441, 10664.946675266574, 10869.40218287986, 10988.835167472495, 11031.393273369882, 11051.336919784839, 10876.891231087746, 10945.343279403261, 10969.506372213269, 10980.780384392427, 10993.780124397626, 7677.61611919413, 7979.965108897194, 8201.974753155855, 8282.875756863532, 8327.833443331221, 5965.522758179249, 5967.910481342697, 6086.695665216633, 6152.538559607958, 6173.81478555644, 10137.695869123643, 10182.134518566005, 10217.59129634836, 10339.22455815832, 10341.534630941474, 9898.059684329977, 10286.177105831366, 10313.587456501657, 10437.634682786065, 10446.947765261126, 6276.905846412216, 6966.12923384577, 6993.020977063317, 7218.566885986857, 7691.230876912264, 8218.753437396888, 8449.493030418194, 8489.74530764078, 8495.490270583785, 8499.91500085016, 7385.7045718170675, 7488.625568721651, 7525.059367579052, 7572.621368931642, 7623.847523049619, 7345.0, 7389.224131466119, 7416.221296763762, 7428.1086269647985, 7537.472376933562, 6911.723531058701, 6932.688029038604, 6993.545419547713, 7015.228324884157, 7028.226895041644, 4989.501049894981, 5044.924326135184, 5053.191489361684, 5092.108880945893, 5124.333836601218, 4209.9368509473, 5025.698458092526, 5171.92242116361, 5180.352455943007, 5186.559142472781], "value": 4499.4728369079885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.9875420455961, 307.58168890309184, 315.2214031342586, 315.43064768093586, 318.1727508477958, 4848.951510484899, 5320.866978325535, 5355.678659280446, 5455.636090977256, 5498.945010549888, 5479.315085614298, 5500.1749737539385, 5518.613697041209, 5522.944770552303, 5570.3315602127805, 4221.957780422187, 4246.129543443602, 4252.425922500469, 4252.893677658068, 4284.742915425085, 3843.8654647087355, 3957.663598594123, 3989.960100398988, 4254.808533615979, 4276.0, 3875.670568001723, 4041.7574945503216, 4130.586941305875, 4142.544320124784, 4189.622933935945, 3523.577170739502, 3721.348763966295, 3913.25648126855, 4219.092895027568, 4231.4076029355765, 3090.443720130376, 3683.208110256281, 3684.44733290006, 3694.9445758313645, 3696.5748938872107, 5131.61512886535, 5265.236540701615, 5295.4969277918535, 5299.655522391032, 5376.946230537684, 4143.958560414418, 4511.6165125964335, 4838.322634831126, 4859.246816743411, 4865.051314993578, 5455.4760993693535, 5773.509251713609, 5778.884422311572, 5791.885961624019, 5812.796552120655, 3985.2228815381013, 4006.1787333596544, 4021.2560676274984, 4025.879223623296, 4032.1734044520804, 4920.8031678732805, 5834.620749651302, 6084.421979911901, 6100.72546735398, 6117.235345581802, 3430.811305378178, 3925.3326934421216, 4062.7968601569805, 4078.3882417636996, 4095.5494895561465, 3238.7085162335034, 3958.1687845552756, 4113.609207125318, 4169.60388763067, 4182.2681030820095, 4358.8038538265855, 4451.955480445219, 4502.851772797915, 4599.103174880866, 4796.026463592595, 4924.212126059885, 5470.480304371078, 5780.075187969905, 5813.941860581424, 5894.646321220741, 1374.1411617738913, 1406.3249640172774, 1415.9929200353931, 1424.9572512824632, 1433.3406632948752, 849.9957500212583, 869.2654706772767, 885.3581153663671, 891.157855826251, 896.6592694775943, 4225.830966761295, 4456.5766252205985, 4558.293464513005, 4561.498235194126, 4563.863084107482, 4214.431051808028, 4254.617084462413, 4902.28916807061, 5020.849374518771, 5024.924626130611, 4028.154087641628, 4460.394258067094, 4481.5070342262325, 4492.887677808081, 4502.527234640315, 2300.482391461908, 2315.397996520917, 2429.9514009719705, 2509.736477669818, 2562.93592660185, 4287.614114729627, 4712.489195549338, 4913.624185228142, 5034.533078791439, 5207.192885102815, 4395.362672412482, 4697.228635105304, 4733.573978341897, 5101.438841727407, 5111.980767596779, 4347.434833471755, 4741.6917900335675, 4766.976165119152, 4922.827701030446, 5167.974160129175, 4702.623790096853, 4875.68308059975, 4966.478519755374, 4981.4022317320705, 5035.212499562749, 4467.463904331501, 4805.951940480551, 4915.557599816034, 4945.530174633404, 5008.599312055099, 4370.890727731705, 4397.670174736843, 4683.414573178353, 4980.551750342486, 5053.924191137209, 3110.548970399325, 3221.935561288808, 3388.186835159495, 3390.3727810355163, 3584.7849129052343, 4967.696481839536, 4981.975090124672, 5000.374953130859, 5018.021485810232, 5560.666360018412, 4664.953350466452, 5115.897682046412, 5384.461553844584, 5577.581681373832, 5677.772889084556, 5191.299174611379, 5250.97374513125, 5531.2532808072, 5616.213730077874, 5724.627899186706, 5274.947250527445, 5542.307211598551, 5556.833295001156, 5639.503507825175, 5683.516901063456, 4951.752412379438, 5093.490650934877, 5701.287339082614, 5713.571607098225, 5744.310682717934, 4979.377577802775, 5211.5049070338255, 5565.248691426606, 5654.2649455572155, 5658.151277308272], "value": 3955.619551806201, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.6845166945574, 612.2163630552891, 677.945794289879, 729.485712572636, 761.7752762934933, 15393.538193854174, 16116.27476763547, 16342.019478831278, 16349.754753678704, 16360.0, 14774.335154918028, 14872.776908346383, 15078.86749266059, 15771.21143942804, 15884.52346429606, 844.3793811548492, 858.7509622209552, 868.6438560190323, 873.1094283830489, 873.9038705742363, 682.8600136971934, 846.6444093480723, 849.9915000850006, 852.4033176776281, 867.5315329721942, 907.9682211122612, 908.9181973622373, 912.3203213605873, 913.1234015345276, 914.6570036236411, 819.7499762572443, 865.709987154304, 898.2275243290732, 903.7740564858785, 903.9728808135767, 664.8537321789198, 670.8188789026984, 670.8859493886051, 672.5393105722595, 675.0212192321154, 15407.845921540753, 15453.613659658598, 15476.0, 15478.5356439307, 15529.068255904682, 13433.395497202551, 14020.369083391271, 14068.929655351658, 14130.505432309923, 14365.353559089866, 11632.53469861213, 11974.760504789943, 12052.819207711891, 12076.0, 12480.50077996879, 3737.8878633641034, 3767.4537192107264, 3804.6956243500454, 3814.351560234739, 3964.306246406867, 123985.0, 124233.0, 124791.0, 125524.37237813843, 126399.73600263972, 10571.260011799253, 10857.945710271399, 10909.290896091887, 10960.725981850359, 11282.266652667551, 22325.0, 22462.550748985257, 22497.100115995505, 22513.0, 22570.322890313317, 7591.848163036709, 7655.617219139132, 7736.690532378754, 7816.16538726451, 7885.172056934054, 4140.192662430797, 4154.1068670236045, 4169.061961058798, 4186.591003789775, 4218.261804184255, 3582.9283414331567, 3688.8340024698955, 3689.6679298863232, 3695.131644063643, 3705.0552109212517, 694.2814187316104, 698.1308271202386, 711.8825393809964, 714.7033980897869, 730.0691618186748, 537.0949949335383, 539.9055165346049, 638.8243233110932, 658.5159907468008, 659.2385794407467, 8084.7574572762915, 8113.584190038973, 8907.97558280806, 8967.0, 9263.212626926786, 513.3172880069499, 544.4881811097556, 642.3218746282881, 646.0, 674.2010717300016, 4154.854580089743, 4226.964393723558, 4390.165868484977, 4431.8448854289945, 4492.505824359318, 101780.0, 149374.25312873584, 152759.0, 154185.0, 154805.2259738694, 115368.0, 115451.0, 116234.0, 117777.0, 119823.40088299676, 81754.0, 110629.44685276684, 111189.44405277922, 114491.42754286341, 117109.82890171159, 8884.0, 8928.821423571362, 8934.4192627479, 8965.955170224108, 8998.460092394478, 49439.50560494349, 52170.0, 53387.733061334446, 53976.0, 66895.33104669086, 69194.61610767656, 83583.5820820892, 88936.555317223, 90190.54904725435, 90668.54665726628, 11493.597724079616, 13707.657308566966, 13787.931060344634, 13802.0, 14207.857921420653, 14380.209088499814, 14641.194734289717, 14751.0, 14811.629709256924, 15231.847681523486, 13643.454261829813, 13652.590422287347, 13875.236861972284, 13875.86124138786, 13923.164610123426, 12691.93654031724, 13388.389264093408, 13505.594832155051, 13609.319534023458, 14130.364133613806, 13451.46194152223, 13483.325833708079, 13563.0, 13695.589132326046, 14093.224872632103, 10734.843906353546, 11256.155788315746, 11418.885811141783, 11545.191836571359, 12015.519379225083, 10570.735731606772, 11400.94299528497, 11496.0, 11724.824127638263, 11742.706432338919], "value": 7762.112827463211, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 116.0, 112.0, 138.0, 119.0, 118.0, 117.0, 117.0, 135.0], "value": 120.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 136.0, 189.0, 139.0, 131.0, 127.0, 116.0, 214.0, 123.0], "value": 143.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 224.0, 239.0, 207.0, 270.0, 277.0, 244.0, 271.0, 188.0, 220.0], "value": 233.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 74.0, 144.0, 61.0, 59.0, 59.0, 61.0, 138.0, 71.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 115.0, 71.0, 70.0, 77.0, 110.0, 67.0, 73.0, 113.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 112.0, 121.0, 86.0, 97.0, 87.0, 89.0, 87.0, 90.0, 86.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 98.0, 96.0, 95.0, 94.0, 150.0, 95.0, 94.0, 99.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 123.0, 84.0, 145.0, 141.0, 145.0, 92.0, 93.0, 78.0, 145.0], "value": 118.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 58.0, 59.0, 57.0, 57.0, 58.0, 57.0, 56.0, 57.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 74.0, 67.0, 74.0, 75.0, 75.0, 95.0, 66.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 80.0, 72.0, 92.0, 107.0, 107.0, 106.0, 77.0, 113.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 137.0, 145.0, 160.0, 152.0, 156.0, 156.0, 158.0, 184.0, 144.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 168.0, 153.0, 159.0, 214.0, 227.0, 197.0, 169.0, 171.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 87.0, 71.0, 66.0, 67.0, 68.0, 68.0, 70.0, 67.0], "value": 70.2, "unit": "score"}]}]} 15:47:19 INFO - Return code: 0 15:47:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:47:19 ERROR - # TBPL SUCCESS # 15:47:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:47:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:47:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:47:19 INFO - 2016-09-05 15:47:19.542 screenresolution[1669:10275] starting screenresolution argv=screenresolution get 15:47:19 INFO - 2016-09-05 15:47:19.545 screenresolution[1669:10275] Display 0: 1600x1200x32@60 15:47:19 INFO - 2016-09-05 15:47:19.551 screenresolution[1670:10278] starting screenresolution argv=screenresolution list 15:47:19 INFO - Available Modes on Display 0 15:47:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:47:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:47:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:47:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:47:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:47:19 INFO - Intel Iris: 15:47:19 INFO - Chipset Model: Intel Iris 15:47:19 INFO - Type: GPU 15:47:19 INFO - Bus: Built-In 15:47:19 INFO - VRAM (Dynamic, Max): 1536 MB 15:47:19 INFO - Vendor: Intel (0x8086) 15:47:19 INFO - Device ID: 0x0a2e 15:47:19 INFO - Revision ID: 0x0009 15:47:19 INFO - Displays: 15:47:19 INFO - Display: 15:47:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:47:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:47:19 INFO - Display Serial Number: 1600x1200 60 15:47:19 INFO - Main Display: Yes 15:47:19 INFO - Mirror: Off 15:47:19 INFO - Online: Yes 15:47:19 INFO - Rotation: Supported 15:47:19 INFO - Return code: 0 15:47:19 INFO - Running post-action listener: _resource_record_post_action 15:47:19 INFO - [mozharness: 2016-09-05 22:47:19.793816Z] Finished run-tests step (success) 15:47:19 INFO - Running post-run listener: _resource_record_post_run 15:47:19 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 37282816; Write bytes: 705764864; Read time: 243; Write time: 2009 15:47:19 INFO - TinderboxPrint: CPU usage
54.6% 15:47:19 INFO - TinderboxPrint: I/O read bytes / time
37,282,816 / 243 15:47:19 INFO - TinderboxPrint: I/O write bytes / time
705,764,864 / 2,009 15:47:19 INFO - TinderboxPrint: CPU idle
2,198.6 (45.4%) 15:47:19 INFO - TinderboxPrint: CPU system
973.4 (20.1%) 15:47:19 INFO - TinderboxPrint: CPU user
1,665.7 (34.4%) 15:47:19 INFO - TinderboxPrint: Swap in / out
442,892,288 / 0 15:47:19 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396263936; Write bytes: 390875136; Read time: 29496; Write time: 809 15:47:19 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 35341312; Write bytes: 296117760; Read time: 224; Write time: 1154 15:47:20 INFO - Running post-run listener: _upload_blobber_files 15:47:20 INFO - Blob upload gear active. 15:47:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:47:20 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 15:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:47:20 INFO - (blobuploader) - INFO - Open directory for files ... 15:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:47:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:21 INFO - (blobuploader) - INFO - Done attempting. 15:47:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:47:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:47:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:22 INFO - (blobuploader) - INFO - Done attempting. 15:47:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:47:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:47:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:24 INFO - (blobuploader) - INFO - Done attempting. 15:47:24 INFO - (blobuploader) - INFO - Iteration through files over. 15:47:24 INFO - Return code: 0 15:47:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:47:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:47:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade356a0548fede14f97b2d88cc8eafcbdb7ad91f3ea6a0df06d46a7495ab9df43c09a92465aa46baaf530fb06b74441cb811aff86b8c6e3afd0eb1ab92a11ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a084efdd3b8500f029ba3b212f86f581a320983d395a3a16b79d002df7f05c6b6a1109edde4b9dd8d2b837f4cfcad586516a5af630767d180ec811cb47adf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da121465cc6ec3f2ec73f2ead7c745a872ab6fbae44700738afd07d781b5c586611416c823130a1541b0e8996db4266e5f985b608ef0de3ea305aa7189bb98c"} 15:47:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:47:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:47:24 INFO - Contents: 15:47:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade356a0548fede14f97b2d88cc8eafcbdb7ad91f3ea6a0df06d46a7495ab9df43c09a92465aa46baaf530fb06b74441cb811aff86b8c6e3afd0eb1ab92a11ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a084efdd3b8500f029ba3b212f86f581a320983d395a3a16b79d002df7f05c6b6a1109edde4b9dd8d2b837f4cfcad586516a5af630767d180ec811cb47adf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da121465cc6ec3f2ec73f2ead7c745a872ab6fbae44700738afd07d781b5c586611416c823130a1541b0e8996db4266e5f985b608ef0de3ea305aa7189bb98c"} 15:47:24 INFO - Running post-run listener: copy_logs_to_upload_dir 15:47:24 INFO - Copying logs to upload dir... 15:47:24 INFO - mkdir: /builds/slave/test/build/upload/logs 15:47:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.578377 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-05 15:47:24.872392) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 15:47:24.875756) ========= 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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade356a0548fede14f97b2d88cc8eafcbdb7ad91f3ea6a0df06d46a7495ab9df43c09a92465aa46baaf530fb06b74441cb811aff86b8c6e3afd0eb1ab92a11ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a084efdd3b8500f029ba3b212f86f581a320983d395a3a16b79d002df7f05c6b6a1109edde4b9dd8d2b837f4cfcad586516a5af630767d180ec811cb47adf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da121465cc6ec3f2ec73f2ead7c745a872ab6fbae44700738afd07d781b5c586611416c823130a1541b0e8996db4266e5f985b608ef0de3ea305aa7189bb98c"} build_url:https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010924 build_url: 'https://queue.taskcluster.net/v1/task/FTKLTS84QNa6wx1Ax6DMSQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade356a0548fede14f97b2d88cc8eafcbdb7ad91f3ea6a0df06d46a7495ab9df43c09a92465aa46baaf530fb06b74441cb811aff86b8c6e3afd0eb1ab92a11ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a084efdd3b8500f029ba3b212f86f581a320983d395a3a16b79d002df7f05c6b6a1109edde4b9dd8d2b837f4cfcad586516a5af630767d180ec811cb47adf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da121465cc6ec3f2ec73f2ead7c745a872ab6fbae44700738afd07d781b5c586611416c823130a1541b0e8996db4266e5f985b608ef0de3ea305aa7189bb98c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 15:47:24.909720) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:47:24.910051) ========= 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.ROX8UjlUUH/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.c9WLx8RVTr/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.005121 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:47:24.958097) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 15:47:24.958409) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 15:47:24.958758) ========= ========= Total master_lag: 0.26 =========