builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0092 starttime: 1473174704.93 results: success (0) buildid: 20160906064355 builduid: b5e44c655601444cb7eaa0f958fd5b18 revision: 0788fe67e9ae4254553d3f308cc2c3ee061023f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:44.934012) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:44.934518) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:44.934837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:45.258573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:45.258889) ========= 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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-06 08:11:45-- 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% 14.1M=0.001s 2016-09-06 08:11:45 (14.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174301 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:45.494101) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:45.494448) ========= 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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.028278 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:45.551925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 08:11:45.552288) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0788fe67e9ae4254553d3f308cc2c3ee061023f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 0788fe67e9ae4254553d3f308cc2c3ee061023f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-06 08:11:45,632 truncating revision to first 12 chars 2016-09-06 08:11:45,632 Setting DEBUG logging. 2016-09-06 08:11:45,632 attempt 1/10 2016-09-06 08:11:45,632 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0788fe67e9ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 08:11:46,365 unpacking tar archive at: autoland-0788fe67e9ae/testing/mozharness/ program finished with exit code 0 elapsedTime=0.978421 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 08:11:46.556519) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:46.556960) ========= script_repo_revision: 0788fe67e9ae4254553d3f308cc2c3ee061023f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:46.557456) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:46.557849) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 08:11:46.580943) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-06 08:11:46.581279) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:11:46 INFO - MultiFileLogger online at 20160906 08:11:46 in /builds/slave/test 08:11:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 08:11:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:46 INFO - {'append_to_log': False, 08:11:46 INFO - 'base_work_dir': '/builds/slave/test', 08:11:46 INFO - 'blob_upload_branch': 'Autoland', 08:11:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:46 INFO - 'branch': 'Autoland', 08:11:46 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:46 INFO - 'config_files': ('talos/mac_config.py',), 08:11:46 INFO - 'default_actions': ('clobber', 08:11:46 INFO - 'read-buildbot-config', 08:11:46 INFO - 'download-and-extract', 08:11:46 INFO - 'populate-webroot', 08:11:46 INFO - 'create-virtualenv', 08:11:46 INFO - 'install', 08:11:46 INFO - 'run-tests'), 08:11:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:46 INFO - 'download_minidump_stackwalk': True, 08:11:46 INFO - 'download_symbols': 'ondemand', 08:11:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:46 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:46 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:46 INFO - 'installer_path': 'installer.exe', 08:11:46 INFO - 'log_level': 'info', 08:11:46 INFO - 'log_name': 'talos', 08:11:46 INFO - 'log_to_console': True, 08:11:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:11:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:11:46 INFO - 'opt_config_files': (), 08:11:46 INFO - 'pip_index': False, 08:11:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:11:46 INFO - 'cmd': ('bash', 08:11:46 INFO - '-c', 08:11:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:11:46 INFO - 'enabled': True, 08:11:46 INFO - 'halt_on_failure': False, 08:11:46 INFO - 'name': 'check_screen_resolution'},), 08:11:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:11:46 INFO - 'cmd': ('bash', 08:11:46 INFO - '-c', 08:11:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:11:46 INFO - 'enabled': True, 08:11:46 INFO - 'halt_on_failure': False, 08:11:46 INFO - 'name': 'check_screen_resolution'},), 08:11:46 INFO - 'run_cmd_checks_enabled': True, 08:11:46 INFO - 'sps_profile': False, 08:11:46 INFO - 'sps_profile_interval': 0, 08:11:46 INFO - 'suite': 'dromaeojs-e10s', 08:11:46 INFO - 'system_bits': '32', 08:11:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:46 INFO - 'title': 't-yosemite-r7-0092', 08:11:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:46 INFO - 'use_talos_json': True, 08:11:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:46 INFO - 'work_dir': 'build'} 08:11:46 INFO - [mozharness: 2016-09-06 15:11:46.783936Z] Running clobber step. 08:11:46 INFO - Running pre-action listener: _resource_record_pre_action 08:11:46 INFO - Running main action method: clobber 08:11:46 INFO - rmtree: /builds/slave/test/build 08:11:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:11:48 INFO - Running post-action listener: _resource_record_post_action 08:11:48 INFO - [mozharness: 2016-09-06 15:11:48.912683Z] Finished clobber step (success) 08:11:48 INFO - [mozharness: 2016-09-06 15:11:48.912812Z] Running read-buildbot-config step. 08:11:48 INFO - Running pre-action listener: _resource_record_pre_action 08:11:48 INFO - Running main action method: read_buildbot_config 08:11:48 INFO - Using buildbot properties: 08:11:48 INFO - { 08:11:48 INFO - "project": "", 08:11:48 INFO - "product": "firefox", 08:11:48 INFO - "script_repo_revision": "production", 08:11:48 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 08:11:48 INFO - "repository": "", 08:11:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 08:11:48 INFO - "buildid": "20160906064355", 08:11:48 INFO - "pgo_build": "False", 08:11:48 INFO - "basedir": "/builds/slave/test", 08:11:48 INFO - "buildnumber": 162, 08:11:48 INFO - "slavename": "t-yosemite-r7-0092", 08:11:48 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:11:48 INFO - "platform": "yosemite_r7", 08:11:48 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 08:11:48 INFO - "branch": "autoland", 08:11:48 INFO - "repo_path": "integration/autoland", 08:11:48 INFO - "revision": "0788fe67e9ae4254553d3f308cc2c3ee061023f1", 08:11:48 INFO - "stage_platform": "macosx64", 08:11:48 INFO - "builduid": "b5e44c655601444cb7eaa0f958fd5b18", 08:11:48 INFO - "slavebuilddir": "test" 08:11:48 INFO - } 08:11:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:11:48 INFO - Running post-action listener: _resource_record_post_action 08:11:48 INFO - [mozharness: 2016-09-06 15:11:48.916102Z] Finished read-buildbot-config step (success) 08:11:48 INFO - [mozharness: 2016-09-06 15:11:48.916228Z] Running download-and-extract step. 08:11:48 INFO - Running pre-action listener: _resource_record_pre_action 08:11:48 INFO - Running main action method: download_and_extract 08:11:48 INFO - mkdir: /builds/slave/test/build/tests 08:11:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:48 INFO - trying https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:11:48 INFO - Downloading https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/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 08:11:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/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 08:11:49 INFO - Downloaded 1199 bytes. 08:11:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:11:49 INFO - Using the following test package requirements: 08:11:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:11:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:11:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:11:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'jsshell-mac.zip'], 08:11:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:11:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:11:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:11:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:11:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:11:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:11:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:11:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:11:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:11:50 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 08:11:57 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 08:11:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:11:57 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 08:12:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:12:05 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 08:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:06 INFO - trying https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:12:06 INFO - Downloading https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/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 08:12:09 INFO - Downloaded 111521936 bytes. 08:12:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:12:09 INFO - mkdir: /builds/slave/test/properties 08:12:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:12:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:12:09 INFO - Contents: 08:12:09 INFO - build_url:https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:12:10 INFO - Running post-action listener: _resource_record_post_action 08:12:10 INFO - Running post-action listener: set_extra_try_arguments 08:12:10 INFO - [mozharness: 2016-09-06 15:12:10.962120Z] Finished download-and-extract step (success) 08:12:10 INFO - [mozharness: 2016-09-06 15:12:10.962261Z] Running populate-webroot step. 08:12:10 INFO - Running pre-action listener: _resource_record_pre_action 08:12:10 INFO - Running main action method: populate_webroot 08:12:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:12:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:12:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:12:10 INFO - '--tppagecycles', 08:12:10 INFO - '7'], 08:12:10 INFO - 'tests': ['tsvgm']}}, 08:12:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:12:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:12:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:12:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:12:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['damp', 'tps']}, 08:12:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'tests': ['damp', 'tps']}, 08:12:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['dromaeo_dom']}, 08:12:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:12:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['basic_compositor_video']}, 08:12:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:12:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['a11yr', 08:12:10 INFO - 'ts_paint', 08:12:10 INFO - 'tpaint', 08:12:10 INFO - 'sessionrestore', 08:12:10 INFO - 'sessionrestore_no_auto_restore', 08:12:10 INFO - 'tabpaint']}, 08:12:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:12:10 INFO - 'ts_paint', 08:12:10 INFO - 'tpaint', 08:12:10 INFO - 'sessionrestore', 08:12:10 INFO - 'sessionrestore_no_auto_restore', 08:12:10 INFO - 'tabpaint']}, 08:12:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:12:10 INFO - 'ts_paint', 08:12:10 INFO - 'tpaint', 08:12:10 INFO - 'sessionrestore', 08:12:10 INFO - 'sessionrestore_no_auto_restore', 08:12:10 INFO - 'tabpaint']}, 08:12:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:12:10 INFO - 'ts_paint', 08:12:10 INFO - 'tpaint', 08:12:10 INFO - 'sessionrestore', 08:12:10 INFO - 'sessionrestore_no_auto_restore', 08:12:10 INFO - 'tabpaint']}, 08:12:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['a11yr', 08:12:10 INFO - 'ts_paint', 08:12:10 INFO - 'tpaint', 08:12:10 INFO - 'sessionrestore', 08:12:10 INFO - 'sessionrestore_no_auto_restore', 08:12:10 INFO - 'tabpaint']}, 08:12:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['a11yr', 08:12:10 INFO - 'ts_paint', 08:12:10 INFO - 'tpaint', 08:12:10 INFO - 'sessionrestore', 08:12:10 INFO - 'sessionrestore_no_auto_restore', 08:12:10 INFO - 'tabpaint']}, 08:12:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['tsvgx', 08:12:10 INFO - 'tsvgr_opacity', 08:12:10 INFO - 'tart', 08:12:10 INFO - 'tscrollx', 08:12:10 INFO - 'cart']}, 08:12:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:12:10 INFO - 'tsvgr_opacity', 08:12:10 INFO - 'tart', 08:12:10 INFO - 'tscrollx', 08:12:10 INFO - 'cart']}, 08:12:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'talos_options': ['--disable-e10s'], 08:12:10 INFO - 'tests': ['tp5o']}, 08:12:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'tests': ['tp5o']}, 08:12:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'talos_options': ['--disable-e10s', 08:12:10 INFO - '--xperf_path', 08:12:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:10 INFO - 'tests': ['tp5n']}, 08:12:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:10 INFO - 'talos_options': ['--xperf_path', 08:12:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:10 INFO - 'tests': ['tp5n']}}, 08:12:10 INFO - 'talos.zip': {'path': '', 08:12:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:12:10 INFO - Running post-action listener: _resource_record_post_action 08:12:10 INFO - [mozharness: 2016-09-06 15:12:10.980778Z] Finished populate-webroot step (success) 08:12:10 INFO - [mozharness: 2016-09-06 15:12:10.980908Z] Running create-virtualenv step. 08:12:10 INFO - Running pre-action listener: _resource_record_pre_action 08:12:10 INFO - Running main action method: create_virtualenv 08:12:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:12:10 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 08:12:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:12:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:12:11 INFO - Using real prefix '/tools/python27' 08:12:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:12:11 INFO - Installing distribute.............................................................................................................................................................................................done. 08:12:14 INFO - Installing pip.................done. 08:12:14 INFO - Return code: 0 08:12:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:12:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:14 INFO - 'HOME': '/Users/cltbld', 08:12:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:14 INFO - 'LOGNAME': 'cltbld', 08:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:14 INFO - 'MOZ_NO_REMOTE': '1', 08:12:14 INFO - 'NO_EM_RESTART': '1', 08:12:14 INFO - 'PAGER': '/bin/cat', 08:12:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:14 INFO - 'PWD': '/builds/slave/test', 08:12:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:14 INFO - 'SHELL': '/bin/bash', 08:12:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:14 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:14 INFO - 'USER': 'cltbld', 08:12:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:14 INFO - 'XPC_FLAGS': '0x0', 08:12:14 INFO - 'XPC_SERVICE_NAME': '0', 08:12:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:15 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 08:12:15 INFO - Cleaning up... 08:12:15 INFO - Return code: 0 08:12:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:15 INFO - 'HOME': '/Users/cltbld', 08:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:15 INFO - 'LOGNAME': 'cltbld', 08:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:15 INFO - 'MOZ_NO_REMOTE': '1', 08:12:15 INFO - 'NO_EM_RESTART': '1', 08:12:15 INFO - 'PAGER': '/bin/cat', 08:12:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:15 INFO - 'PWD': '/builds/slave/test', 08:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:15 INFO - 'SHELL': '/bin/bash', 08:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:15 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:15 INFO - 'USER': 'cltbld', 08:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:15 INFO - 'XPC_FLAGS': '0x0', 08:12:15 INFO - 'XPC_SERVICE_NAME': '0', 08:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:15 INFO - Downloading/unpacking psutil>=3.1.1 08:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:12:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:12:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:12:18 INFO - Installing collected packages: psutil 08:12:18 INFO - Running setup.py install for psutil 08:12:18 INFO - building 'psutil._psutil_osx' extension 08:12:18 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 08:12:18 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 08:12:18 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 08:12:19 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 08:12:19 INFO - building 'psutil._psutil_posix' extension 08:12:19 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 08:12:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:12:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:12:19 INFO - ^ 08:12:19 INFO - 1 warning generated. 08:12:19 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 08:12:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:12:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:12:19 INFO - Successfully installed psutil 08:12:19 INFO - Cleaning up... 08:12:19 INFO - Return code: 0 08:12:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:19 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:19 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 08:12:19 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 08:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:19 INFO - 'HOME': '/Users/cltbld', 08:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:19 INFO - 'LOGNAME': 'cltbld', 08:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:19 INFO - 'MOZ_NO_REMOTE': '1', 08:12:19 INFO - 'NO_EM_RESTART': '1', 08:12:19 INFO - 'PAGER': '/bin/cat', 08:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:19 INFO - 'PWD': '/builds/slave/test', 08:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:19 INFO - 'SHELL': '/bin/bash', 08:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:19 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:19 INFO - 'USER': 'cltbld', 08:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:19 INFO - 'XPC_FLAGS': '0x0', 08:12:19 INFO - 'XPC_SERVICE_NAME': '0', 08:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:12:22 INFO - Installing collected packages: mozsystemmonitor 08:12:22 INFO - Running setup.py install for mozsystemmonitor 08:12:22 INFO - Successfully installed mozsystemmonitor 08:12:22 INFO - Cleaning up... 08:12:22 INFO - Return code: 0 08:12:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:12:22 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')]} 08:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:22 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')]} 08:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:22 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:22 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 08:12:22 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 08:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:22 INFO - 'HOME': '/Users/cltbld', 08:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:22 INFO - 'LOGNAME': 'cltbld', 08:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:22 INFO - 'MOZ_NO_REMOTE': '1', 08:12:22 INFO - 'NO_EM_RESTART': '1', 08:12:22 INFO - 'PAGER': '/bin/cat', 08:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:22 INFO - 'PWD': '/builds/slave/test', 08:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:22 INFO - 'SHELL': '/bin/bash', 08:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:22 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:22 INFO - 'USER': 'cltbld', 08:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:22 INFO - 'XPC_FLAGS': '0x0', 08:12:22 INFO - 'XPC_SERVICE_NAME': '0', 08:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:22 INFO - Downloading/unpacking jsonschema==2.5.1 08:12:22 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 08:12:22 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 08:12:22 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 08:12:22 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 08:12:22 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 08:12:22 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 08:12:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:12:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:12:25 INFO - warning: no files found matching '*.txt' 08:12:25 INFO - no previously-included directories found matching 'build' 08:12:25 INFO - no previously-included directories found matching 'dist' 08:12:25 INFO - no previously-included directories found matching '.git*' 08:12:25 INFO - Installing collected packages: jsonschema, functools32 08:12:25 INFO - Running setup.py install for functools32 08:12:25 INFO - warning: no files found matching '*.txt' 08:12:25 INFO - no previously-included directories found matching 'build' 08:12:25 INFO - no previously-included directories found matching 'dist' 08:12:25 INFO - no previously-included directories found matching '.git*' 08:12:25 INFO - Successfully installed jsonschema functools32 08:12:25 INFO - Cleaning up... 08:12:26 INFO - Return code: 0 08:12:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:12:26 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')]} 08:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:26 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')]} 08:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:26 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:26 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 08:12:26 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 08:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:26 INFO - 'HOME': '/Users/cltbld', 08:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:26 INFO - 'LOGNAME': 'cltbld', 08:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:26 INFO - 'MOZ_NO_REMOTE': '1', 08:12:26 INFO - 'NO_EM_RESTART': '1', 08:12:26 INFO - 'PAGER': '/bin/cat', 08:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:26 INFO - 'PWD': '/builds/slave/test', 08:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:26 INFO - 'SHELL': '/bin/bash', 08:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:26 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:26 INFO - 'USER': 'cltbld', 08:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:26 INFO - 'XPC_FLAGS': '0x0', 08:12:26 INFO - 'XPC_SERVICE_NAME': '0', 08:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:12:26 INFO - Cleaning up... 08:12:26 INFO - Return code: 0 08:12:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:12:26 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')]} 08:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:26 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')]} 08:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:26 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:26 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 08:12:26 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 08:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:26 INFO - 'HOME': '/Users/cltbld', 08:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:26 INFO - 'LOGNAME': 'cltbld', 08:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:26 INFO - 'MOZ_NO_REMOTE': '1', 08:12:26 INFO - 'NO_EM_RESTART': '1', 08:12:26 INFO - 'PAGER': '/bin/cat', 08:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:26 INFO - 'PWD': '/builds/slave/test', 08:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:26 INFO - 'SHELL': '/bin/bash', 08:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:26 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:26 INFO - 'USER': 'cltbld', 08:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:26 INFO - 'XPC_FLAGS': '0x0', 08:12:26 INFO - 'XPC_SERVICE_NAME': '0', 08:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:12:26 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 08:12:26 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 08:12:26 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 08:12:26 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 08:12:26 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 08:12:26 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 08:12:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:12:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:12:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:12:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 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 08:12:29 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 08:12:30 INFO - Downloading docopt-0.6.1.tar.gz 08:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:12:30 INFO - Installing collected packages: blobuploader, requests, docopt 08:12:30 INFO - Running setup.py install for blobuploader 08:12:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:12:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:12:30 INFO - Running setup.py install for requests 08:12:30 INFO - Running setup.py install for docopt 08:12:31 INFO - Successfully installed blobuploader requests docopt 08:12:31 INFO - Cleaning up... 08:12:31 INFO - Return code: 0 08:12:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:31 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:31 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 08:12:31 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 08:12:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:31 INFO - 'HOME': '/Users/cltbld', 08:12:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:31 INFO - 'LOGNAME': 'cltbld', 08:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:31 INFO - 'MOZ_NO_REMOTE': '1', 08:12:31 INFO - 'NO_EM_RESTART': '1', 08:12:31 INFO - 'PAGER': '/bin/cat', 08:12:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:31 INFO - 'PWD': '/builds/slave/test', 08:12:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:31 INFO - 'SHELL': '/bin/bash', 08:12:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:31 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:31 INFO - 'USER': 'cltbld', 08:12:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:31 INFO - 'XPC_FLAGS': '0x0', 08:12:31 INFO - 'XPC_SERVICE_NAME': '0', 08:12:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-KDMjfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Q3H4Ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-juwJoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-o_TfwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-ueAK31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-9xbmGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-MKnlFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-G9dS5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-yrz3G0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-fN0pMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-qanZM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-iToaTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-KmcagT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-GrRnG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-3Kecze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:33 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-QlysIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:33 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-D1woDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:12:33 INFO - Running setup.py install for manifestparser 08:12:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:12:33 INFO - Running setup.py install for mozcrash 08:12:33 INFO - Running setup.py install for mozdebug 08:12:33 INFO - Running setup.py install for mozdevice 08:12:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:12:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:12:33 INFO - Running setup.py install for mozfile 08:12:33 INFO - Running setup.py install for mozhttpd 08:12:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Running setup.py install for mozinfo 08:12:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Running setup.py install for mozInstall 08:12:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Running setup.py install for mozleak 08:12:34 INFO - Running setup.py install for mozlog 08:12:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Running setup.py install for moznetwork 08:12:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:12:34 INFO - Running setup.py install for mozprocess 08:12:35 INFO - Running setup.py install for mozprofile 08:12:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:12:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:12:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:12:35 INFO - Running setup.py install for mozrunner 08:12:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:12:35 INFO - Running setup.py install for mozscreenshot 08:12:35 INFO - Running setup.py install for moztest 08:12:35 INFO - Running setup.py install for mozversion 08:12:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:12:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:12:35 INFO - Cleaning up... 08:12:35 INFO - Return code: 0 08:12:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:35 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:35 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 08:12:35 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 08:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:35 INFO - 'HOME': '/Users/cltbld', 08:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:35 INFO - 'LOGNAME': 'cltbld', 08:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:35 INFO - 'MOZ_NO_REMOTE': '1', 08:12:35 INFO - 'NO_EM_RESTART': '1', 08:12:35 INFO - 'PAGER': '/bin/cat', 08:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:35 INFO - 'PWD': '/builds/slave/test', 08:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:35 INFO - 'SHELL': '/bin/bash', 08:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:35 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:35 INFO - 'USER': 'cltbld', 08:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:35 INFO - 'XPC_FLAGS': '0x0', 08:12:35 INFO - 'XPC_SERVICE_NAME': '0', 08:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-jNop32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-CbQAla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-R93HIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Bn0fpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-wF9ZAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-eCi396-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-5RXet6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-SoU8hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:36 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)) 08:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-3Zja7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-YHNFZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Ne_9yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-0jNxY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-R90tNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Aedq_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-r0hNGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-rA0OoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:37 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)) 08:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-WJS2bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:37 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)) 08:12:37 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)) 08:12:37 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)) 08:12:37 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)) 08:12:37 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)) 08:12:37 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)) 08:12:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:12:37 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 08:12:37 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 08:12:37 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 08:12:37 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 08:12:37 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 08:12:37 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 08:12:40 INFO - Downloading blessings-1.6.tar.gz 08:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:12:40 INFO - Installing collected packages: blessings 08:12:40 INFO - Running setup.py install for blessings 08:12:40 INFO - Successfully installed blessings 08:12:40 INFO - Cleaning up... 08:12:41 INFO - Return code: 0 08:12:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:12:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:41 INFO - Reading from file tmpfile_stdout 08:12:41 INFO - Current package versions: 08:12:41 INFO - blessings == 1.6 08:12:41 INFO - blobuploader == 1.2.4 08:12:41 INFO - docopt == 0.6.1 08:12:41 INFO - functools32 == 3.2.3-2 08:12:41 INFO - jsonschema == 2.5.1 08:12:41 INFO - manifestparser == 1.1 08:12:41 INFO - mozInstall == 1.12 08:12:41 INFO - mozcrash == 0.17 08:12:41 INFO - mozdebug == 0.1 08:12:41 INFO - mozdevice == 0.48 08:12:41 INFO - mozfile == 1.2 08:12:41 INFO - mozhttpd == 0.7 08:12:41 INFO - mozinfo == 0.9 08:12:41 INFO - mozleak == 0.1 08:12:41 INFO - mozlog == 3.3 08:12:41 INFO - moznetwork == 0.27 08:12:41 INFO - mozprocess == 0.23 08:12:41 INFO - mozprofile == 0.28 08:12:41 INFO - mozrunner == 6.12 08:12:41 INFO - mozscreenshot == 0.1 08:12:41 INFO - mozsystemmonitor == 0.3 08:12:41 INFO - moztest == 0.7 08:12:41 INFO - mozversion == 1.4 08:12:41 INFO - psutil == 3.1.1 08:12:41 INFO - requests == 1.2.3 08:12:41 INFO - wsgiref == 0.1.2 08:12:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:41 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:41 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 08:12:41 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 08:12:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:41 INFO - 'HOME': '/Users/cltbld', 08:12:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:41 INFO - 'LOGNAME': 'cltbld', 08:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:41 INFO - 'MOZ_NO_REMOTE': '1', 08:12:41 INFO - 'NO_EM_RESTART': '1', 08:12:41 INFO - 'PAGER': '/bin/cat', 08:12:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:41 INFO - 'PWD': '/builds/slave/test', 08:12:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:41 INFO - 'SHELL': '/bin/bash', 08:12:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:41 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:41 INFO - 'USER': 'cltbld', 08:12:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:41 INFO - 'XPC_FLAGS': '0x0', 08:12:41 INFO - 'XPC_SERVICE_NAME': '0', 08:12:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 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)) 08:12:41 INFO - Cleaning up... 08:12:41 INFO - Return code: 0 08:12:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:12: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')]} 08:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12: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')]} 08:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:42 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 0x10e916a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ab618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa090488ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, '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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 08:12:42 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 08:12:42 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 08:12:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:12:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:42 INFO - 'HOME': '/Users/cltbld', 08:12:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:42 INFO - 'LOGNAME': 'cltbld', 08:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:42 INFO - 'MOZ_NO_REMOTE': '1', 08:12:42 INFO - 'NO_EM_RESTART': '1', 08:12:42 INFO - 'PAGER': '/bin/cat', 08:12:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:42 INFO - 'PWD': '/builds/slave/test', 08:12:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:42 INFO - 'SHELL': '/bin/bash', 08:12:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:12:42 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:12:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:42 INFO - 'USER': 'cltbld', 08:12:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:42 INFO - 'XPC_FLAGS': '0x0', 08:12:42 INFO - 'XPC_SERVICE_NAME': '0', 08:12:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:12:42 INFO - Cleaning up... 08:12:42 INFO - Return code: 0 08:12:42 INFO - Running post-action listener: _resource_record_post_action 08:12:42 INFO - Running post-action listener: _start_resource_monitoring 08:12:42 INFO - Starting resource monitoring. 08:12:42 INFO - [mozharness: 2016-09-06 15:12:42.289054Z] Finished create-virtualenv step (success) 08:12:42 INFO - [mozharness: 2016-09-06 15:12:42.290011Z] Running install step. 08:12:42 INFO - Running pre-action listener: _resource_record_pre_action 08:12:42 INFO - Running main action method: install 08:12:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:42 INFO - Reading from file tmpfile_stdout 08:12:42 INFO - Detecting whether we're running mozinstall >=1.0... 08:12:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:12:42 INFO - Reading from file tmpfile_stdout 08:12:42 INFO - Output received: 08:12:42 INFO - Usage: mozinstall [options] installer 08:12:42 INFO - Options: 08:12:42 INFO - -h, --help show this help message and exit 08:12:42 INFO - -d DEST, --destination=DEST 08:12:42 INFO - Directory to install application into. [default: 08:12:42 INFO - "/builds/slave/test"] 08:12:42 INFO - --app=APP Application being installed. [default: firefox] 08:12:42 INFO - mkdir: /builds/slave/test/build/application 08:12:42 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'] 08:12:42 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 08:13:17 INFO - Reading from file tmpfile_stdout 08:13:17 INFO - Output received: 08:13:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:13:17 INFO - Running post-action listener: _resource_record_post_action 08:13:17 INFO - [mozharness: 2016-09-06 15:13:17.196198Z] Finished install step (success) 08:13:17 INFO - [mozharness: 2016-09-06 15:13:17.196327Z] Running run-tests step. 08:13:17 INFO - Running pre-action listener: _resource_record_pre_action 08:13:17 INFO - Running main action method: run_tests 08:13:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:13:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:13:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:13:17 INFO - 2016-09-06 08:13:17.210 screenresolution[1642:7568] starting screenresolution argv=screenresolution get 08:13:17 INFO - 2016-09-06 08:13:17.213 screenresolution[1642:7568] Display 0: 1600x1200x32@60 08:13:17 INFO - 2016-09-06 08:13:17.219 screenresolution[1643:7570] starting screenresolution argv=screenresolution list 08:13:17 INFO - Available Modes on Display 0 08:13:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:13:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:13:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:13:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:13:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:13:17 INFO - Intel Iris: 08:13:17 INFO - Chipset Model: Intel Iris 08:13:17 INFO - Type: GPU 08:13:17 INFO - Bus: Built-In 08:13:17 INFO - VRAM (Dynamic, Max): 1536 MB 08:13:17 INFO - Vendor: Intel (0x8086) 08:13:17 INFO - Device ID: 0x0a2e 08:13:17 INFO - Revision ID: 0x0009 08:13:17 INFO - Displays: 08:13:17 INFO - Display: 08:13:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:13:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:13:17 INFO - Display Serial Number: 1600x1200 60 08:13:17 INFO - Main Display: Yes 08:13:17 INFO - Mirror: Off 08:13:17 INFO - Online: Yes 08:13:17 INFO - Rotation: Supported 08:13:17 INFO - Return code: 0 08:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:13:17 INFO - Python 2.7.3 08:13:17 INFO - Return code: 0 08:13:17 INFO - grabbing minidump binary from tooltool 08:13:17 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')]} 08:13:17 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 0x10e87ad30>, '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 0x10e5dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e872b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:13:17 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 08:13:17 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 08:13:17 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 08:13:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:13:17 INFO - Return code: 0 08:13:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:13:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:13:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:13:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:13:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 08:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --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 08:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cQTWKcBnyw/Render', 08:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:13:17 INFO - 'HOME': '/Users/cltbld', 08:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:13:17 INFO - 'LOGNAME': 'cltbld', 08:13:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:13:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:17 INFO - 'MOZ_NO_REMOTE': '1', 08:13:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:13:17 INFO - 'NO_EM_RESTART': '1', 08:13:17 INFO - 'PAGER': '/bin/cat', 08:13:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:17 INFO - 'PWD': '/builds/slave/test', 08:13:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:13:17 INFO - 'SHELL': '/bin/bash', 08:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NJD6nGn1Ys/Listeners', 08:13:17 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 08:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:13:17 INFO - 'USER': 'cltbld', 08:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:17 INFO - 'XPC_FLAGS': '0x0', 08:13:17 INFO - 'XPC_SERVICE_NAME': '0', 08:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:13:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 08:13:17 INFO - mozversion application_buildid: 20160906064355 08:13:17 INFO - mozversion application_changeset: 0788fe67e9ae4254553d3f308cc2c3ee061023f1 08:13:17 INFO - mozversion application_display_name: Nightly 08:13:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:13:17 INFO - mozversion application_name: Firefox 08:13:17 INFO - mozversion application_remotingname: firefox 08:13:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:13:17 INFO - mozversion application_vendor: Mozilla 08:13:17 INFO - mozversion application_version: 51.0a1 08:13:17 INFO - mozversion platform_buildid: 20160906064355 08:13:17 INFO - mozversion platform_changeset: 0788fe67e9ae4254553d3f308cc2c3ee061023f1 08:13:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:13:17 INFO - mozversion platform_version: 51.0a1 08:13:17 INFO - using testdate: 1473174797 08:13:17 INFO - actual date: 1473174797 08:13:17 INFO - starting webserver on 'localhost:49283' 08:13:17 INFO - SUITE-START | Running 2 tests 08:13:17 INFO - TEST-START | dromaeo_css 08:13:17 INFO - operating with platform_type : mac_ 08:13:17 INFO - Initialising browser for dromaeo_css test... 08:13:17 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpPHpXYx/profile http://localhost:49283/getInfo.html) 08:13:20 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 08:13:20 INFO - PROCESS | 1653 | colorDepth:24 08:13:20 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 08:13:20 INFO - PROCESS | 1653 | __metrics 08:13:20 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 08:13:21 INFO - TEST-INFO | 1653: exit 0 08:13:21 INFO - Browser initialized. 08:13:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:13:21 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpPHpXYx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:13:33 INFO - PROCESS | 1656 | RSS: Main: 243998720 08:13:33 INFO - PROCESS | 1656 | 08:14:22 INFO - PROCESS | 1656 | 1473174862978 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] 08:16:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 08:16:36 INFO - PROCESS | 1656 | RSS: Main: 264081408 08:16:36 INFO - PROCESS | 1656 | 08:19:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 08:19:39 INFO - PROCESS | 1656 | RSS: Main: 268263424 08:19:39 INFO - PROCESS | 1656 | 08:22:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 08:22:42 INFO - PROCESS | 1656 | RSS: Main: 276594688 08:22:42 INFO - PROCESS | 1656 | 08:25:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 08:25:45 INFO - PROCESS | 1656 | RSS: Main: 272740352 08:25:45 INFO - PROCESS | 1656 | 08:28:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 08:28:48 INFO - PROCESS | 1656 | RSS: Main: 278138880 08:28:48 INFO - PROCESS | 1656 | 08:31:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 08:31:52 INFO - PROCESS | 1656 | RSS: Main: 280944640 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | __start_tp_report 08:31:52 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:31:52 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:31:52 INFO - PROCESS | 1656 | |i|pagename|runs| 08:31:52 INFO - PROCESS | 1656 | |0;dojo.html;3099.53506973954;3364.855948977347;3406.4719968404897;3407.0630576591434;3461.567304086989;5607.7476513556885;5667.886642267152;5679.204911312414;5700.8289751307475;5773.595848290622;4603.49361570227;5688.374278829326;5753.712314384275;5777.62445441046;5832.154337621053;4927.482614325498;5109.131447653889;5223.503767142115;5225.660332078422;5284.44513326101;4770.189067858455;5317.175837745136;5321.3348331458565;5372.301600791891;5376.247325374451;5165.74171291434;5176.637635365526;5204.34945631796;5250.107481728115;5287.86780330491;5098.056859481007;5232.502912223333;5239.895202095975;5255.448177941299;5281.36623605166;3889.86385476507;5184.274201611777;5272.525472707474;5273.736313184326;5278.894422111574;4950.059488697136;5247.47525247476;5828.3297420796725;5861.677607731573;5869.383714709979;6188.195534580521;6209.192804935375;6234.940060189779;6243.594166379171;6254.0931564923285;6261.217347831521;6267.310595834455;6269.185005949243;6271.655058971754;6272.937270627281;5781.392953739881;5868.4424979626865;5922.7927022554;5947.702614869239;5969.8955693196895;5331.733413329395;5336.359636843604;6203.883301005772;6296.527760417987;6302.243730752248;6141.662208578571;6175.2589689237575;6184.783532576338;6188.44304012641;6190.350013248634;5127.769335359487;5596.496315331558;6107.826086956522;6155.784547540815;6205.503559715212;6212.347703491068;6228;6230.626162430178;6240.500759939194;6243.375662433811;6143.139960405547;6150.446459818638;6204.255489341218;6228.190335256479;6239.719212635442;4846.757662116951;4888.511148885154;4923.48303428142;4945.431275403303;4968.080905032581;4417.491988421372;4435.866923992286;4442.822287108479;4450.109978004412;4485;5761.703616686212;5877.471027607536;5893.882122357614;5907.49786268172;5926.170336152942;6098.54260930432;6159.969200153971;6172.4136207060255;6195.101710251988;6229.657368844757;5940.881182376328;5953.910691339634;5961.2846458425265;5962.284525856839;6001.5198784097165;5443.809466668727;5450.1007333789485;5452.890942181134;5458.645188062843;5468.972655136698;6180.134781130645;6245;6259.624422534663;6291.496680265476;6378.968105159444;6199.752009919553;6214.689265536796;6217.626942383471;6221.2845522763655;6247.843803904939;6335.619862808247;6364.07720880479;6366.172397588469;6383.39357761012;6395.840103997437;6085.056816193497;6129.20597562684;6132.508481341157;6146;6149.692515374124;6220.564560480904;6221.471174949999;6227.875442491034;6227.875442491216;6242.968785156045;5141.691498510101;5996.4603185713495;6184.010558310649;6185.752569897334;6187.783427580013;5948.464638182584;6116.66358350295;6117.279373342116;6137.41694539018;6164.506839452923;5856.209411729362;6101.176341193882;6106.3283038866575;6116.480099191618;6119.112728654231;5445.829146733471;6130.969345153246;6148.907766383419;6177.722002509988;6197.101420294122;5813.3895940925595;6006.579539432198;6053.848653783691;6056;6088.178095956989 08:31:52 INFO - PROCESS | 1656 | |1;ext.html;463.9491551635545;468.4542507978205;471.8065593106825;493.0385747791807;564.9576281778868;38690.806545967265;41433.79283103584;41497.5475858345;41504.75485735425;41726.99999999993;37651;42297.78851105748;42371.3644295335;42464.36303455451;42611.3608295875;9610.471424071657;9781.853272200922;9799.265055120859;9802.509874506282;9819.607215711363;9517.904820951806;9602.039796020377;9619.989901060393;9628.277879159092;9628.759281017961;9591.66429174979;9631.614735410576;9638.228941684683;9653.806923861519;9658.565364558577;9504.667336643219;9557.952210238975;9562.426254424721;9577.425354478753;9583.712488625353;7875.9367485389885;9322.440653560809;9557.761055973588;9675.854862177073;9690.612375504972;39830.40254396186;40054;40078;40173.79913100445;40454.190916181804;3298.7855789373853;3307.338532293527;3357.345317663057;3478.269563391691;3616.529851119364;30412.527545777073;30439.086827395215;30728.2317942051;30910.22724431885;30971.845140774152;3213.4376484115187;3230.983845080783;3239.3521295740948;3264.135004223883;3358.2947581007775;485198.5740071277;486765;492455;492816;493092;60353;60925.69537152286;60934.695326523084;60936.39063609307;62431;34312.65687343144;35424;35498.935031949084;63401;63762;16047.518574442785;16204.83795162057;16210.43263485796;16223.26995285215;16261.59346016359;9728.854067188999;10617.628383006559;12435.75128497443;12467.750644987049;12491.437885295183;17741.37905173313;18427.447176584723;18593.628127437376;18607;18641.627167456845;6531.3468653134305;6806.7957961261245;7625.885611715828;7647.082350118021;7691.50005249657;842.6123982967781;927.0173615967096;988.5057471264367;999.4602914426274;1105.1159072741775;25450.490990180464;30078.398432031237;30763.846180768953;30822.53766193509;30877.228069298;746.813296675831;879.4547380623926;975.9658411955689;1025.2720568396373;1085.1698450685233;11837.408129593487;15419.61450963735;15498.922505387402;15628.3748650055;16054.598635033984;652725;666885;670239;672470;673995.6300218467;56313.43686563164;56442.7177864108;56743.71628141833;56937.71531142318;57173;1227.9631611051682;1311.4229738914846;1343.7111021130504;1354.5665387076042;1361.5370773936909;23555.64666529969;27759.4732289726;29324.706752932198;29994.95017674371;30007;49884.75057624834;49962;50691.98616027732;50810.23784643159;50817;622657;630956;631037;631598;635465;26496.86751566307;26702.866485667448;26759.06343277976;26829.792659330767;26905.730942691105;45901;47031.294530581385;47037.294440584104;47549;47714;38977.22045559016;39186;39255.01862453461;39968.80405547377;40313;38348;38591.228175436896;38835.223295534495;40017;40148.397774034;37689;38225.426618600184;38551;38702.80648596739;38848.41727374035;31584.052478425685;34361.31277374489;34608.13479662928;34616.30767384689;35039.29921401507;23620.763792361857;25556.872215638803;25791.613125802753;25935.87032064903;26180.345491362106 08:31:52 INFO - PROCESS | 1656 | |2;jquery.html;349.31708509863216;363.9581448133464;372.3223732806292;385.0489291450117;397.6321902240427;14278.857211427883;17410.955342679415;19235.51911202219;19605.313814016496;20132.396028119143;11733.823992640117;11938.76122477552;12029.398530073506;12029.699257518567;12047.397630118481;4360.909772556861;5097.770600322976;5204.947950520503;5315.149576067811;5345.732713364336;3822.311983842907;4575.313702944552;5367.953249116424;5373.408925018245;5702.287214098238;5921.851953701149;6013.128096426016;6032.607880487754;6034.607750496226;6042.57701960863;5793.188953546507;5793.6234144780665;5807.157962095473;5893.469587737124;6020.969895150518;5653.717314134276;5701.657900525982;5704.258446401983;5712.714364281811;5716.256886604757;12732.23606583608;13035.217886926815;13047.608571742863;13355.666108347272;13378.598642040753;2496.0639760089966;2630.6843178818663;2702.4324891772753;2808.8033837631374;2926.6049083373687;9877.654282100091;9954.303198776088;10009.949950250275;10347.63783267582;10484.161267098614;1829.1037391678115;1909.2267631609081;1950.882947023183;1985.186073709772;1987.5130593004662;95627.52186239093;114066.42966785196;118928;120999.3950030253;124169.37915310365;11020.559177632966;11197;11497.482613282422;11565.942170289094;11610.88389116098;14314.284285785669;14453.205073721047;14482;14504.854951450561;14510.984231103721;8320.875186872201;8595.355348348774;8692.913070869337;8704.477731336014;8708.303335733126;8450.492970421794;8485.278751306085;8570.528620925785;8609.784755381166;8661.870071948926;3655.5430571178604;3854.71089668276;4537;4563.201439748097;4625.421822272216;1917.3193516301792;1945.6692362298443;1971.4282857971027;3831.9845241011153;3903.7312873316287;796.6932730898529;818.9549574773445;853.9786505337293;901.7610333261691;910.4036855859423;19440.805591944183;20347.593048138955;20971.37085887426;21286.10419603469;21398.358049258546;894.579547612621;914.9679761208325;930.2418528898926;942.2744486745167;963.7881062960522;9711.980242074622;9787.874394444587;9973.850392244121;10540.367577945195;10573.942605739518;505442;507618;510824;537321;577202;124145.75854241522;126800;127495;130173.69826301615;130916;936.7611259129014;962.4658314635444;976.7509285132246;978.9510524473748;980.9264305176997;18676.719849202;18762.812371876105;18939.90530047341;18988;19085.71371429402;41778.74663760092;55595;56053.31173668088;56837.43162568487;57377.42622573721;78604;78640;79181.8122728148;79809.20190798018;80024.79962800679;5380.3274590676165;5476.808311709071;7145.82135446618;7443.851122977619;7476.102867655699;9160;10603;11134.610288639858;12207.75584488323;13009.804852927024;10998.175136864607;11384.544618215179;11458.026067784333;11625.069994400259;11626.128040396836;5223.686578805284;8267.917320826715;11250.381229032482;11270.478485404365;11418.942905285421;11234.3259404436;11527.250728702607;11782;11798.11514136426;11854.288742675468;7385.9261407387385;7476.962615186889;7567.9243207567215;7569.848603028019;7614.619269036415;9145.359824812465;10306.020928011827;11386.658400248005;11650.6504804856;11732.94133529361 08:31:52 INFO - PROCESS | 1656 | |3;mootools.html;1498.9100653960763;1526.6565022869854;1699.1589163364138;1838.2738818166815;1870.4201697473775;7852.743561030236;7968.482048666832;8477.78805529862;8980.191782739552;8987.37088403812;8055.6777728890775;8871.778205544864;8875.112488751121;8879.55602219888;8889;2251.9324420267417;2279.0541925101065;2300.3559003479054;2301.80434663053;2307.111761971636;2293.9655905161435;2370.7510711375317;2408.0127147869375;2421.2131057406327;2422.6729391532094;2393.102586530051;2401.9639705404434;2403.170906037423;2409.903603855844;2413.698287714036;2370.2296753555165;2370.6444033394955;2385.5467461182407;2398.364433425126;2407.7592240775784;2367.846090004144;2370.7747763962398;2374.3233178544033;2384.7972922301624;2387.331547166796;8774.780630484225;8784.077671844492;8800.163984421533;8807;8861;4755.453122890877;5164.509371609729;5242.7640756166065;5363.9463605363835;5372.462753724635;3597.94603080954;3613.5483064616924;3646.908827279313;3684.281565094808;3691.2986532559;1079.3038490173794;1086.666072860069;1095.7041598768367;1110.1285490889613;1112.7496313329516;11314.717132071682;11372.431378431045;11418.371989540572;11442.656720298404;11472.770544589144;4530.818767249339;4604.470485894098;4801.68789028712;4823.469418363977;4849.151398505247;9647.324687271823;9649.179819715262;9832.655857045112;9851.211903047739;9900.108990190918;5271.789128434896;5307.6019298552765;5322.813701520428;5323.680579165262;5333.546648534918;3719.925601487955;4074.6332830044844;4388.2759344707765;4670.159371313196;4881.29221262922;3899.9610003900166;3991.1419044905483;4085.571015043437;4107.774072426046;4149.585041495887;1096.961606343774;1312.9737405252033;1318.7494376068516;1328.7608230518406;1329.2024785129038;742.59157463396;845.5729856422467;871.9651213951371;887.2813021452515;891.3493150000406;5065.645404821627;5706.771729130871;5714.457126572969;5966.955782738003;6093.75624974996;431.3917376499184;435.4948260018427;437.92336341140174;439.24230702039364;444.64650602770877;3399.6430374810784;3949.229900169497;3986.3223252047223;4036.414719865603;4037.8384864605673;10265.74335641615;10661.413622250695;10699;10719.356838589709;10734;10105.29262951601;10592.523336449878;10621.5220315086;10693.679189624323;10849.403282819521;8010.519368837889;8225.917740822515;8229.547374894439;8232.958835205905;8236.917630823735;5929.495992840484;5997.490213331741;6064.636121832704;6081.847953801191;6111.449969502766;9611.327207095435;10134.949325253327;10206.489675516343;10210.387376757419;10218.846717299099;9978.251631127549;10129.088382045651;10312.793744125225;10350.689479315633;10418.426986515511;6940;7311.488195826241;7429.851402972018;7519.73680921187;7626.466147369631;8355.415120941476;8442.577871106543;8452.408331416742;8455.32357411418;8458.830823383374;7311.853762924606;7453.81365465846;7473.6263186841525;7539.547627142389;7549.2073332299315;7301.379382752525;7328.487005909536;7382.040334756447;7470.887936680845;7510.098788145458;6799.524033317621;6948.444124470131;6951.235364110045;6962.477814164059;6970.895436568354;4858.295547145714;4929.926051109164;5031.195008798574;5080.7205603692155;5090.490950905028;4898.92651610219;5005.199168133081;5049.444561098349;5066.189409694432;5228.921566176435 08:31:52 INFO - PROCESS | 1656 | |4;prototype.html;284.1163980022131;307.9045495896271;311.7615024506253;315.76063949000167;318.56674922105947;4684.812607495696;5571.944280557193;5733.104782085046;5902.114682797577;5957.165996760457;4894.8286809961655;5044.319016932713;5419.729013549317;5545.639533430324;5716.8284951451415;4316.589923957219;4362.280223763075;4382.737035777847;4402.625776808975;4419.73481591104;3661.041342881559;4132.607402296777;4291.206126866524;4341.2836881914445;4455.487618923817;3794.7477332480353;3838.750481218726;4341.565843415665;4342.196693611676;4343.3267843484155;3388.847501862422;3707.6663100321102;4230.851920182794;4359.978200109011;4386.429764130643;2939.808912420686;3095.5666206731075;3789.677877380426;3791.6966642668795;3804.6005169457085;4687.273472611717;5436.836894893159;5594.188842617838;5605.243292155547;5646.322441307069;4914.385701787277;5030.748462576857;5040.722760248185;5048.7728052237735;5114.744262786883;5344.327836081959;6071.964017991005;6123.295820980555;6166.907496387557;6190.257169139687;3332.450145725952;3729.4219395993664;4003.679705623543;4029.959700402988;4042.6765858731233;5626.774929002835;5726.427357264281;6353.523485738542;6371.745130194833;6476.676166191719;3684.7604905681046;3925.4896863407575;4007;4077.327241005201;4091.85678501251;3409.3351796399465;3615.1323682316056;4099.692523060723;4112.280350938574;4121.9587804122175;3786.9431958520645;4115.92985823688;4881.707097574086;4889.535494128052;4905.950940490549;5833.766649334064;5958.225430694069;6153.092786080871;6161.691915404212;6176.752929882844;1396.4220430913003;1396.5880065380736;1414.6631433295643;1415.8177921435718;1416.3838730152418;824.7773101262626;825.1830687619214;848.4654667559521;859.1537335724306;861.3711990247155;3971.9801400993388;4472.888177795581;4820.831270905502;4880.829170978998;4899.256416406753;4714;5293.761780719877;5336.199570064521;5359.812406565751;5364.946350536445;4218.725782824106;4222.796502996631;4527;4692.929606055912;4784.617230621542;2505.8120640952;2526.080179063925;2673.2782148820097;2676.69218039924;2791.3579876628082;4316.740995540277;4613.716210475769;5461.290032295856;5492.752826122754;5493.038718224295;4828.903421931612;4896.436909755353;5108.438071812097;5458.945410545924;5466.671999680032;5114;5121.950000249865;5151.4333423323405;5475.835724928259;5480.91778623321;4301.784910754387;4889.168841296917;4920.581750551243;5326.280952071421;5351.565037907665;4474.507804141607;4612.947238478845;4630.513796051501;5162.406323272721;5260.316158899319;5492.258545096521;5496.80761173357;5502.367227768697;5538.667679939216;5541.4483944495605;3338.983305083459;3570.535830342039;3649.5073165122367;3970.583088775637;4035.4866924903163;5326.36083669962;5772;6215.720292586754;6286.768197289896;6323.683815809283;5340.279062326536;6087.930103764564;6089.238845144357;6123.907426749463;6139.754409823557;5727.4845263926445;6108.43445752776;6126.724297406538;6187.917114505034;6199.752009919733;5569.303837020372;5839.795607153729;6077.726502307495;6082.574219804571;6124.234470691164;5405.8918821622565;5756.366799652119;6069.939300607114;6160.260768707783;6222.097795819672;4921;5969.313528944227;6007.438066102757;6027.4739277521385;6030.728617212324 08:31:52 INFO - PROCESS | 1656 | |5;yui.html;719.4783781758226;767.1446337333871;785.6268272570534;802.8916096326996;836.544412721864;15505.767413488808;15561.533154005383;15575.221238938051;15584.376624934988;15638;14301.213433261166;14368.71262574748;14374.20941848198;14376;14415.207163605997;666.6866572710818;820.4011071917504;821.954792486413;835.6991483066091;839.8026463781007;835.9164083591622;846.255295340103;846.9745907622781;847.9703210387638;848.6435697007272;859.2181115185183;866.1079088538784;900.5587262241487;901.6753968571309;901.8692289618001;863.6329559937033;868.830578037283;873.5457562067753;890.9109089091104;893.8871105473694;646.6411141816291;649.486905344777;649.7985624456386;660.8017594721564;664.0570390046146;14213.218272994982;14360.066595671353;14572.05281656689;14715.55853324402;14745.041572297767;14193.716125677533;14210.147391156564;14362.425502979868;14408.207548584824;14573.6356591085;11498.310101393943;11550.306981581132;11639.534418623245;11704.765904681859;11750.353730544817;3211.0527394418705;3621.7826930384263;3762.7177961652988;3867.903302417434;3940.4286378474962;68458;98924;100980;118760.61670278231;124159.9999999991;10509.054185123376;10708;11047.779044419067;11109.666709998552;11249.550017999352;20933.89533052325;20976;21183;21184.364469065953;21271.255506057212;6853.897191542131;7783.104942931635;7803.141654418009;8013.879791803128;8091.878621820678;4296.205202037633;4357.041450880801;4362.1275744851155;4383;4402;3420.9828950855085;3495.9861640124586;3534.9823250884097;3551.662592053751;3551.7158627309755;633.1673848888696;640.8782331357028;645.1741770533724;658.9095047696071;661.8874791285493;553.3194171169484;581.7149596697673;620.8416853702367;621.3103455164763;626.3661304215761;8619.569021549023;8916.286697064219;9073.682421115229;9131.634734610541;9220.815583688289;541.5153437673339;565.2369301443019;577.1631134854478;591.3849596419761;612.6844674992383;4222.19778242133;4245.908008647254;4318.52496225415;4336.6313863321975;4485.372047913294;93409;94490.52754736182;136443;167896;176045;59431;100032.4998375018;106934;141575;147751;103208.48395758124;103333;103643.4817825921;105127.94872051335;105686;9491.66779162726;9742.9025709742;9844.803103937738;9978.401295922267;10070.597176112873;46449.30326045045;58487.70756146192;59551.80896382135;60366.69816650889;60935.39064609297;75413;87630.56184719036;91349.54325228331;91755.62366564374;94073.5296323514;12444.377781111089;12771.552995645108;12877.098603097693;13262.801057983945;13524.98562607828;14448.060876043024;14515.346809393814;14638.853611463748;14820.407183712961;14918.403263869324;11197.72005699864;13584.981126415756;13603.251821149535;13672.65818354549;13735.107218030797;13429.26139062361;13905.51330703461;14021.5092471763;14113.788293175405;14121.788173177205;12991.935040324737;13060.151090179108;13441.193528388329;13441.476300319731;13830.239336836168;11498.942505287754;11526.654200374001;11606.30362178272;11616.709582260512;11857.762844743229;10967.783562718085;11276.492557834754;11414.315141091562;11516.884831151581;11517 08:31:52 INFO - PROCESS | 1656 | __end_tp_report 08:31:52 INFO - PROCESS | 1656 | __start_cc_report 08:31:52 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,384 08:31:52 INFO - PROCESS | 1656 | __end_cc_report 08:31:52 INFO - PROCESS | 1656 | __startTimestamp1473175912136__endTimestamp 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:31:52 INFO - PROCESS | 1656 | Number of tests: 6 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5721.40 Median:5969.90 stddev:666.40 (11.2%) stddev-sans-first:635.91 08:31:52 INFO - PROCESS | 1656 | Values: 3099.5 3364.9 3406.5 3407.1 3461.6 5607.7 5667.9 5679.2 5700.8 5773.6 4603.5 5688.4 5753.7 5777.6 5832.2 4927.5 5109.1 5223.5 5225.7 5284.4 4770.2 5317.2 5321.3 5372.3 5376.2 5165.7 5176.6 5204.3 5250.1 5287.9 5098.1 5232.5 5239.9 5255.4 5281.4 3889.9 5184.3 5272.5 5273.7 5278.9 4950.1 5247.5 5828.3 5861.7 5869.4 6188.2 6209.2 6234.9 6243.6 6254.1 6261.2 6267.3 6269.2 6271.7 6272.9 5781.4 5868.4 5922.8 5947.7 5969.9 5331.7 5336.4 6203.9 6296.5 6302.2 6141.7 6175.3 6184.8 6188.4 6190.4 5127.8 5596.5 6107.8 6155.8 6205.5 6212.3 6228.0 6230.6 6240.5 6243.4 6143.1 6150.4 6204.3 6228.2 6239.7 4846.8 4888.5 4923.5 4945.4 4968.1 4417.5 4435.9 4442.8 4450.1 4485.0 5761.7 5877.5 5893.9 5907.5 5926.2 6098.5 6160.0 6172.4 6195.1 6229.7 5940.9 5953.9 5961.3 5962.3 6001.5 5443.8 5450.1 5452.9 5458.6 5469.0 6180.1 6245.0 6259.6 6291.5 6379.0 6199.8 6214.7 6217.6 6221.3 6247.8 6335.6 6364.1 6366.2 6383.4 6395.8 6085.1 6129.2 6132.5 6146.0 6149.7 6220.6 6221.5 6227.9 6227.9 6243.0 5141.7 5996.5 6184.0 6185.8 6187.8 5948.5 6116.7 6117.3 6137.4 6164.5 5856.2 6101.2 6106.3 6116.5 6119.1 5445.8 6131.0 6148.9 6177.7 6197.1 5813.4 6006.6 6053.8 6056.0 6088.2 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:71951.45 Median:40383.60 stddev:160893.39 (398.4%) stddev-sans-first:161255.21 08:31:52 INFO - PROCESS | 1656 | Values: 463.9 468.5 471.8 493.0 565.0 38690.8 41433.8 41497.5 41504.8 41727.0 37651.0 42297.8 42371.4 42464.4 42611.4 9610.5 9781.9 9799.3 9802.5 9819.6 9517.9 9602.0 9620.0 9628.3 9628.8 9591.7 9631.6 9638.2 9653.8 9658.6 9504.7 9558.0 9562.4 9577.4 9583.7 7875.9 9322.4 9557.8 9675.9 9690.6 39830.4 40054.0 40078.0 40173.8 40454.2 3298.8 3307.3 3357.3 3478.3 3616.5 30412.5 30439.1 30728.2 30910.2 30971.8 3213.4 3231.0 3239.4 3264.1 3358.3 485198.6 486765.0 492455.0 492816.0 493092.0 60353.0 60925.7 60934.7 60936.4 62431.0 34312.7 35424.0 35498.9 63401.0 63762.0 16047.5 16204.8 16210.4 16223.3 16261.6 9728.9 10617.6 12435.8 12467.8 12491.4 17741.4 18427.4 18593.6 18607.0 18641.6 6531.3 6806.8 7625.9 7647.1 7691.5 842.6 927.0 988.5 999.5 1105.1 25450.5 30078.4 30763.8 30822.5 30877.2 746.8 879.5 976.0 1025.3 1085.2 11837.4 15419.6 15498.9 15628.4 16054.6 652725.0 666885.0 670239.0 672470.0 673995.6 56313.4 56442.7 56743.7 56937.7 57173.0 1228.0 1311.4 1343.7 1354.6 1361.5 23555.6 27759.5 29324.7 29995.0 30007.0 49884.8 49962.0 50692.0 50810.2 50817.0 622657.0 630956.0 631037.0 631598.0 635465.0 26496.9 26702.9 26759.1 26829.8 26905.7 45901.0 47031.3 47037.3 47549.0 47714.0 38977.2 39186.0 39255.0 39968.8 40313.0 38348.0 38591.2 38835.2 40017.0 40148.4 37689.0 38225.4 38551.0 38702.8 38848.4 31584.1 34361.3 34608.1 34616.3 35039.3 23620.8 25556.9 25791.6 25935.9 26180.3 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32227.80 Median:3879.22 stddev:89012.12 (2294.6%) stddev-sans-first:89229.64 08:31:52 INFO - PROCESS | 1656 | Values: 349.3 364.0 372.3 385.0 397.6 14278.9 17411.0 19235.5 19605.3 20132.4 11733.8 11938.8 12029.4 12029.7 12047.4 4360.9 5097.8 5204.9 5315.1 5345.7 3822.3 4575.3 5368.0 5373.4 5702.3 5921.9 6013.1 6032.6 6034.6 6042.6 5793.2 5793.6 5807.2 5893.5 6021.0 5653.7 5701.7 5704.3 5712.7 5716.3 12732.2 13035.2 13047.6 13355.7 13378.6 2496.1 2630.7 2702.4 2808.8 2926.6 9877.7 9954.3 10009.9 10347.6 10484.2 1829.1 1909.2 1950.9 1985.2 1987.5 95627.5 114066.4 118928.0 120999.4 124169.4 11020.6 11197.0 11497.5 11565.9 11610.9 14314.3 14453.2 14482.0 14504.9 14511.0 8320.9 8595.4 8692.9 8704.5 8708.3 8450.5 8485.3 8570.5 8609.8 8661.9 3655.5 3854.7 4537.0 4563.2 4625.4 1917.3 1945.7 1971.4 3832.0 3903.7 796.7 819.0 854.0 901.8 910.4 19440.8 20347.6 20971.4 21286.1 21398.4 894.6 915.0 930.2 942.3 963.8 9712.0 9787.9 9973.9 10540.4 10573.9 505442.0 507618.0 510824.0 537321.0 577202.0 124145.8 126800.0 127495.0 130173.7 130916.0 936.8 962.5 976.8 979.0 980.9 18676.7 18762.8 18939.9 18988.0 19085.7 41778.7 55595.0 56053.3 56837.4 57377.4 78604.0 78640.0 79181.8 79809.2 80024.8 5380.3 5476.8 7145.8 7443.9 7476.1 9160.0 10603.0 11134.6 12207.8 13009.8 10998.2 11384.5 11458.0 11625.1 11626.1 5223.7 8267.9 11250.4 11270.5 11418.9 11234.3 11527.3 11782.0 11798.1 11854.3 7385.9 7477.0 7567.9 7569.8 7614.6 9145.4 10306.0 11386.7 11650.7 11732.9 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5681.89 Median:4812.58 stddev:3198.53 (66.5%) stddev-sans-first:3192.06 08:31:52 INFO - PROCESS | 1656 | Values: 1498.9 1526.7 1699.2 1838.3 1870.4 7852.7 7968.5 8477.8 8980.2 8987.4 8055.7 8871.8 8875.1 8879.6 8889.0 2251.9 2279.1 2300.4 2301.8 2307.1 2294.0 2370.8 2408.0 2421.2 2422.7 2393.1 2402.0 2403.2 2409.9 2413.7 2370.2 2370.6 2385.5 2398.4 2407.8 2367.8 2370.8 2374.3 2384.8 2387.3 8774.8 8784.1 8800.2 8807.0 8861.0 4755.5 5164.5 5242.8 5363.9 5372.5 3597.9 3613.5 3646.9 3684.3 3691.3 1079.3 1086.7 1095.7 1110.1 1112.7 11314.7 11372.4 11418.4 11442.7 11472.8 4530.8 4604.5 4801.7 4823.5 4849.2 9647.3 9649.2 9832.7 9851.2 9900.1 5271.8 5307.6 5322.8 5323.7 5333.5 3719.9 4074.6 4388.3 4670.2 4881.3 3900.0 3991.1 4085.6 4107.8 4149.6 1097.0 1313.0 1318.7 1328.8 1329.2 742.6 845.6 872.0 887.3 891.3 5065.6 5706.8 5714.5 5967.0 6093.8 431.4 435.5 437.9 439.2 444.6 3399.6 3949.2 3986.3 4036.4 4037.8 10265.7 10661.4 10699.0 10719.4 10734.0 10105.3 10592.5 10621.5 10693.7 10849.4 8010.5 8225.9 8229.5 8233.0 8236.9 5929.5 5997.5 6064.6 6081.8 6111.4 9611.3 10134.9 10206.5 10210.4 10218.8 9978.3 10129.1 10312.8 10350.7 10418.4 6940.0 7311.5 7429.9 7519.7 7626.5 8355.4 8442.6 8452.4 8455.3 8458.8 7311.9 7453.8 7473.6 7539.5 7549.2 7301.4 7328.5 7382.0 7470.9 7510.1 6799.5 6948.4 6951.2 6962.5 6970.9 4858.3 4929.9 5031.2 5080.7 5090.5 4898.9 5005.2 5049.4 5066.2 5228.9 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4597.27 Median:4975.87 stddev:1455.95 (29.3%) stddev-sans-first:1423.59 08:31:52 INFO - PROCESS | 1656 | Values: 284.1 307.9 311.8 315.8 318.6 4684.8 5571.9 5733.1 5902.1 5957.2 4894.8 5044.3 5419.7 5545.6 5716.8 4316.6 4362.3 4382.7 4402.6 4419.7 3661.0 4132.6 4291.2 4341.3 4455.5 3794.7 3838.8 4341.6 4342.2 4343.3 3388.8 3707.7 4230.9 4360.0 4386.4 2939.8 3095.6 3789.7 3791.7 3804.6 4687.3 5436.8 5594.2 5605.2 5646.3 4914.4 5030.7 5040.7 5048.8 5114.7 5344.3 6072.0 6123.3 6166.9 6190.3 3332.5 3729.4 4003.7 4030.0 4042.7 5626.8 5726.4 6353.5 6371.7 6476.7 3684.8 3925.5 4007.0 4077.3 4091.9 3409.3 3615.1 4099.7 4112.3 4122.0 3786.9 4115.9 4881.7 4889.5 4906.0 5833.8 5958.2 6153.1 6161.7 6176.8 1396.4 1396.6 1414.7 1415.8 1416.4 824.8 825.2 848.5 859.2 861.4 3972.0 4472.9 4820.8 4880.8 4899.3 4714.0 5293.8 5336.2 5359.8 5364.9 4218.7 4222.8 4527.0 4692.9 4784.6 2505.8 2526.1 2673.3 2676.7 2791.4 4316.7 4613.7 5461.3 5492.8 5493.0 4828.9 4896.4 5108.4 5458.9 5466.7 5114.0 5122.0 5151.4 5475.8 5480.9 4301.8 4889.2 4920.6 5326.3 5351.6 4474.5 4612.9 4630.5 5162.4 5260.3 5492.3 5496.8 5502.4 5538.7 5541.4 3339.0 3570.5 3649.5 3970.6 4035.5 5326.4 5772.0 6215.7 6286.8 6323.7 5340.3 6087.9 6089.2 6123.9 6139.8 5727.5 6108.4 6126.7 6187.9 6199.8 5569.3 5839.8 6077.7 6082.6 6124.2 5405.9 5756.4 6069.9 6160.3 6222.1 4921.0 5969.3 6007.4 6027.5 6030.7 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:23306.62 Median:3551.69 stddev:36905.05 (1039.1%) stddev-sans-first:36969.61 08:31:52 INFO - PROCESS | 1656 | Values: 719.5 767.1 785.6 802.9 836.5 15505.8 15561.5 15575.2 15584.4 15638.0 14301.2 14368.7 14374.2 14376.0 14415.2 666.7 820.4 822.0 835.7 839.8 835.9 846.3 847.0 848.0 848.6 859.2 866.1 900.6 901.7 901.9 863.6 868.8 873.5 890.9 893.9 646.6 649.5 649.8 660.8 664.1 14213.2 14360.1 14572.1 14715.6 14745.0 14193.7 14210.1 14362.4 14408.2 14573.6 11498.3 11550.3 11639.5 11704.8 11750.4 3211.1 3621.8 3762.7 3867.9 3940.4 68458.0 98924.0 100980.0 118760.6 124160.0 10509.1 10708.0 11047.8 11109.7 11249.6 20933.9 20976.0 21183.0 21184.4 21271.3 6853.9 7783.1 7803.1 8013.9 8091.9 4296.2 4357.0 4362.1 4383.0 4402.0 3421.0 3496.0 3535.0 3551.7 3551.7 633.2 640.9 645.2 658.9 661.9 553.3 581.7 620.8 621.3 626.4 8619.6 8916.3 9073.7 9131.6 9220.8 541.5 565.2 577.2 591.4 612.7 4222.2 4245.9 4318.5 4336.6 4485.4 93409.0 94490.5 136443.0 167896.0 176045.0 59431.0 100032.5 106934.0 141575.0 147751.0 103208.5 103333.0 103643.5 105127.9 105686.0 9491.7 9742.9 9844.8 9978.4 10070.6 46449.3 58487.7 59551.8 60366.7 60935.4 75413.0 87630.6 91349.5 91755.6 94073.5 12444.4 12771.6 12877.1 13262.8 13525.0 14448.1 14515.3 14638.9 14820.4 14918.4 11197.7 13585.0 13603.3 13672.7 13735.1 13429.3 13905.5 14021.5 14113.8 14121.8 12991.9 13060.2 13441.2 13441.5 13830.2 11498.9 11526.7 11606.3 11616.7 11857.8 10967.8 11276.5 11414.3 11516.9 11517.0 08:31:52 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:31:52 INFO - PROCESS | 1656 | 08:31:52 INFO - TEST-INFO | 1656: exit 0 08:31:52 INFO - TEST-OK | dromaeo_css | took 1114955ms 08:31:52 INFO - TEST-START | kraken 08:31:52 INFO - operating with platform_type : mac_ 08:31:52 INFO - Initialising browser for kraken test... 08:31:52 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpn6DNvW/profile http://localhost:49283/getInfo.html) 08:31:55 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 08:31:55 INFO - PROCESS | 1666 | colorDepth:24 08:31:55 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 08:31:55 INFO - PROCESS | 1666 | __metrics 08:31:55 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 08:31:56 INFO - TEST-INFO | 1666: exit 0 08:31:56 INFO - Browser initialized. 08:31:56 INFO - Running cycle 1/1 for kraken test... 08:31:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpn6DNvW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:08 INFO - PROCESS | 1668 | RSS: Main: 243654656 08:32:08 INFO - PROCESS | 1668 | 08:32:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 08:32:12 INFO - PROCESS | 1668 | RSS: Main: 256757760 08:32:12 INFO - PROCESS | 1668 | 08:32:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 08:32:15 INFO - PROCESS | 1668 | RSS: Main: 265379840 08:32:15 INFO - PROCESS | 1668 | 08:32:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 08:32:19 INFO - PROCESS | 1668 | RSS: Main: 262135808 08:32:19 INFO - PROCESS | 1668 | 08:32:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 08:32:22 INFO - PROCESS | 1668 | RSS: Main: 263438336 08:32:22 INFO - PROCESS | 1668 | 08:32:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:32:24 INFO - PROCESS | 1668 | RSS: Main: 263806976 08:32:24 INFO - PROCESS | 1668 | 08:32:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 08:32:28 INFO - PROCESS | 1668 | RSS: Main: 264097792 08:32:28 INFO - PROCESS | 1668 | 08:32:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 08:32:31 INFO - PROCESS | 1668 | RSS: Main: 264327168 08:32:31 INFO - PROCESS | 1668 | 08:32:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 08:32:35 INFO - PROCESS | 1668 | RSS: Main: 266055680 08:32:35 INFO - PROCESS | 1668 | 08:32:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:32:37 INFO - PROCESS | 1668 | RSS: Main: 265412608 08:32:37 INFO - PROCESS | 1668 | 08:32:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:32:39 INFO - PROCESS | 1668 | RSS: Main: 265457664 08:32:39 INFO - PROCESS | 1668 | 08:32:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:32:42 INFO - PROCESS | 1668 | RSS: Main: 265715712 08:32:42 INFO - PROCESS | 1668 | 08:32:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:32:46 INFO - PROCESS | 1668 | RSS: Main: 266235904 08:32:46 INFO - PROCESS | 1668 | 08:32:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:32:50 INFO - PROCESS | 1668 | RSS: Main: 266129408 08:32:50 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 08:32:52 INFO - PROCESS | 1668 | RSS: Main: 267898880 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | __start_tp_report 08:32:52 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 08:32:52 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 08:32:52 INFO - PROCESS | 1668 | |i|pagename|runs| 08:32:52 INFO - PROCESS | 1668 | |0;ai-astar;107;117;117;115;157;119;118;121;172;132 08:32:52 INFO - PROCESS | 1668 | |1;audio-beat-detection;143;110;143;162;97;123;163;96;147;133 08:32:52 INFO - PROCESS | 1668 | |2;audio-dft;195;204;228;202;201;199;248;195;202;273 08:32:52 INFO - PROCESS | 1668 | |3;audio-fft;70;84;80;178;87;83;66;59;129;61 08:32:52 INFO - PROCESS | 1668 | |4;audio-oscillator;70;70;128;70;68;78;96;89;60;93 08:32:52 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;93;88;89;88;104;86;95;78;134;131 08:32:52 INFO - PROCESS | 1668 | |6;imaging-darkroom;100;102;101;101;105;101;101;101;101;101 08:32:52 INFO - PROCESS | 1668 | |7;imaging-desaturate;146;139;90;91;90;91;92;90;91;93 08:32:52 INFO - PROCESS | 1668 | |8;json-parse-financial;56;58;59;58;59;58;58;60;58;59 08:32:52 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;68;71;69;77;71;76;74;70;69;72 08:32:52 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;89;90;110;109;85;85;89;88;109;111 08:32:52 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;141;146;161;164;158;159;134;156;198;170 08:32:52 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;171;171;232;186;164;213;243;184;201;243 08:32:52 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;67;69;71;70;69;67;73;82;90;78 08:32:52 INFO - PROCESS | 1668 | __end_tp_report 08:32:52 INFO - PROCESS | 1668 | __start_cc_report 08:32:52 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,942 08:32:52 INFO - PROCESS | 1668 | __end_cc_report 08:32:52 INFO - PROCESS | 1668 | __startTimestamp1473175972438__endTimestamp 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | ------- Summary: start ------- 08:32:52 INFO - PROCESS | 1668 | Number of tests: 14 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:127.50 Median:120.00 stddev:20.74 (17.3%) stddev-sans-first:20.63 08:32:52 INFO - PROCESS | 1668 | Values: 107.0 117.0 117.0 115.0 157.0 119.0 118.0 121.0 172.0 132.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:154.50 stddev:24.53 (15.9%) stddev-sans-first:25.67 08:32:52 INFO - PROCESS | 1668 | Values: 143.0 110.0 143.0 162.0 97.0 123.0 163.0 96.0 147.0 133.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:214.70 Median:203.00 stddev:26.52 (13.1%) stddev-sans-first:27.16 08:32:52 INFO - PROCESS | 1668 | Values: 195.0 204.0 228.0 202.0 201.0 199.0 248.0 195.0 202.0 273.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:89.70 Median:75.00 stddev:36.87 (49.2%) stddev-sans-first:38.41 08:32:52 INFO - PROCESS | 1668 | Values: 70.0 84.0 80.0 178.0 87.0 83.0 66.0 59.0 129.0 61.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:74.00 stddev:20.03 (27.1%) stddev-sans-first:20.75 08:32:52 INFO - PROCESS | 1668 | Values: 70.0 70.0 128.0 70.0 68.0 78.0 96.0 89.0 60.0 93.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:88.00 stddev:19.08 (21.7%) stddev-sans-first:20.13 08:32:52 INFO - PROCESS | 1668 | Values: 93.0 88.0 89.0 88.0 104.0 86.0 95.0 78.0 134.0 131.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:101.00 stddev:1.35 (1.3%) stddev-sans-first:1.33 08:32:52 INFO - PROCESS | 1668 | Values: 100.0 102.0 101.0 101.0 105.0 101.0 101.0 101.0 101.0 101.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:91.00 stddev:21.80 (24.0%) stddev-sans-first:16.03 08:32:52 INFO - PROCESS | 1668 | Values: 146.0 139.0 90.0 91.0 90.0 91.0 92.0 90.0 91.0 93.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.50 stddev:1.06 (1.8%) stddev-sans-first:0.73 08:32:52 INFO - PROCESS | 1668 | Values: 56.0 58.0 59.0 58.0 59.0 58.0 58.0 60.0 58.0 59.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:3.06 (4.3%) stddev-sans-first:2.93 08:32:52 INFO - PROCESS | 1668 | Values: 68.0 71.0 69.0 77.0 71.0 76.0 74.0 70.0 69.0 72.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:86.50 stddev:11.53 (13.3%) stddev-sans-first:11.91 08:32:52 INFO - PROCESS | 1668 | Values: 89.0 90.0 110.0 109.0 85.0 85.0 89.0 88.0 109.0 111.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:160.00 stddev:17.63 (11.0%) stddev-sans-first:17.50 08:32:52 INFO - PROCESS | 1668 | Values: 141.0 146.0 161.0 164.0 158.0 159.0 134.0 156.0 198.0 170.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.80 Median:207.00 stddev:30.40 (14.7%) stddev-sans-first:30.27 08:32:52 INFO - PROCESS | 1668 | Values: 171.0 171.0 232.0 186.0 164.0 213.0 243.0 184.0 201.0 243.0 08:32:52 INFO - PROCESS | 1668 | 08:32:52 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:72.00 stddev:7.52 (10.4%) stddev-sans-first:7.58 08:32:52 INFO - PROCESS | 1668 | Values: 67.0 69.0 71.0 70.0 69.0 67.0 73.0 82.0 90.0 78.0 08:32:52 INFO - PROCESS | 1668 | -------- Summary: end -------- 08:32:52 INFO - PROCESS | 1668 | 08:32:53 INFO - TEST-INFO | 1668: exit 0 08:32:53 INFO - TEST-OK | kraken | took 60451ms 08:32:53 INFO - SUITE-END | took 1175s 08:32:53 INFO - Completed test suite (00:19:35) 08:32:53 INFO - javascript benchmark 08:32:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7237.354409804242, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3099.53506973954, 3364.855948977347, 3406.4719968404897, 3407.0630576591434, 3461.567304086989, 5607.7476513556885, 5667.886642267152, 5679.204911312414, 5700.8289751307475, 5773.595848290622, 4603.49361570227, 5688.374278829326, 5753.712314384275, 5777.62445441046, 5832.154337621053, 4927.482614325498, 5109.131447653889, 5223.503767142115, 5225.660332078422, 5284.44513326101, 4770.189067858455, 5317.175837745136, 5321.3348331458565, 5372.301600791891, 5376.247325374451, 5165.74171291434, 5176.637635365526, 5204.34945631796, 5250.107481728115, 5287.86780330491, 5098.056859481007, 5232.502912223333, 5239.895202095975, 5255.448177941299, 5281.36623605166, 3889.86385476507, 5184.274201611777, 5272.525472707474, 5273.736313184326, 5278.894422111574, 4950.059488697136, 5247.47525247476, 5828.3297420796725, 5861.677607731573, 5869.383714709979, 6188.195534580521, 6209.192804935375, 6234.940060189779, 6243.594166379171, 6254.0931564923285, 6261.217347831521, 6267.310595834455, 6269.185005949243, 6271.655058971754, 6272.937270627281, 5781.392953739881, 5868.4424979626865, 5922.7927022554, 5947.702614869239, 5969.8955693196895, 5331.733413329395, 5336.359636843604, 6203.883301005772, 6296.527760417987, 6302.243730752248, 6141.662208578571, 6175.2589689237575, 6184.783532576338, 6188.44304012641, 6190.350013248634, 5127.769335359487, 5596.496315331558, 6107.826086956522, 6155.784547540815, 6205.503559715212, 6212.347703491068, 6228.0, 6230.626162430178, 6240.500759939194, 6243.375662433811, 6143.139960405547, 6150.446459818638, 6204.255489341218, 6228.190335256479, 6239.719212635442, 4846.757662116951, 4888.511148885154, 4923.48303428142, 4945.431275403303, 4968.080905032581, 4417.491988421372, 4435.866923992286, 4442.822287108479, 4450.109978004412, 4485.0, 5761.703616686212, 5877.471027607536, 5893.882122357614, 5907.49786268172, 5926.170336152942, 6098.54260930432, 6159.969200153971, 6172.4136207060255, 6195.101710251988, 6229.657368844757, 5940.881182376328, 5953.910691339634, 5961.2846458425265, 5962.284525856839, 6001.5198784097165, 5443.809466668727, 5450.1007333789485, 5452.890942181134, 5458.645188062843, 5468.972655136698, 6180.134781130645, 6245.0, 6259.624422534663, 6291.496680265476, 6378.968105159444, 6199.752009919553, 6214.689265536796, 6217.626942383471, 6221.2845522763655, 6247.843803904939, 6335.619862808247, 6364.07720880479, 6366.172397588469, 6383.39357761012, 6395.840103997437, 6085.056816193497, 6129.20597562684, 6132.508481341157, 6146.0, 6149.692515374124, 6220.564560480904, 6221.471174949999, 6227.875442491034, 6227.875442491216, 6242.968785156045, 5141.691498510101, 5996.4603185713495, 6184.010558310649, 6185.752569897334, 6187.783427580013, 5948.464638182584, 6116.66358350295, 6117.279373342116, 6137.41694539018, 6164.506839452923, 5856.209411729362, 6101.176341193882, 6106.3283038866575, 6116.480099191618, 6119.112728654231, 5445.829146733471, 6130.969345153246, 6148.907766383419, 6177.722002509988, 6197.101420294122, 5813.3895940925595, 6006.579539432198, 6053.848653783691, 6056.0, 6088.178095956989], "value": 5679.912529088197, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.9491551635545, 468.4542507978205, 471.8065593106825, 493.0385747791807, 564.9576281778868, 38690.806545967265, 41433.79283103584, 41497.5475858345, 41504.75485735425, 41726.99999999993, 37651.0, 42297.78851105748, 42371.3644295335, 42464.36303455451, 42611.3608295875, 9610.471424071657, 9781.853272200922, 9799.265055120859, 9802.509874506282, 9819.607215711363, 9517.904820951806, 9602.039796020377, 9619.989901060393, 9628.277879159092, 9628.759281017961, 9591.66429174979, 9631.614735410576, 9638.228941684683, 9653.806923861519, 9658.565364558577, 9504.667336643219, 9557.952210238975, 9562.426254424721, 9577.425354478753, 9583.712488625353, 7875.9367485389885, 9322.440653560809, 9557.761055973588, 9675.854862177073, 9690.612375504972, 39830.40254396186, 40054.0, 40078.0, 40173.79913100445, 40454.190916181804, 3298.7855789373853, 3307.338532293527, 3357.345317663057, 3478.269563391691, 3616.529851119364, 30412.527545777073, 30439.086827395215, 30728.2317942051, 30910.22724431885, 30971.845140774152, 3213.4376484115187, 3230.983845080783, 3239.3521295740948, 3264.135004223883, 3358.2947581007775, 485198.5740071277, 486765.0, 492455.0, 492816.0, 493092.0, 60353.0, 60925.69537152286, 60934.695326523084, 60936.39063609307, 62431.0, 34312.65687343144, 35424.0, 35498.935031949084, 63401.0, 63762.0, 16047.518574442785, 16204.83795162057, 16210.43263485796, 16223.26995285215, 16261.59346016359, 9728.854067188999, 10617.628383006559, 12435.75128497443, 12467.750644987049, 12491.437885295183, 17741.37905173313, 18427.447176584723, 18593.628127437376, 18607.0, 18641.627167456845, 6531.3468653134305, 6806.7957961261245, 7625.885611715828, 7647.082350118021, 7691.50005249657, 842.6123982967781, 927.0173615967096, 988.5057471264367, 999.4602914426274, 1105.1159072741775, 25450.490990180464, 30078.398432031237, 30763.846180768953, 30822.53766193509, 30877.228069298, 746.813296675831, 879.4547380623926, 975.9658411955689, 1025.2720568396373, 1085.1698450685233, 11837.408129593487, 15419.61450963735, 15498.922505387402, 15628.3748650055, 16054.598635033984, 652725.0, 666885.0, 670239.0, 672470.0, 673995.6300218467, 56313.43686563164, 56442.7177864108, 56743.71628141833, 56937.71531142318, 57173.0, 1227.9631611051682, 1311.4229738914846, 1343.7111021130504, 1354.5665387076042, 1361.5370773936909, 23555.64666529969, 27759.4732289726, 29324.706752932198, 29994.95017674371, 30007.0, 49884.75057624834, 49962.0, 50691.98616027732, 50810.23784643159, 50817.0, 622657.0, 630956.0, 631037.0, 631598.0, 635465.0, 26496.86751566307, 26702.866485667448, 26759.06343277976, 26829.792659330767, 26905.730942691105, 45901.0, 47031.294530581385, 47037.294440584104, 47549.0, 47714.0, 38977.22045559016, 39186.0, 39255.01862453461, 39968.80405547377, 40313.0, 38348.0, 38591.228175436896, 38835.223295534495, 40017.0, 40148.397774034, 37689.0, 38225.426618600184, 38551.0, 38702.80648596739, 38848.41727374035, 31584.052478425685, 34361.31277374489, 34608.13479662928, 34616.30767384689, 35039.29921401507, 23620.763792361857, 25556.872215638803, 25791.613125802753, 25935.87032064903, 26180.345491362106], "value": 19663.062522133136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.31708509863216, 363.9581448133464, 372.3223732806292, 385.0489291450117, 397.6321902240427, 14278.857211427883, 17410.955342679415, 19235.51911202219, 19605.313814016496, 20132.396028119143, 11733.823992640117, 11938.76122477552, 12029.398530073506, 12029.699257518567, 12047.397630118481, 4360.909772556861, 5097.770600322976, 5204.947950520503, 5315.149576067811, 5345.732713364336, 3822.311983842907, 4575.313702944552, 5367.953249116424, 5373.408925018245, 5702.287214098238, 5921.851953701149, 6013.128096426016, 6032.607880487754, 6034.607750496226, 6042.57701960863, 5793.188953546507, 5793.6234144780665, 5807.157962095473, 5893.469587737124, 6020.969895150518, 5653.717314134276, 5701.657900525982, 5704.258446401983, 5712.714364281811, 5716.256886604757, 12732.23606583608, 13035.217886926815, 13047.608571742863, 13355.666108347272, 13378.598642040753, 2496.0639760089966, 2630.6843178818663, 2702.4324891772753, 2808.8033837631374, 2926.6049083373687, 9877.654282100091, 9954.303198776088, 10009.949950250275, 10347.63783267582, 10484.161267098614, 1829.1037391678115, 1909.2267631609081, 1950.882947023183, 1985.186073709772, 1987.5130593004662, 95627.52186239093, 114066.42966785196, 118928.0, 120999.3950030253, 124169.37915310365, 11020.559177632966, 11197.0, 11497.482613282422, 11565.942170289094, 11610.88389116098, 14314.284285785669, 14453.205073721047, 14482.0, 14504.854951450561, 14510.984231103721, 8320.875186872201, 8595.355348348774, 8692.913070869337, 8704.477731336014, 8708.303335733126, 8450.492970421794, 8485.278751306085, 8570.528620925785, 8609.784755381166, 8661.870071948926, 3655.5430571178604, 3854.71089668276, 4537.0, 4563.201439748097, 4625.421822272216, 1917.3193516301792, 1945.6692362298443, 1971.4282857971027, 3831.9845241011153, 3903.7312873316287, 796.6932730898529, 818.9549574773445, 853.9786505337293, 901.7610333261691, 910.4036855859423, 19440.805591944183, 20347.593048138955, 20971.37085887426, 21286.10419603469, 21398.358049258546, 894.579547612621, 914.9679761208325, 930.2418528898926, 942.2744486745167, 963.7881062960522, 9711.980242074622, 9787.874394444587, 9973.850392244121, 10540.367577945195, 10573.942605739518, 505442.0, 507618.0, 510824.0, 537321.0, 577202.0, 124145.75854241522, 126800.0, 127495.0, 130173.69826301615, 130916.0, 936.7611259129014, 962.4658314635444, 976.7509285132246, 978.9510524473748, 980.9264305176997, 18676.719849202, 18762.812371876105, 18939.90530047341, 18988.0, 19085.71371429402, 41778.74663760092, 55595.0, 56053.31173668088, 56837.43162568487, 57377.42622573721, 78604.0, 78640.0, 79181.8122728148, 79809.20190798018, 80024.79962800679, 5380.3274590676165, 5476.808311709071, 7145.82135446618, 7443.851122977619, 7476.102867655699, 9160.0, 10603.0, 11134.610288639858, 12207.75584488323, 13009.804852927024, 10998.175136864607, 11384.544618215179, 11458.026067784333, 11625.069994400259, 11626.128040396836, 5223.686578805284, 8267.917320826715, 11250.381229032482, 11270.478485404365, 11418.942905285421, 11234.3259404436, 11527.250728702607, 11782.0, 11798.11514136426, 11854.288742675468, 7385.9261407387385, 7476.962615186889, 7567.9243207567215, 7569.848603028019, 7614.619269036415, 9145.359824812465, 10306.020928011827, 11386.658400248005, 11650.6504804856, 11732.94133529361], "value": 9075.26136697792, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.9100653960763, 1526.6565022869854, 1699.1589163364138, 1838.2738818166815, 1870.4201697473775, 7852.743561030236, 7968.482048666832, 8477.78805529862, 8980.191782739552, 8987.37088403812, 8055.6777728890775, 8871.778205544864, 8875.112488751121, 8879.55602219888, 8889.0, 2251.9324420267417, 2279.0541925101065, 2300.3559003479054, 2301.80434663053, 2307.111761971636, 2293.9655905161435, 2370.7510711375317, 2408.0127147869375, 2421.2131057406327, 2422.6729391532094, 2393.102586530051, 2401.9639705404434, 2403.170906037423, 2409.903603855844, 2413.698287714036, 2370.2296753555165, 2370.6444033394955, 2385.5467461182407, 2398.364433425126, 2407.7592240775784, 2367.846090004144, 2370.7747763962398, 2374.3233178544033, 2384.7972922301624, 2387.331547166796, 8774.780630484225, 8784.077671844492, 8800.163984421533, 8807.0, 8861.0, 4755.453122890877, 5164.509371609729, 5242.7640756166065, 5363.9463605363835, 5372.462753724635, 3597.94603080954, 3613.5483064616924, 3646.908827279313, 3684.281565094808, 3691.2986532559, 1079.3038490173794, 1086.666072860069, 1095.7041598768367, 1110.1285490889613, 1112.7496313329516, 11314.717132071682, 11372.431378431045, 11418.371989540572, 11442.656720298404, 11472.770544589144, 4530.818767249339, 4604.470485894098, 4801.68789028712, 4823.469418363977, 4849.151398505247, 9647.324687271823, 9649.179819715262, 9832.655857045112, 9851.211903047739, 9900.108990190918, 5271.789128434896, 5307.6019298552765, 5322.813701520428, 5323.680579165262, 5333.546648534918, 3719.925601487955, 4074.6332830044844, 4388.2759344707765, 4670.159371313196, 4881.29221262922, 3899.9610003900166, 3991.1419044905483, 4085.571015043437, 4107.774072426046, 4149.585041495887, 1096.961606343774, 1312.9737405252033, 1318.7494376068516, 1328.7608230518406, 1329.2024785129038, 742.59157463396, 845.5729856422467, 871.9651213951371, 887.2813021452515, 891.3493150000406, 5065.645404821627, 5706.771729130871, 5714.457126572969, 5966.955782738003, 6093.75624974996, 431.3917376499184, 435.4948260018427, 437.92336341140174, 439.24230702039364, 444.64650602770877, 3399.6430374810784, 3949.229900169497, 3986.3223252047223, 4036.414719865603, 4037.8384864605673, 10265.74335641615, 10661.413622250695, 10699.0, 10719.356838589709, 10734.0, 10105.29262951601, 10592.523336449878, 10621.5220315086, 10693.679189624323, 10849.403282819521, 8010.519368837889, 8225.917740822515, 8229.547374894439, 8232.958835205905, 8236.917630823735, 5929.495992840484, 5997.490213331741, 6064.636121832704, 6081.847953801191, 6111.449969502766, 9611.327207095435, 10134.949325253327, 10206.489675516343, 10210.387376757419, 10218.846717299099, 9978.251631127549, 10129.088382045651, 10312.793744125225, 10350.689479315633, 10418.426986515511, 6940.0, 7311.488195826241, 7429.851402972018, 7519.73680921187, 7626.466147369631, 8355.415120941476, 8442.577871106543, 8452.408331416742, 8455.32357411418, 8458.830823383374, 7311.853762924606, 7453.81365465846, 7473.6263186841525, 7539.547627142389, 7549.2073332299315, 7301.379382752525, 7328.487005909536, 7382.040334756447, 7470.887936680845, 7510.098788145458, 6799.524033317621, 6948.444124470131, 6951.235364110045, 6962.477814164059, 6970.895436568354, 4858.295547145714, 4929.926051109164, 5031.195008798574, 5080.7205603692155, 5090.490950905028, 4898.92651610219, 5005.199168133081, 5049.444561098349, 5066.189409694432, 5228.921566176435], "value": 4489.0254247218845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.1163980022131, 307.9045495896271, 311.7615024506253, 315.76063949000167, 318.56674922105947, 4684.812607495696, 5571.944280557193, 5733.104782085046, 5902.114682797577, 5957.165996760457, 4894.8286809961655, 5044.319016932713, 5419.729013549317, 5545.639533430324, 5716.8284951451415, 4316.589923957219, 4362.280223763075, 4382.737035777847, 4402.625776808975, 4419.73481591104, 3661.041342881559, 4132.607402296777, 4291.206126866524, 4341.2836881914445, 4455.487618923817, 3794.7477332480353, 3838.750481218726, 4341.565843415665, 4342.196693611676, 4343.3267843484155, 3388.847501862422, 3707.6663100321102, 4230.851920182794, 4359.978200109011, 4386.429764130643, 2939.808912420686, 3095.5666206731075, 3789.677877380426, 3791.6966642668795, 3804.6005169457085, 4687.273472611717, 5436.836894893159, 5594.188842617838, 5605.243292155547, 5646.322441307069, 4914.385701787277, 5030.748462576857, 5040.722760248185, 5048.7728052237735, 5114.744262786883, 5344.327836081959, 6071.964017991005, 6123.295820980555, 6166.907496387557, 6190.257169139687, 3332.450145725952, 3729.4219395993664, 4003.679705623543, 4029.959700402988, 4042.6765858731233, 5626.774929002835, 5726.427357264281, 6353.523485738542, 6371.745130194833, 6476.676166191719, 3684.7604905681046, 3925.4896863407575, 4007.0, 4077.327241005201, 4091.85678501251, 3409.3351796399465, 3615.1323682316056, 4099.692523060723, 4112.280350938574, 4121.9587804122175, 3786.9431958520645, 4115.92985823688, 4881.707097574086, 4889.535494128052, 4905.950940490549, 5833.766649334064, 5958.225430694069, 6153.092786080871, 6161.691915404212, 6176.752929882844, 1396.4220430913003, 1396.5880065380736, 1414.6631433295643, 1415.8177921435718, 1416.3838730152418, 824.7773101262626, 825.1830687619214, 848.4654667559521, 859.1537335724306, 861.3711990247155, 3971.9801400993388, 4472.888177795581, 4820.831270905502, 4880.829170978998, 4899.256416406753, 4714.0, 5293.761780719877, 5336.199570064521, 5359.812406565751, 5364.946350536445, 4218.725782824106, 4222.796502996631, 4527.0, 4692.929606055912, 4784.617230621542, 2505.8120640952, 2526.080179063925, 2673.2782148820097, 2676.69218039924, 2791.3579876628082, 4316.740995540277, 4613.716210475769, 5461.290032295856, 5492.752826122754, 5493.038718224295, 4828.903421931612, 4896.436909755353, 5108.438071812097, 5458.945410545924, 5466.671999680032, 5114.0, 5121.950000249865, 5151.4333423323405, 5475.835724928259, 5480.91778623321, 4301.784910754387, 4889.168841296917, 4920.581750551243, 5326.280952071421, 5351.565037907665, 4474.507804141607, 4612.947238478845, 4630.513796051501, 5162.406323272721, 5260.316158899319, 5492.258545096521, 5496.80761173357, 5502.367227768697, 5538.667679939216, 5541.4483944495605, 3338.983305083459, 3570.535830342039, 3649.5073165122367, 3970.583088775637, 4035.4866924903163, 5326.36083669962, 5772.0, 6215.720292586754, 6286.768197289896, 6323.683815809283, 5340.279062326536, 6087.930103764564, 6089.238845144357, 6123.907426749463, 6139.754409823557, 5727.4845263926445, 6108.43445752776, 6126.724297406538, 6187.917114505034, 6199.752009919733, 5569.303837020372, 5839.795607153729, 6077.726502307495, 6082.574219804571, 6124.234470691164, 5405.8918821622565, 5756.366799652119, 6069.939300607114, 6160.260768707783, 6222.097795819672, 4921.0, 5969.313528944227, 6007.438066102757, 6027.4739277521385, 6030.728617212324], "value": 4133.936915016078, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.4783781758226, 767.1446337333871, 785.6268272570534, 802.8916096326996, 836.544412721864, 15505.767413488808, 15561.533154005383, 15575.221238938051, 15584.376624934988, 15638.0, 14301.213433261166, 14368.71262574748, 14374.20941848198, 14376.0, 14415.207163605997, 666.6866572710818, 820.4011071917504, 821.954792486413, 835.6991483066091, 839.8026463781007, 835.9164083591622, 846.255295340103, 846.9745907622781, 847.9703210387638, 848.6435697007272, 859.2181115185183, 866.1079088538784, 900.5587262241487, 901.6753968571309, 901.8692289618001, 863.6329559937033, 868.830578037283, 873.5457562067753, 890.9109089091104, 893.8871105473694, 646.6411141816291, 649.486905344777, 649.7985624456386, 660.8017594721564, 664.0570390046146, 14213.218272994982, 14360.066595671353, 14572.05281656689, 14715.55853324402, 14745.041572297767, 14193.716125677533, 14210.147391156564, 14362.425502979868, 14408.207548584824, 14573.6356591085, 11498.310101393943, 11550.306981581132, 11639.534418623245, 11704.765904681859, 11750.353730544817, 3211.0527394418705, 3621.7826930384263, 3762.7177961652988, 3867.903302417434, 3940.4286378474962, 68458.0, 98924.0, 100980.0, 118760.61670278231, 124159.9999999991, 10509.054185123376, 10708.0, 11047.779044419067, 11109.666709998552, 11249.550017999352, 20933.89533052325, 20976.0, 21183.0, 21184.364469065953, 21271.255506057212, 6853.897191542131, 7783.104942931635, 7803.141654418009, 8013.879791803128, 8091.878621820678, 4296.205202037633, 4357.041450880801, 4362.1275744851155, 4383.0, 4402.0, 3420.9828950855085, 3495.9861640124586, 3534.9823250884097, 3551.662592053751, 3551.7158627309755, 633.1673848888696, 640.8782331357028, 645.1741770533724, 658.9095047696071, 661.8874791285493, 553.3194171169484, 581.7149596697673, 620.8416853702367, 621.3103455164763, 626.3661304215761, 8619.569021549023, 8916.286697064219, 9073.682421115229, 9131.634734610541, 9220.815583688289, 541.5153437673339, 565.2369301443019, 577.1631134854478, 591.3849596419761, 612.6844674992383, 4222.19778242133, 4245.908008647254, 4318.52496225415, 4336.6313863321975, 4485.372047913294, 93409.0, 94490.52754736182, 136443.0, 167896.0, 176045.0, 59431.0, 100032.4998375018, 106934.0, 141575.0, 147751.0, 103208.48395758124, 103333.0, 103643.4817825921, 105127.94872051335, 105686.0, 9491.66779162726, 9742.9025709742, 9844.803103937738, 9978.401295922267, 10070.597176112873, 46449.30326045045, 58487.70756146192, 59551.80896382135, 60366.69816650889, 60935.39064609297, 75413.0, 87630.56184719036, 91349.54325228331, 91755.62366564374, 94073.5296323514, 12444.377781111089, 12771.552995645108, 12877.098603097693, 13262.801057983945, 13524.98562607828, 14448.060876043024, 14515.346809393814, 14638.853611463748, 14820.407183712961, 14918.403263869324, 11197.72005699864, 13584.981126415756, 13603.251821149535, 13672.65818354549, 13735.107218030797, 13429.26139062361, 13905.51330703461, 14021.5092471763, 14113.788293175405, 14121.788173177205, 12991.935040324737, 13060.151090179108, 13441.193528388329, 13441.476300319731, 13830.239336836168, 11498.942505287754, 11526.654200374001, 11606.30362178272, 11616.709582260512, 11857.762844743229, 10967.783562718085, 11276.492557834754, 11414.315141091562, 11516.884831151581, 11517.0], "value": 7639.522000550195, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 115.0, 157.0, 119.0, 118.0, 121.0, 172.0, 132.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 143.0, 162.0, 97.0, 123.0, 163.0, 96.0, 147.0, 133.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 228.0, 202.0, 201.0, 199.0, 248.0, 195.0, 202.0, 273.0], "value": 214.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 84.0, 80.0, 178.0, 87.0, 83.0, 66.0, 59.0, 129.0, 61.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 128.0, 70.0, 68.0, 78.0, 96.0, 89.0, 60.0, 93.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 89.0, 88.0, 104.0, 86.0, 95.0, 78.0, 134.0, 131.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 101.0, 101.0, 105.0, 101.0, 101.0, 101.0, 101.0, 101.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 90.0, 91.0, 90.0, 91.0, 92.0, 90.0, 91.0, 93.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 59.0, 58.0, 59.0, 58.0, 58.0, 60.0, 58.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 69.0, 77.0, 71.0, 76.0, 74.0, 70.0, 69.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 110.0, 109.0, 85.0, 85.0, 89.0, 88.0, 109.0, 111.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 161.0, 164.0, 158.0, 159.0, 134.0, 156.0, 198.0, 170.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 232.0, 186.0, 164.0, 213.0, 243.0, 184.0, 201.0, 243.0], "value": 200.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 71.0, 70.0, 69.0, 67.0, 73.0, 82.0, 90.0, 78.0], "value": 73.6, "unit": "score"}]}]} 08:32:53 INFO - Return code: 0 08:32:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:32:53 ERROR - # TBPL SUCCESS # 08:32:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:32:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:32:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:32:53 INFO - 2016-09-06 08:32:53.770 screenresolution[1673:10763] starting screenresolution argv=screenresolution get 08:32:53 INFO - 2016-09-06 08:32:53.773 screenresolution[1673:10763] Display 0: 1600x1200x32@60 08:32:53 INFO - 2016-09-06 08:32:53.779 screenresolution[1674:10766] starting screenresolution argv=screenresolution list 08:32:53 INFO - Available Modes on Display 0 08:32:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:32:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:32:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:32:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:32:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:32:54 INFO - Intel Iris: 08:32:54 INFO - Chipset Model: Intel Iris 08:32:54 INFO - Type: GPU 08:32:54 INFO - Bus: Built-In 08:32:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:32:54 INFO - Vendor: Intel (0x8086) 08:32:54 INFO - Device ID: 0x0a2e 08:32:54 INFO - Revision ID: 0x0009 08:32:54 INFO - Displays: 08:32:54 INFO - Display: 08:32:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:32:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:32:54 INFO - Display Serial Number: 1600x1200 60 08:32:54 INFO - Main Display: Yes 08:32:54 INFO - Mirror: Off 08:32:54 INFO - Online: Yes 08:32:54 INFO - Rotation: Supported 08:32:54 INFO - Return code: 0 08:32:54 INFO - Running post-action listener: _resource_record_post_action 08:32:54 INFO - [mozharness: 2016-09-06 15:32:54.013984Z] Finished run-tests step (success) 08:32:54 INFO - Running post-run listener: _resource_record_post_run 08:32:54 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38331392; Write bytes: 707765248; Read time: 437; Write time: 2010 08:32:54 INFO - TinderboxPrint: CPU usage
54.6% 08:32:54 INFO - TinderboxPrint: I/O read bytes / time
38,331,392 / 437 08:32:54 INFO - TinderboxPrint: I/O write bytes / time
707,765,248 / 2,010 08:32:54 INFO - TinderboxPrint: CPU idle
2,199.7 (45.4%) 08:32:54 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 08:32:54 INFO - TinderboxPrint: CPU user
1,666.3 (34.4%) 08:32:54 INFO - TinderboxPrint: Swap in / out
443,891,712 / 0 08:32:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391045632; Write bytes: 381001216; Read time: 29239; Write time: 738 08:32:54 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 32087040; Write bytes: 298395136; Read time: 355; Write time: 1218 08:32:54 INFO - Running post-run listener: _upload_blobber_files 08:32:54 INFO - Blob upload gear active. 08:32:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:32:54 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 08:32:54 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:32:54 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:32:54 INFO - (blobuploader) - INFO - Open directory for files ... 08:32:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:32:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:32:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:56 INFO - (blobuploader) - INFO - Done attempting. 08:32:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:32:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:32:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:57 INFO - (blobuploader) - INFO - Done attempting. 08:32:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:32:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:32:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:59 INFO - (blobuploader) - INFO - Done attempting. 08:32:59 INFO - (blobuploader) - INFO - Iteration through files over. 08:32:59 INFO - Return code: 0 08:32:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:32:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:32:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1caa3b2fb34da94a507afe6c88f62d0966bab49e0647380ae800f206162f22b7b54e76d61dc434974d2ea867e171cdde632d50bde1b068a48ac8bd9118f9f725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88d20da2123b6b8b604cc463bae3bb8740617178554f8d4df012cf1f9b10696137f6445ce48ad4f4d519366cb1e6fe3cab5c59ff845aa1679d755a67fbd6d2f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b7859d7f8590811468c9e8a5c86208d8139046f605c1d3b7688ca09b6b3c7cba0d876e599754b8fce83c3ccdbf52c10d9a5d0428d770e211cd1a01bac7f8b4"} 08:32:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:32:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:32:59 INFO - Contents: 08:32:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1caa3b2fb34da94a507afe6c88f62d0966bab49e0647380ae800f206162f22b7b54e76d61dc434974d2ea867e171cdde632d50bde1b068a48ac8bd9118f9f725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88d20da2123b6b8b604cc463bae3bb8740617178554f8d4df012cf1f9b10696137f6445ce48ad4f4d519366cb1e6fe3cab5c59ff845aa1679d755a67fbd6d2f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b7859d7f8590811468c9e8a5c86208d8139046f605c1d3b7688ca09b6b3c7cba0d876e599754b8fce83c3ccdbf52c10d9a5d0428d770e211cd1a01bac7f8b4"} 08:32:59 INFO - Running post-run listener: copy_logs_to_upload_dir 08:32:59 INFO - Copying logs to upload dir... 08:32:59 INFO - mkdir: /builds/slave/test/build/upload/logs 08:32:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.986767 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-06 08:32:59.605457) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 08:32:59.608768) ========= 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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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/Autoland/sha512/1caa3b2fb34da94a507afe6c88f62d0966bab49e0647380ae800f206162f22b7b54e76d61dc434974d2ea867e171cdde632d50bde1b068a48ac8bd9118f9f725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88d20da2123b6b8b604cc463bae3bb8740617178554f8d4df012cf1f9b10696137f6445ce48ad4f4d519366cb1e6fe3cab5c59ff845aa1679d755a67fbd6d2f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b7859d7f8590811468c9e8a5c86208d8139046f605c1d3b7688ca09b6b3c7cba0d876e599754b8fce83c3ccdbf52c10d9a5d0428d770e211cd1a01bac7f8b4"} build_url:https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011074 build_url: 'https://queue.taskcluster.net/v1/task/TfjTckm3Ss69j0VQOFIaDg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1caa3b2fb34da94a507afe6c88f62d0966bab49e0647380ae800f206162f22b7b54e76d61dc434974d2ea867e171cdde632d50bde1b068a48ac8bd9118f9f725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88d20da2123b6b8b604cc463bae3bb8740617178554f8d4df012cf1f9b10696137f6445ce48ad4f4d519366cb1e6fe3cab5c59ff845aa1679d755a67fbd6d2f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b7859d7f8590811468c9e8a5c86208d8139046f605c1d3b7688ca09b6b3c7cba0d876e599754b8fce83c3ccdbf52c10d9a5d0428d770e211cd1a01bac7f8b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 08:32:59.640854) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 08:32:59.641142) ========= 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.cQTWKcBnyw/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.NJD6nGn1Ys/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.005290 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 08:32:59.688965) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 08:32:59.689269) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 08:32:59.689582) ========= ========= Total master_lag: 0.22 =========